IssueTracker

As far as we can tell, we are setting the same values, as Estimote / Qorvo don't actually publish any useful information on how to actually use their UWB beacons / DWM3000EVB running in Nearby mode.

The Bluetooth incoming data is in this format:
//        0x01, 0x01, 0x00, 0x00, 0x00, 0x14, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, // Fixed data
//        0x13, // configLen - 15

//        0x01, 0x00, 0x00, 0x00, 0x3f, 0xf5, 0x03, 0x00, 0xb8,  // Fixed data
//        0x0b, 0x00, // Preamble - 25
//        0x00, 0x01, 0x09,   // Fixed Data
//        0x09, 0x00, // Channel - 30
//        0x01,
//        0x7c, 0xd2, // Dest Addr - 33

So we get the Preamble / Channel / Dest Addr from the Bluetooth data.

The outgoing Bluetooth packet data is in this format:
// Android timings
        val packetPayload = byteArrayOf(
            0x01, 0x00, 0x00, 0x00, 0x17, 0x45, 0x55, // Fixed data
            0x11.toByte(), 0x22.toByte(), 0x00, 0x00, // SessionID  - 7
            0x0b, // preamble - 11
            0x09, // channel  - 12
            0x06, 0x00, // num slots - 13
            0x60, 0x09, // slot duration - 15
            0xf0.toByte(), 0x00, // block duration - 17
            0x03,  Fixed Data
            0x11.toByte(), 0x22.toByte(), 0x33.toByte(), 0x44.toByte(), 0x55.toByte(), 0x66.toByte(),  // STS IV - 20
            0x46, 0x59.toByte(), // SRC_ADDR - 26
        )

So we return the session ID and STS IV, although Android also needs the vendor Id which is set to 0x004c.

UWB seems to be the king of proprietary data with no documentation on anything... perhaps if you pay $5000/year to FiRA you get something, but as an application developer just wanting to use UWB ranging this shouldn't be necessary!

Trying with the latest Android 13 August 5th release on the Pixel 6 Pro and AndroidX UWB Alpha3 with Controller support, we do get a different trace.
The ranging result isn't getting back to the App, but doing a full logcat trace show some more responses.
I'm guessing Status 33 in the NearBy level means an error, but at least there is more info now:
NearbyUWB: UWB Ranging Data from peer 0X1B53 Status 33

A more full log:
022-08-25 13:27:15.938 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 1 session_id 3776601084
2022-08-25 13:27:15.938 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.938 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_init: MT/GID/OID 2100 RSP 0
2022-08-25 13:27:15.938 904-904/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 0 (0)
2022-08-25 13:27:15.938 1779-25614/? 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-25 13:27:15.938 1779-25614/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-08-25 13:27:15.938 1779-25734/? 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-25 13:27:15.938 1779-25614/? 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: 3776601084, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands }) }) }) }, uci_notification: UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 3776601084, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands }) }) }, session_notification: SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 3776601084, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands }) }, session_status_ntf: SessionStatusNtfData { session_id: 3776601084, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands } })
2022-08-25 13:27:15.938 1779-25614/? I/uwb: uwb_uci_rust::uci: Session 3776601084 initialized, invoking session init HAL API
2022-08-25 13:27:15.939 1779-25614/? D/NativeUwbManager: onSessionStatusNotificationReceived(3776601084, 0, 0)
2022-08-25 13:27:15.939 1779-25614/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 3776601084, state : INIT reasonCode:0
2022-08-25 13:27:15.939 1779-25734/? D/UwbConfManager: setAppConfigurations for protocol: fira
2022-08-25 13:27:15.939 1779-25734/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeSetAppConfigurations: enter
2022-08-25 13:27:15.939 1779-25614/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciSetAppConfig { session_id: 3776601084, 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, 156, 74, 7, 2, 83, 27, 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, 9, 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-25 13:27:15.939 1779-25614/? 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: 3776601084, 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: [156, 74] }, AppConfigTlv { cfg_id: DstMacAddress, v: [83, 27] }, 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: [9] }, 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: 3776601084, 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: [156, 74] }, AppConfigTlv { cfg_id: DstMacAddress, v: [83, 27] }, 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: [9] }, 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] }, AppConfi
2022-08-25 13:27:15.939 1779-25614/? D/uwb: gTlv { 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] }] }) }) } }
2022-08-25 13:27:15.940 1779-25614/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.UciBackendAndroidCCC: SessionSetAppConfig: MT/GID/OID 2103 CMD
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: MT/GID/OID 2103 CMD
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: session_id=e11a5bfc
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 3776601084
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: mac_state=3
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: uci_state=0
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 12 session_id 3776601084
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 3776601084
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: mac_state=3
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 9 session_id 3776601084
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 5 session_id 3776601084
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: MT/GID/OID 4103 RSP 0
2022-08-25 13:27:15.940 904-904/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 3 (0)
2022-08-25 13:27:15.940 1779-25614/? 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-25 13:27:15.940 1779-25614/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-08-25 13:27:15.940 1779-25614/? 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: 3776601084, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands }) }) }) }, uci_notification: UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 3776601084, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands }) }) }, session_notification: SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 3776601084, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands }) }, session_status_ntf: SessionStatusNtfData { session_id: 3776601084, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands } })
2022-08-25 13:27:15.940 1779-25734/? 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-25 13:27:15.940 1779-25734/? I/UwbConfManager: setAppConfigurations respData: UwbConfigStatusData {  status = 0 length = 0, tlvs = [[]] }
2022-08-25 13:27:15.940 1779-25614/? D/NativeUwbManager: onSessionStatusNotificationReceived(3776601084, 3, 0)
2022-08-25 13:27:15.940 1779-25614/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 3776601084, state : IDLE reasonCode:0
2022-08-25 13:27:15.940 1779-25734/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingOpened
2022-08-25 13:27:15.941 1779-2087/? I/UwbSessionManager: sessionInit() : finish - sessionId : -518366212
2022-08-25 13:27:15.941 2754-24909/? I/NearbyUWB: UWB session opened: -518366212 [CONTEXT service_id=49 ]
2022-08-25 13:27:15.942 2754-25482/? I/NearbyUWB: exec UWB operation Start ranging [CONTEXT service_id=49 ]
2022-08-25 13:27:15.942 1779-1797/? I/UwbSessionManager: startRanging() - Session ID : -518366212
2022-08-25 13:27:15.943 1779-25735/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeRangingStart: enter
2022-08-25 13:27:15.943 1779-25614/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciStartRange(3776601084)
2022-08-25 13:27:15.943 1779-25614/? 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: 3776601084 }) }) }) }, uci_command: UciCommandData { child: RangingCommand(RangingCommandData { child: RangeStartCmd(RangeStartCmdData { session_id: 3776601084 }) }) } }
2022-08-25 13:27:15.943 1779-25614/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-08-25 13:27:15.943 904-904/? I/qorvo.uwb.UciBackendAndroidCCC: RangingStart: MT/GID/OID 2200 CMD
2022-08-25 13:27:15.943 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: MT/GID/OID 2200 CMD
2022-08-25 13:27:15.943 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: session_id=e11a5bfc
2022-08-25 13:27:15.943 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 3776601084
2022-08-25 13:27:15.943 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-25 13:27:15.943 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.943 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.946 1092-1092/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x2c, rc: 0
2022-08-25 13:27:15.947 1092-1092/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cb, tag: 0x2d, rc: 0
2022-08-25 13:27:15.947 1092-1092/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x2e, rc: 0
2022-08-25 13:27:15.958 1092-1092/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x2f, rc: 0
2022-08-25 13:27:15.969 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 2 session_id 3776601084
2022-08-25 13:27:15.970 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-25 13:27:15.970 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: MT/GID/OID 2200 RSP 0
2022-08-25 13:27:15.970 904-904/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 2 (0)
2022-08-25 13:27:15.970 1779-25614/? 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-25 13:27:15.970 1779-25614/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-08-25 13:27:15.971 1779-25735/? 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-25 13:27:15.971 1779-25614/? 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-25 13:27:15.971 1779-25614/? D/NativeUwbManager: onDeviceStatusNotificationReceived(2)
2022-08-25 13:27:15.971 1779-25614/? I/UwbServiceCore: handleDeviceStatusNotification = ACTIVE
2022-08-25 13:27:15.971 1779-25614/? 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: 3776601084, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands }) }) }) }, uci_notification: UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 3776601084, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands }) }) }, session_notification: SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 3776601084, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands }) }, session_status_ntf: SessionStatusNtfData { session_id: 3776601084, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands } })
2022-08-25 13:27:15.972 1779-25614/? D/NativeUwbManager: onSessionStatusNotificationReceived(3776601084, 2, 0)
2022-08-25 13:27:15.972 1779-25614/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 3776601084, state : ACTIVE reasonCode:0
2022-08-25 13:27:15.972 1779-25735/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingStarted
2022-08-25 13:27:15.972 2754-24909/? I/NearbyUWB: UWB ranging started [CONTEXT service_id=49 ]
2022-08-25 13:27:15.972 2754-24909/? I/NearbyUWB: Local UWB address is 0X4A9C [CONTEXT service_id=49 ]
2022-08-25 13:27:15.973 2754-24909/? I/NearbyUWB: Local UWB address is 0X4A9C [CONTEXT service_id=49 ]
2022-08-25 13:27:15.975 25670-25670/com.example.app I/UwbClientSessionScope: Started UWB ranging.
2022-08-25 13:27:16.025 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-25 13:27:16.026 1779-25614/? 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: 3776601084, 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: 6995, 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: 3776601084, 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: 6995, 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: 3776601084, 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: 6995, 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: 3776601084, 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: 6995, 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: 6995, 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-25 13:27:16.028 1779-25614/? D/NativeUwbManager: onRangeDataNotificationReceived : UwbRangingData {  SeqCounter = 0, SessionId = 3776601084, RcrIndication = 0, CurrRangingInterval = 240, RangingMeasuresType = 1, MacAddressMode = 0, NoOfRangingMeasures = 1, RangingTwoWayMeasures = [UwbTwoWayMeasurement {  MacAddress = 531B, 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-25 13:27:16.029 1779-25614/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingResult
2022-08-25 13:27:16.031 2754-25482/? I/NearbyUWB: UWB Ranging Data from peer 0X1B53 Status 33 [CONTEXT service_id=49 ]
2022-08-25 13:27:16.265 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-25 13:27:16.266 1779-25614/? 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: 1, session_id: 3776601084, 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: 6995, 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: 1, session_id: 3776601084, 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: 6995, 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: 1, session_id: 3776601084, 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: 6995, 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: 1, session_id: 3776601084, 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: 6995, 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: 6995, 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-25 13:27:16.267 1779-25614/? D/NativeUwbManager: onRangeDataNotificationReceived : UwbRangingData {  SeqCounter = 1, SessionId = 3776601084, RcrIndication = 0, CurrRangingInterval = 240, RangingMeasuresType = 1, MacAddressMode = 0, NoOfRangingMeasures = 1, RangingTwoWayMeasures = [UwbTwoWayMeasurement {  MacAddress = 531B, 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-25 13:27:16.268 1779-25614/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingResult
2022-08-25 13:27:16.268 2754-25482/? I/NearbyUWB: UWB Ranging Data from peer 0X1B53 Status 33 [CONTEXT service_id=49 ]
2022-08-25 13:27:16.505 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-25 13:27:16.506 1779-25614/? 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: 2, session_id: 3776601084, 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: 6995, 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: 2, session_id: 3776601084, 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: 6995, 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: 2, session_id: 3776601084, 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: 6995, 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: 2, session_id: 3776601084, 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: 6995, 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: 6995, 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-25 13:27:16.507 1779-25614/? D/NativeUwbManager: onRangeDataNotificationReceived : UwbRangingData {  SeqCounter = 2, SessionId = 3776601084, RcrIndication = 0, CurrRangingInterval = 240, RangingMeasuresType = 1, MacAddressMode = 0, NoOfRangingMeasures = 1, RangingTwoWayMeasures = [UwbTwoWayMeasurement {  MacAddress = 531B, 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-25 13:27:16.508 1779-25614/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingResult
2022-08-25 13:27:16.509 2754-25482/? I/NearbyUWB: UWB Ranging Data from peer 0X1B53 Status 33 [CONTEXT service_id=49 ]
2022-08-25 13:27:16.745 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-25 13:27:16.746 1779-25614/? 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: 3, session_id: 3776601084, 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: 6995, 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: 3, session_id: 3776601084, 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: 6995, 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: 3, session_id: 3776601084, 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: 6995, 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: 3, session_id: 3776601084, 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: 6995, 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: 6995, 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-25 13:27:16.748 1779-25614/? D/NativeUwbManager: onRangeDataNotificationReceived : UwbRangingData {  SeqCounter = 3, SessionId = 3776601084, RcrIndication = 0, CurrRangingInterval = 240, RangingMeasuresType = 1, MacAddressMode = 0, NoOfRangingMeasures = 1, RangingTwoWayMeasures = [UwbTwoWayMeasurement {  MacAddress = 531B, 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-25 13:27:16.749 1779-25614/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingResult
2022-08-25 13:27:16.754 2754-25482/? I/NearbyUWB: UWB Ranging Data from peer 0X1B53 Status 33 [CONTEXT service_id=49 ]
2022-08-25 13:27:16.985 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-25 13:27:16.986 1779-25614/? 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: 4, session_id: 3776601084, 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: 6995, 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: 4, session_id: 3776601084, 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: 6995, 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: 4, session_id: 3776601084, 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: 6995, 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: 4, session_id: 3776601084, 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: 6995, 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: 6995, 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-25 13:27:16.989 1779-25614/? D/NativeUwbManager: onRangeDataNotificationReceived : UwbRangingData {  SeqCounter = 4, SessionId = 3776601084, RcrIndication = 0, CurrRangingInterval = 240, RangingMeasuresType = 1, MacAddressMode = 0, NoOfRangingMeasures = 1, RangingTwoWayMeasures = [UwbTwoWayMeasurement {  MacAddress = 531B, 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-25 13:27:16.990 1779-25614/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingResult
2022-08-25 13:27:16.992 2754-25482/? I/NearbyUWB: UWB Ranging Data from peer 0X1B53 Status 33 [CONTEXT service_id=49 ]

For reference, this is the Android code snippet we are using to try and get UWB ranging information:

 I’ve been setting breakpoints in the Qorvo code to see why the errors might be happening with Android, but iOS is fine.
This is using the DWM3000EVB obviously.
 
Android is a controller, like iOS, and apart from different session Ids / STS_IV / src addr / dest addr, the FiRa config looks the same. (I have tried different timings between iOS and Android to match the 2400 RSTU)
 
On Android I am setting  breakpoints in Qorvo cdw3000_mcps_mcu.c, and I see a mcps_rxerror_cb.
The status is set to 0x3001307 which I think decodes the SYS_STATUS as:
PLLHILO Clock PLL Losing Lock
RCINIT
RXPHE Receiver PHY Header Error
RXSFDD Receiver SFD Detected
RXPRD Receiver Preamble Detected
SPICRCE SPI CRC Error
CPLOCK Clock PLL lock
IRQS
The important ones seem to be PLLHILO and RXPHE.
Not sure about PLLHILO, as perhaps the breakpoints are getting in the way, but PHR demod sounds bad.

Any ideas from the Android UWB team would be welcome…
 
This is the section from the DW3000 manual:
 
PHR demodulation
The main role of the PHY Header (PHR) is to convey the length of the data portion of the packet, and to indicate the data rate being employed for data demodulation. See paragraph 2.9 - PHY header and paragraph 2.10 for details of the PHY header. For data rates of 850 kb/s and 6.8 Mb/s the PHR is modulated / demodulated as per the 850 kb/s data rate (note that because Reed Solomon encoding is not applied to the PHR, its bit rate is approximately 1 Mb/s). If the PHR is indicating 850 kb/s then the data demodulation continues at this rate, but if the PHR is indicating 6.8 Mb/s then the demodulation changes to this rate at the end of the PHR as data demodulation begins.
It is also possible to configure the PHR rate to be the same as the data rate, i.e. to use 6.8 Mbit/s with PHR_6M8 configuration bit in SYS_CFG, 8.2.2.4.

OK a bit more progress...
It appears that NearBy is setting the channel and preamble-index, so what you set in the RangingParameters is being ignored.

After a bit of looking at the UwbControllerSessionScope, I see the complex channel is available, so reading that has allowed me to get the complex channel set by NearBy UWB.

Unfortunately it is still getting the RXPHE error on the DW3000.

2022-09-04 10:56:19.919 2688-9214/? I/NearbyUWB: UWB Specification: [CONTEXT service_id=49 ]
2022-09-04 10:56:19.919 2688-9214/? I/NearbyUWB: Capability: block_striding, value: true [CONTEXT service_id=49 ]
2022-09-04 10:56:19.919 2688-9214/? I/NearbyUWB: Capability: max_phy_version, value: 1.1 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.919 2688-9214/? I/NearbyUWB: Capability: non_deferred_mode, value: false [CONTEXT service_id=49 ]
2022-09-04 10:56:19.919 2688-9214/? I/NearbyUWB: Capability: sts_capabilities, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: hprf_parameter_set_capabilities, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: ranging_round_capabilities, value: 3 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: psdu_data_rate_capabilities, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: rframe_capabilities, value: 8 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: prf_capabilities, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: bundle_version, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: protocol_name, value: fira [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: min_mac_version, value: 1.1 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: aoa_capabilities, value: 25 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: bprf_parameter_set_capabilities, value: 8 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: min_phy_version, value: 1.1 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: channels, value: [5, 9] [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: initiation_time, value: true [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: max_mac_version, value: 1.1 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: device_role_capabilities, value: 15 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.920 2688-9214/? I/NearbyUWB: Capability: multi_node_capabilities, value: 3 [CONTEXT service_id=49 ]
2022-09-04 10:56:19.925 2688-9214/? I/NearbyUWB: set complexChannel to UwbComplexChannel{channel=9, preambleIndex=11} [CONTEXT service_id=49 ]
2022-09-04 10:56:19.927 10758-10758/com.example.app D/MainActivity: Got UWB local address 0E:31 ranging capabilities: distance true, azimuth true, elevation false
2022-09-04 10:56:21.157 10758-10758/com.example.app D/MainActivity$ConnectBluetooth$leScanCallback: Found UWB BLE GATT: D7:F5:0F:38:FC:02
2022-09-04 10:56:21.440 10758-10758/com.example.app D/MainActivity: UWB BLE Connected DWM3000EVB + nRF52840DK
2022-09-04 10:56:22.334 10758-10758/com.example.app D/MainActivity: UWB BLE Device Ready
2022-09-04 10:56:22.336 10758-10758/com.example.app D/UARTManager: UWB BLE 1 bytes sent data: 0x0a
2022-09-04 10:56:22.434 10758-10758/com.example.app D/UARTManager: UWB BLE 36 bytes received data: 0x01, 0x01, 0x00, 0x00, 0x00, 0x14, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x13, 0x01, 0x00, 0x00, 0x00, 0x3f, 0xf5, 0x03, 0x00, 0xb8, 0x0b, 0x00, 0x00, 0x01, 0x09, 0x09, 0x00, 0x00, 0x64, 0x22
2022-09-04 10:56:22.435 10758-10758/com.example.app D/MainActivity: Got UWB config data length: 35
2022-09-04 10:56:22.436 10758-10758/com.example.app D/MainActivity: Got UWB Config 256:0 update rate: 20 UwbConfigLen 19
2022-09-04 10:56:22.438 10758-10794/com.example.app D/MainActivity: UWB Session id:        1
2022-09-04 10:56:22.438 10758-10794/com.example.app D/MainActivity: Config UWB channel 9 preamble 11 destAddr 64:22 srcAddr 0E:31
2022-09-04 10:56:22.440 10758-10758/com.example.app D/UARTManager: UWB BLE 29 bytes sent data: 0x0b, 0x01, 0x00, 0x00, 0x00, 0x17, 0x45, 0x55, 0x01, 0x00, 0x00, 0x00, 0x0b, 0x09, 0x06, 0x00, 0x60, 0x09, 0xf0, 0x00, 0x03, 0x01, 0x01, 0x01, 0x01, 0x01, 0x01, 0x0e, 0x31
2022-09-04 10:56:22.634 10758-10758/com.example.app D/UARTManager: UWB BLE 1 bytes received data: 0x02
2022-09-04 10:56:22.637 10758-10758/com.example.app D/MainActivity: Accessory UWB started
2022-09-04 10:56:22.641 10758-10758/com.example.app D/MainActivity: Start ranging UWB channel 9 preamble 11 destAddr 64:22
2022-09-04 10:56:22.651 2688-9214/? I/NearbyUWB: Add UWB peer: 0X6422 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.651 2688-9214/? I/NearbyUWB: UWB Ranging Device start ranging [CONTEXT service_id=49 ]
2022-09-04 10:56:22.651 2688-9214/? I/NearbyUWB: Local UWB address is 0X0E31 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: Opens UWB session with bundle parameters: [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: aoa_result_request, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: range_data_ntf_proximity_near, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: is_tx_adaptive_payload_power_enabled, value: false [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: rframe_config, value: 3 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: max_ranging_round_retries, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: psdu_data_rate, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: fcs_type, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: device_role, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: device_type, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: block_stride_length, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: prf_mode, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: slot_duration_rstu, value: 2400 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: protocol_version, value: 1.1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: slots_per_ranging_round, value: 6 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.652 2688-9214/? I/NearbyUWB: UWB parameter: vendor_id, value: [76, 0] [CONTEXT service_id=49 ]
2022-09-04 10:56:22.653 2688-9214/? I/NearbyUWB: UWB parameter: ranging_interval_ms, value: 240 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.653 2688-9214/? I/NearbyUWB: UWB parameter: dest_address_list, value: [7215329553000955904] [CONTEXT service_id=49 ]
2022-09-04 10:56:22.653 2688-9214/? I/NearbyUWB: UWB parameter: sfd_id, value: 2 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.653 2688-9214/? I/NearbyUWB: UWB parameter: num_of_msrmt_focus_on_range, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.653 2688-9214/? I/NearbyUWB: UWB parameter: channel_number, value: 9 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.653 2688-9214/? I/NearbyUWB: UWB parameter: session_priority, value: 50 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.653 2688-9214/? I/NearbyUWB: UWB parameter: is_key_rotation_enabled, value: false [CONTEXT service_id=49 ]
2022-09-04 10:56:22.653 2688-9214/? I/NearbyUWB: UWB parameter: sts_config, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.656 2688-9214/? I/NearbyUWB: UWB parameter: num_of_msrmt_focus_on_aoa_elevation, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.656 2688-9214/? I/NearbyUWB: UWB parameter: in_band_termination_attempt_count, value: 3 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.656 2688-9214/? I/NearbyUWB: UWB parameter: range_data_ntf_proximity_far, value: 20000 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.656 2688-9214/? I/NearbyUWB: UWB parameter: bprf_phr_data_rate, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.656 2688-9214/? I/NearbyUWB: UWB parameter: sts_length, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.656 2688-9214/? I/NearbyUWB: UWB parameter: initiation_time_ms, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.658 2688-9214/? I/NearbyUWB: UWB parameter: aoa_type, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: has_time_of_flight_report, value: true [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: has_angle_of_arrival_figure_of_merit_report, value: false [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: has_angle_of_arrival_azimuth_report, value: false [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: mac_address_mode, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: preamble_duration, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: key_rotation_rate, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: bundle_version, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: has_angle_of_arrival_elevation_report, value: false [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: static_sts_iv, value: [1, 1, 1, 1, 1, 1] [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: range_data_ntf_config, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: ranging_round_usage, value: 2 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: protocol_name, value: fira [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: has_result_report_phase, value: true [CONTEXT service_id=49 ]
2022-09-04 10:56:22.661 2688-9214/? I/NearbyUWB: UWB parameter: device_address, value: 1022598590389813248 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.662 2688-9214/? I/NearbyUWB: UWB parameter: measurement_report_type, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.662 2688-9214/? I/NearbyUWB: UWB parameter: preamble_code_index, value: 11 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.662 2688-9214/? I/NearbyUWB: UWB parameter: session_id, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.662 2688-9214/? I/NearbyUWB: UWB parameter: multi_node_mode, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.662 2688-9214/? I/NearbyUWB: UWB parameter: num_of_msrmt_focus_on_aoa_azimuth, value: 0 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.662 2688-9214/? I/NearbyUWB: UWB parameter: hopping_mode, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.662 2688-9214/? I/NearbyUWB: UWB parameter: sts_segment_count, value: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.662 2688-9214/? I/NearbyUWB: exec UWB operation Open session [CONTEXT service_id=49 ]
2022-09-04 10:56:22.668 1756-3953/? E/AppOps: noteOperation
    java.lang.SecurityException: Specified package "android" under uid 10104 but it is not
        at com.android.server.appop.AppOpsService.verifyAndGetBypass(AppOpsService.java:4695)
        at com.android.server.appop.AppOpsService.noteOperationUnchecked(AppOpsService.java:3453)
        at com.android.server.appop.AppOpsService.noteOperationImpl(AppOpsService.java:3441)
        at com.android.server.appop.AppOpsService.-$$Nest$mnoteOperationImpl(Unknown Source:0)
        at com.android.server.appop.AppOpsService$CheckOpsDelegateDispatcher.lambda$noteOperation$4(AppOpsService.java:7641)
        at com.android.server.appop.AppOpsService$CheckOpsDelegateDispatcher.$r8$lambda$oOwUoG55BBmJ6yiwhp3OAq3tQ30(Unknown Source:0)
        at com.android.server.appop.AppOpsService$CheckOpsDelegateDispatcher$$ExternalSyntheticLambda1.apply(Unknown Source:32)
        at com.android.server.policy.AppOpsPolicy.noteOperation(AppOpsPolicy.java:227)
        at com.android.server.appop.AppOpsService$CheckOpsDelegateDispatcher.noteOperation(AppOpsService.java:7639)
        at com.android.server.appop.AppOpsService.noteOperation(AppOpsService.java:3425)
        at com.android.server.am.ActivityManagerService.hasUsageStatsPermission(ActivityManagerService.java:2998)
        at com.android.server.am.ActivityManagerService.hasUsageStatsPermission(ActivityManagerService.java:3008)
        at com.android.server.am.ActivityManagerService.getPackageProcessState(ActivityManagerService.java:3024)
        at android.app.ActivityManager.getPackageImportance(ActivityManager.java:3765)
        at com.android.server.uwb.UwbInjector.getPackageImportance(UwbInjector.java:302)
        at com.android.server.uwb.UwbInjector.isForegroundAppOrService(UwbInjector.java:312)
        at com.android.server.uwb.UwbServiceCore.hasAnyNonSystemAppNotInFgInAttributionSource(UwbServiceCore.java:277)
        at com.android.server.uwb.UwbServiceCore.openRanging(UwbServiceCore.java:296)
        at com.android.server.uwb.UwbServiceImpl.openRanging(UwbServiceImpl.java:154)
        at android.uwb.IUwbAdapter$Stub.onTransact(IUwbAdapter.java:477)
        at android.os.Binder.execTransactInternal(Binder.java:1280)
        at android.os.Binder.execTransact(Binder.java:1244)
2022-09-04 10:56:22.668 1756-3953/? I/UwbSessionManager: initSession() : Enter - sessionId : 1
2022-09-04 10:56:22.669 1756-11059/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeSessionInit: enter
2022-09-04 10:56:22.669 1756-10704/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciSessionInit(1, 0)
2022-09-04 10:56:22.669 1756-10704/? 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-09-04 10:56:22.669 1756-10704/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-09-04 10:56:22.670 900-900/? I/qorvo.uwb.UciBackendAndroidCCC: SessionInit: MT/GID/OID 2100 CMD
2022-09-04 10:56:22.670 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_init: MT/GID/OID 2100 CMD
2022-09-04 10:56:22.670 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.670 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.670 900-900/? I/qorvo.uwb.fira_helper: call_fira: call_id 1 session_id 1
2022-09-04 10:56:22.670 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.670 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_init: MT/GID/OID 2100 RSP 0
2022-09-04 10:56:22.670 900-900/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 0 (0)
2022-09-04 10:56:22.670 1756-10704/? 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-09-04 10:56:22.670 1756-10704/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-09-04 10:56:22.670 1756-11059/? 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-09-04 10:56:22.670 1756-10704/? 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-09-04 10:56:22.670 1756-10704/? I/uwb: uwb_uci_rust::uci: Session 1 initialized, invoking session init HAL API
2022-09-04 10:56:22.671 1756-10704/? D/NativeUwbManager: onSessionStatusNotificationReceived(1, 0, 0)
2022-09-04 10:56:22.671 1756-10704/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 1, state : INIT reasonCode:0
2022-09-04 10:56:22.671 1756-11059/? D/UwbConfManager: setAppConfigurations for protocol: fira
2022-09-04 10:56:22.671 1756-11059/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeSetAppConfigurations: enter
2022-09-04 10:56:22.671 1756-10704/? 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, 49, 14, 7, 2, 34, 100, 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, 11, 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, 1, 1, 1, 1, 1, 1, 41, 1, 1, 42, 2, 0, 0, 43, 4, 0, 0, 0, 0, 44, 1, 1, 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-09-04 10:56:22.672 1756-10704/? 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: [49, 14] }, AppConfigTlv { cfg_id: DstMacAddress, v: [34, 100] }, 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: [11] }, 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: [1, 1, 1, 1, 1, 1] }, 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: [1] }, 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: [49, 14] }, AppConfigTlv { cfg_id: DstMacAddress, v: [34, 100] }, 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: [11] }, 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: K
2022-09-04 10:56:22.672 1756-10704/? D/uwb: eyRotation, 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: [1, 1, 1, 1, 1, 1] }, 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: [1] }, 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-09-04 10:56:22.672 1756-10704/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.UciBackendAndroidCCC: SessionSetAppConfig: MT/GID/OID 2103 CMD
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: MT/GID/OID 2103 CMD
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: session_id=1
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 1
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: mac_state=3
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: uci_state=0
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.fira_helper: call_fira: call_id 12 session_id 1
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 1
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: mac_state=3
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.fira_helper: call_fira: call_id 9 session_id 1
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.fira_helper: call_fira: call_id 5 session_id 1
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: MT/GID/OID 4103 RSP 0
2022-09-04 10:56:22.672 900-900/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 3 (0)
2022-09-04 10:56:22.676 1756-10704/? 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-09-04 10:56:22.676 1756-10704/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-09-04 10:56:22.676 1756-10704/? 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-09-04 10:56:22.676 1756-10704/? D/NativeUwbManager: onSessionStatusNotificationReceived(1, 3, 0)
2022-09-04 10:56:22.676 1756-10704/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 1, state : IDLE reasonCode:0
2022-09-04 10:56:22.677 1756-11059/? 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-09-04 10:56:22.677 1756-11059/? I/UwbConfManager: setAppConfigurations respData: UwbConfigStatusData {  status = 0 length = 0, tlvs = [[]] }
2022-09-04 10:56:22.677 1756-11059/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingOpened
2022-09-04 10:56:22.678 1756-2038/? I/UwbSessionManager: sessionInit() : finish - sessionId : 1
2022-09-04 10:56:22.678 2688-10473/? I/NearbyUWB: UWB session opened: 1 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.679 2688-9214/? I/NearbyUWB: exec UWB operation Start ranging [CONTEXT service_id=49 ]
2022-09-04 10:56:22.679 1756-2176/? I/UwbSessionManager: startRanging() - Session ID : 1
2022-09-04 10:56:22.680 1756-11061/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeRangingStart: enter
2022-09-04 10:56:22.680 1756-10704/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciStartRange(1)
2022-09-04 10:56:22.680 1756-10704/? 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-09-04 10:56:22.680 1756-10704/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-09-04 10:56:22.680 900-900/? I/qorvo.uwb.UciBackendAndroidCCC: RangingStart: MT/GID/OID 2200 CMD
2022-09-04 10:56:22.680 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: MT/GID/OID 2200 CMD
2022-09-04 10:56:22.680 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: session_id=1
2022-09-04 10:56:22.680 900-900/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 1
2022-09-04 10:56:22.718 900-900/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-04 10:56:22.718 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.718 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.721 1083-1083/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x44, rc: 0
2022-09-04 10:56:22.721 1083-1083/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cb, tag: 0x45, rc: 0
2022-09-04 10:56:22.722 1083-1083/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x46, rc: 0
2022-09-04 10:56:22.734 1083-1083/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x47, rc: 0
2022-09-04 10:56:22.743 900-900/? I/qorvo.uwb.fira_helper: call_fira: call_id 2 session_id 1
2022-09-04 10:56:22.745 900-900/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-04 10:56:22.745 900-900/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: MT/GID/OID 2200 RSP 0
2022-09-04 10:56:22.745 900-900/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 2 (0)
2022-09-04 10:56:22.745 1756-10704/? 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-09-04 10:56:22.745 1756-10704/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-09-04 10:56:22.745 1756-11061/? 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-09-04 10:56:22.745 1756-10704/? 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-09-04 10:56:22.745 1756-10704/? D/NativeUwbManager: onDeviceStatusNotificationReceived(2)
2022-09-04 10:56:22.745 1756-10704/? I/UwbServiceCore: handleDeviceStatusNotification = ACTIVE
2022-09-04 10:56:22.746 1756-10704/? 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-09-04 10:56:22.746 1756-10704/? D/NativeUwbManager: onSessionStatusNotificationReceived(1, 2, 0)
2022-09-04 10:56:22.746 1756-10704/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 1, state : ACTIVE reasonCode:0
2022-09-04 10:56:22.746 1756-11061/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingStarted
2022-09-04 10:56:22.747 2688-10473/? I/NearbyUWB: UWB ranging started [CONTEXT service_id=49 ]
2022-09-04 10:56:22.747 2688-10473/? I/NearbyUWB: Local UWB address is 0X0E31 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.747 2688-10473/? I/NearbyUWB: Local UWB address is 0X0E31 [CONTEXT service_id=49 ]
2022-09-04 10:56:22.749 10758-10758/com.example.app I/UwbClientSessionScope: Started UWB ranging.
2022-09-04 10:56:22.799 900-900/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-04 10:56:22.800 1756-10704/? 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: 25634, 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: 25634, 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: 25634, 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: 25634, 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: 25634, 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-09-04 10:56:22.800 1756-10704/? D/NativeUwbManager: onRangeDataNotificationReceived : UwbRangingData {  SeqCounter = 0, SessionId = 1, RcrIndication = 0, CurrRangingInterval = 240, RangingMeasuresType = 1, MacAddressMode = 0, NoOfRangingMeasures = 1, RangingTwoWayMeasures = [UwbTwoWayMeasurement {  MacAddress = 2264, 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-09-04 10:56:22.801 1756-10704/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingResult
2022-09-04 10:56:22.803 2688-10473/? I/NearbyUWB: UWB Ranging Data from peer 0X6422 Status 33 [CONTEXT service_id=49 ]

As an experiment, I tried forcing the DWM3000EVB to be a controller, and there is a different error which looks like it is failing to decode the Rx packet.
UciStatusRangingRxMacIeDecFailed sounds like it could be the encryption of the IE, although I have tried different byte orders and values for STS_IV and Vendor ID.


2022-09-05 11:57:30.474 1513-1551/com.example.app D/MainActivity: Config UWB channel 9 preamble 11 destAddr c9:7d srcAddr 6F:E3
2022-09-05 11:57:30.475 1513-1513/com.example.app D/UARTManager: UWB BLE 29 bytes sent data: 0x0b, 0x01, 0x00, 0x00, 0x00, 0x17, 0x45, 0x55, 0x34, 0x12, 0x00, 0x00, 0x0b, 0x09, 0x06, 0x00, 0x60, 0x09, 0xf0, 0x00, 0x03, 0x06, 0x05, 0x04, 0x03, 0x02, 0x01, 0x6f, 0xe3
2022-09-05 11:57:30.569 1513-1513/com.example.app D/UARTManager: UWB BLE 1 bytes received data: 0x02
2022-09-05 11:57:30.570 1513-1513/com.example.app D/MainActivity: Accessory UWB started
2022-09-05 11:57:30.571 1513-1513/com.example.app D/MainActivity: Start ranging UWB channel 9 preamble 11 destAddr C9:7D
2022-09-05 11:57:30.577 2683-1024/? I/NearbyUWB: UWB Ranging Device start ranging [CONTEXT service_id=49 ]
2022-09-05 11:57:30.577 2683-1024/? I/NearbyUWB: Local UWB address is 0X6FE3 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.581 2683-1024/? I/NearbyUWB: Opens UWB session with bundle parameters: [CONTEXT service_id=49 ]
2022-09-05 11:57:30.586 2683-1024/? I/NearbyUWB: UWB parameter: aoa_result_request, value: 1 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.586 2683-1024/? I/NearbyUWB: UWB parameter: range_data_ntf_proximity_near, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: is_tx_adaptive_payload_power_enabled, value: false [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: rframe_config, value: 3 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: max_ranging_round_retries, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: psdu_data_rate, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: fcs_type, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: device_role, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: device_type, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: block_stride_length, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: prf_mode, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: slot_duration_rstu, value: 2400 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.587 2683-1024/? I/NearbyUWB: UWB parameter: protocol_version, value: 1.1 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.588 2683-1024/? I/NearbyUWB: UWB parameter: slots_per_ranging_round, value: 6 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.588 2683-1024/? I/NearbyUWB: UWB parameter: vendor_id, value: [0, 76] [CONTEXT service_id=49 ]
2022-09-05 11:57:30.588 2683-1024/? I/NearbyUWB: UWB parameter: ranging_interval_ms, value: 240 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.588 2683-1024/? I/NearbyUWB: UWB parameter: dest_address_list, value: [-3927983299997204480] [CONTEXT service_id=49 ]
2022-09-05 11:57:30.588 2683-1024/? I/NearbyUWB: UWB parameter: sfd_id, value: 2 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.588 2683-1024/? I/NearbyUWB: UWB parameter: num_of_msrmt_focus_on_range, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.588 2683-1024/? I/NearbyUWB: UWB parameter: channel_number, value: 9 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.588 2683-1024/? I/NearbyUWB: UWB parameter: session_priority, value: 50 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.588 2683-1024/? I/NearbyUWB: UWB parameter: is_key_rotation_enabled, value: false [CONTEXT service_id=49 ]
2022-09-05 11:57:30.589 2683-1024/? I/NearbyUWB: UWB parameter: sts_config, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.589 2683-1024/? I/NearbyUWB: UWB parameter: num_of_msrmt_focus_on_aoa_elevation, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.589 2683-1024/? I/NearbyUWB: UWB parameter: in_band_termination_attempt_count, value: 3 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.589 2683-1024/? I/NearbyUWB: UWB parameter: range_data_ntf_proximity_far, value: 20000 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.589 2683-1024/? I/NearbyUWB: UWB parameter: bprf_phr_data_rate, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.589 2683-1024/? I/NearbyUWB: UWB parameter: sts_length, value: 1 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.589 2683-1024/? I/NearbyUWB: UWB parameter: initiation_time_ms, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.589 2683-1024/? I/NearbyUWB: UWB parameter: aoa_type, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.589 2683-1024/? I/NearbyUWB: UWB parameter: has_time_of_flight_report, value: true [CONTEXT service_id=49 ]
2022-09-05 11:57:30.590 2683-1024/? I/NearbyUWB: UWB parameter: has_angle_of_arrival_figure_of_merit_report, value: false [CONTEXT service_id=49 ]
2022-09-05 11:57:30.590 2683-1024/? I/NearbyUWB: UWB parameter: has_angle_of_arrival_azimuth_report, value: false [CONTEXT service_id=49 ]
2022-09-05 11:57:30.590 2683-1024/? I/NearbyUWB: UWB parameter: mac_address_mode, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.590 2683-1024/? I/NearbyUWB: UWB parameter: preamble_duration, value: 1 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.590 2683-1024/? I/NearbyUWB: UWB parameter: key_rotation_rate, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.590 2683-1024/? I/NearbyUWB: UWB parameter: bundle_version, value: 1 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: has_angle_of_arrival_elevation_report, value: false [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: static_sts_iv, value: [6, 5, 4, 3, 2, 1] [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: range_data_ntf_config, value: 1 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: ranging_round_usage, value: 2 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: protocol_name, value: fira [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: has_result_report_phase, value: true [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: device_address, value: 8062287757923319808 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: measurement_report_type, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: preamble_code_index, value: 11 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: session_id, value: 4660 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.591 2683-1024/? I/NearbyUWB: UWB parameter: multi_node_mode, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.592 2683-1024/? I/NearbyUWB: UWB parameter: num_of_msrmt_focus_on_aoa_azimuth, value: 0 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.592 2683-1024/? I/NearbyUWB: UWB parameter: hopping_mode, value: 1 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.592 2683-1024/? I/NearbyUWB: UWB parameter: sts_segment_count, value: 1 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.593 2683-1024/? I/NearbyUWB: exec UWB operation Open session [CONTEXT service_id=49 ]
2022-09-05 11:57:30.595 1767-1810/? E/AppOps: noteOperation
    java.lang.SecurityException: Specified package "android" under uid 10104 but it is not
        at com.android.server.appop.AppOpsService.verifyAndGetBypass(AppOpsService.java:4695)
        at com.android.server.appop.AppOpsService.noteOperationUnchecked(AppOpsService.java:3453)
        at com.android.server.appop.AppOpsService.noteOperationImpl(AppOpsService.java:3441)
        at com.android.server.appop.AppOpsService.-$$Nest$mnoteOperationImpl(Unknown Source:0)
        at com.android.server.appop.AppOpsService$CheckOpsDelegateDispatcher.lambda$noteOperation$4(AppOpsService.java:7641)
        at com.android.server.appop.AppOpsService$CheckOpsDelegateDispatcher.$r8$lambda$oOwUoG55BBmJ6yiwhp3OAq3tQ30(Unknown Source:0)
        at com.android.server.appop.AppOpsService$CheckOpsDelegateDispatcher$$ExternalSyntheticLambda1.apply(Unknown Source:32)
        at com.android.server.policy.AppOpsPolicy.noteOperation(AppOpsPolicy.java:227)
        at com.android.server.appop.AppOpsService$CheckOpsDelegateDispatcher.noteOperation(AppOpsService.java:7639)
        at com.android.server.appop.AppOpsService.noteOperation(AppOpsService.java:3425)
        at com.android.server.am.ActivityManagerService.hasUsageStatsPermission(ActivityManagerService.java:2998)
        at com.android.server.am.ActivityManagerService.hasUsageStatsPermission(ActivityManagerService.java:3008)
        at com.android.server.am.ActivityManagerService.getPackageProcessState(ActivityManagerService.java:3024)
        at android.app.ActivityManager.getPackageImportance(ActivityManager.java:3765)
        at com.android.server.uwb.UwbInjector.getPackageImportance(UwbInjector.java:302)
        at com.android.server.uwb.UwbInjector.isForegroundAppOrService(UwbInjector.java:312)
        at com.android.server.uwb.UwbServiceCore.hasAnyNonSystemAppNotInFgInAttributionSource(UwbServiceCore.java:277)
        at com.android.server.uwb.UwbServiceCore.openRanging(UwbServiceCore.java:296)
        at com.android.server.uwb.UwbServiceImpl.openRanging(UwbServiceImpl.java:154)
        at android.uwb.IUwbAdapter$Stub.onTransact(IUwbAdapter.java:477)
        at android.os.Binder.execTransactInternal(Binder.java:1280)
        at android.os.Binder.execTransact(Binder.java:1244)
2022-09-05 11:57:30.595 1767-1810/? I/UwbSessionManager: initSession() : Enter - sessionId : 4660
2022-09-05 11:57:30.596 1767-2188/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeSessionInit: enter
2022-09-05 11:57:30.597 1767-1369/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciSessionInit(4660, 0)
2022-09-05 11:57:30.597 1767-1369/? 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: 4660, session_type: FiraRangingSession }) }) }) }, uci_command: UciCommandData { child: SessionCommand(SessionCommandData { child: SessionInitCmd(SessionInitCmdData { session_id: 4660, session_type: FiraRangingSession }) }) } }
2022-09-05 11:57:30.597 1767-1369/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-09-05 11:57:30.597 901-901/? I/qorvo.uwb.UciBackendAndroidCCC: SessionInit: MT/GID/OID 2100 CMD
2022-09-05 11:57:30.597 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_init: MT/GID/OID 2100 CMD
2022-09-05 11:57:30.625 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.625 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.625 901-901/? I/qorvo.uwb.fira_helper: call_fira: call_id 1 session_id 4660
2022-09-05 11:57:30.625 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.625 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_init: MT/GID/OID 2100 RSP 0
2022-09-05 11:57:30.626 901-901/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 0 (0)
2022-09-05 11:57:30.626 1767-1369/? 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-09-05 11:57:30.626 1767-1369/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-09-05 11:57:30.626 1767-2188/? 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-09-05 11:57:30.626 1767-1369/? 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: 4660, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands }) }) }) }, uci_notification: UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 4660, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands }) }) }, session_notification: SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 4660, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands }) }, session_status_ntf: SessionStatusNtfData { session_id: 4660, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands } })
2022-09-05 11:57:30.626 1767-1369/? I/uwb: uwb_uci_rust::uci: Session 4660 initialized, invoking session init HAL API
2022-09-05 11:57:30.627 1767-1369/? D/NativeUwbManager: onSessionStatusNotificationReceived(4660, 0, 0)
2022-09-05 11:57:30.627 1767-1369/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 4660, state : INIT reasonCode:0
2022-09-05 11:57:30.627 1767-2188/? D/UwbConfManager: setAppConfigurations for protocol: fira
2022-09-05 11:57:30.627 1767-2188/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeSetAppConfigurations: enter
2022-09-05 11:57:30.627 1767-1369/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciSetAppConfig { session_id: 4660, no_of_params: 44, app_config_param_len: 153, app_configs: [0, 1, 0, 1, 1, 2, 2, 1, 0, 3, 1, 0, 4, 1, 9, 5, 1, 1, 6, 2, 227, 111, 7, 2, 125, 201, 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, 0, 18, 1, 3, 20, 1, 11, 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, 76, 0, 40, 6, 6, 5, 4, 3, 2, 1, 41, 1, 1, 42, 2, 0, 0, 43, 4, 0, 0, 0, 0, 44, 1, 1, 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-09-05 11:57:30.627 1767-1369/? 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: 4660, tlvs: [AppConfigTlv { cfg_id: DeviceType, v: [0] }, 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: [227, 111] }, AppConfigTlv { cfg_id: DstMacAddress, v: [125, 201] }, 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: [0] }, AppConfigTlv { cfg_id: RframeConfig, v: [3] }, AppConfigTlv { cfg_id: PreambleCodeIndex, v: [11] }, 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: [76, 0] }, AppConfigTlv { cfg_id: StaticStsIv, v: [6, 5, 4, 3, 2, 1] }, 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: [1] }, 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: 4660, tlvs: [AppConfigTlv { cfg_id: DeviceType, v: [0] }, 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: [227, 111] }, AppConfigTlv { cfg_id: DstMacAddress, v: [125, 201] }, 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: [0] }, AppConfigTlv { cfg_id: RframeConfig, v: [3] }, AppConfigTlv { cfg_id: PreambleCodeIndex, v: [11] }, 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
2022-09-05 11:57:30.627 1767-1369/? D/uwb:  { 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: [76, 0] }, AppConfigTlv { cfg_id: StaticStsIv, v: [6, 5, 4, 3, 2, 1] }, 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: [1] }, 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-09-05 11:57:30.627 1767-1369/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-09-05 11:57:30.627 901-901/? I/qorvo.uwb.UciBackendAndroidCCC: SessionSetAppConfig: MT/GID/OID 2103 CMD
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: MT/GID/OID 2103 CMD
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: session_id=1234
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 4660
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: mac_state=3
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: uci_state=0
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 4660
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: mac_state=3
2022-09-05 11:57:30.628 901-901/? I/qorvo.uwb.fira_helper: call_fira: call_id 9 session_id 4660
2022-09-05 11:57:30.629 901-901/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-05 11:57:30.629 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.629 901-901/? I/qorvo.uwb.fira_helper: call_fira: call_id 5 session_id 4660
2022-09-05 11:57:30.629 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.629 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: MT/GID/OID 4103 RSP 0
2022-09-05 11:57:30.629 901-901/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 3 (0)
2022-09-05 11:57:30.629 1767-1369/? 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-09-05 11:57:30.629 1767-1369/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-09-05 11:57:30.629 1767-2188/? 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-09-05 11:57:30.629 1767-1369/? 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: 4660, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands }) }) }) }, uci_notification: UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 4660, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands }) }) }, session_notification: SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 4660, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands }) }, session_status_ntf: SessionStatusNtfData { session_id: 4660, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands } })
2022-09-05 11:57:30.629 1767-2188/? I/UwbConfManager: setAppConfigurations respData: UwbConfigStatusData {  status = 0 length = 0, tlvs = [[]] }
2022-09-05 11:57:30.629 1767-1369/? D/NativeUwbManager: onSessionStatusNotificationReceived(4660, 3, 0)
2022-09-05 11:57:30.629 1767-1369/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 4660, state : IDLE reasonCode:0
2022-09-05 11:57:30.629 1767-2188/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingOpened
2022-09-05 11:57:30.629 1767-2040/? I/UwbSessionManager: sessionInit() : finish - sessionId : 4660
2022-09-05 11:57:30.630 2683-28423/? I/NearbyUWB: UWB session opened: 4660 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.631 2683-1024/? I/NearbyUWB: exec UWB operation Start ranging [CONTEXT service_id=49 ]
2022-09-05 11:57:30.631 1767-4065/? I/UwbSessionManager: startRanging() - Session ID : 4660
2022-09-05 11:57:30.632 1767-2194/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeRangingStart: enter
2022-09-05 11:57:30.632 1767-1369/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciStartRange(4660)
2022-09-05 11:57:30.632 1767-1369/? 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: 4660 }) }) }) }, uci_command: UciCommandData { child: RangingCommand(RangingCommandData { child: RangeStartCmd(RangeStartCmdData { session_id: 4660 }) }) } }
2022-09-05 11:57:30.632 1767-1369/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-09-05 11:57:30.633 901-901/? I/qorvo.uwb.UciBackendAndroidCCC: RangingStart: MT/GID/OID 2200 CMD
2022-09-05 11:57:30.633 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: MT/GID/OID 2200 CMD
2022-09-05 11:57:30.633 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: session_id=1234
2022-09-05 11:57:30.633 901-901/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 4660
2022-09-05 11:57:30.633 901-901/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-05 11:57:30.633 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.633 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.637 1106-1106/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x2c, rc: 0
2022-09-05 11:57:30.637 1106-1106/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cb, tag: 0x2d, rc: 0
2022-09-05 11:57:30.637 1106-1106/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x2e, rc: 0
2022-09-05 11:57:30.648 1106-1106/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x2f, rc: 0
2022-09-05 11:57:30.657 901-901/? I/qorvo.uwb.fira_helper: call_fira: call_id 2 session_id 4660
2022-09-05 11:57:30.660 901-901/? I/qorvo.uwb.uwbmac: ack_handler
2022-09-05 11:57:30.660 901-901/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: MT/GID/OID 2200 RSP 0
2022-09-05 11:57:30.660 901-901/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 2 (0)
2022-09-05 11:57:30.660 1767-1369/? 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-09-05 11:57:30.660 1767-1369/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-09-05 11:57:30.660 1767-1369/? 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-09-05 11:57:30.660 1767-2194/? 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-09-05 11:57:30.660 1767-1369/? D/NativeUwbManager: onDeviceStatusNotificationReceived(2)
2022-09-05 11:57:30.660 1767-1369/? I/UwbServiceCore: handleDeviceStatusNotification = ACTIVE
2022-09-05 11:57:30.661 1767-1369/? 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: 4660, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands }) }) }) }, uci_notification: UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 4660, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands }) }) }, session_notification: SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 4660, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands }) }, session_status_ntf: SessionStatusNtfData { session_id: 4660, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands } })
2022-09-05 11:57:30.661 1767-1369/? D/NativeUwbManager: onSessionStatusNotificationReceived(4660, 2, 0)
2022-09-05 11:57:30.661 1767-1369/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 4660, state : ACTIVE reasonCode:0
2022-09-05 11:57:30.661 1767-2194/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingStarted
2022-09-05 11:57:30.661 2683-28423/? I/NearbyUWB: UWB ranging started [CONTEXT service_id=49 ]
2022-09-05 11:57:30.661 2683-28423/? I/NearbyUWB: Local UWB address is 0X6FE3 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.661 2683-28423/? I/NearbyUWB: Local UWB address is 0X6FE3 [CONTEXT service_id=49 ]
2022-09-05 11:57:30.663 1513-1513/com.example.app I/UwbClientSessionScope: Started UWB ranging.
2022-09-05 11:57:30.777 901-901/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-09-05 11:57:30.778 1767-1369/? 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: 1, session_id: 4660, 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: 51581, status: UciStatusRangingRxMacIeDecFailed, 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: 0 }] }) }) }) }) }, uci_notification: UciNotificationData { child: RangingNotification(RangingNotificationData { child: RangeDataNtf(RangeDataNtfData { sequence_number: 1, session_id: 4660, 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: 51581, status: UciStatusRangingRxMacIeDecFailed, 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: 0 }] }) }) }) }, ranging_notification: RangingNotificationData { child: RangeDataNtf(RangeDataNtfData { sequence_number: 1, session_id: 4660, 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: 51581, status: UciStatusRangingRxMacIeDecFailed, 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: 0 }] }) }) }, range_data_ntf: RangeDataNtfData { sequence_number: 1, session_id: 4660, 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: 51581, status: UciStatusRangingRxMacIeDecFailed, 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: 0 }] }) }, short_mac_two_way_range_data_ntf: ShortMacTwoWayRangeDataNtfData { two_way_ranging_measurements: [ShortAddressTwoWayRangingMeasurement { mac_address: 51581, status: UciStatusRangingRxMacIeDecFailed, 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: 0 }] } })
2022-09-05 11:57:30.778 1767-1369/? D/NativeUwbManager: onRangeDataNotificationReceived : UwbRangingData {  SeqCounter = 1, SessionId = 4660, RcrIndication = 0, CurrRangingInterval = 240, RangingMeasuresType = 1, MacAddressMode = 0, NoOfRangingMeasures = 1, RangingTwoWayMeasures = [UwbTwoWayMeasurement {  MacAddress = 7DC9, RangingStatus = 38, 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 = 0x00000000}]}
2022-09-05 11:57:30.779 1767-1369/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingResult
2022-09-05 11:57:30.781 2683-2196/? I/NearbyUWB: UWB Ranging Data from peer 0XC97D Status 38 [CONTEXT service_id=49 ]

Yes I added this in comment 9:
After a bit of looking at the UwbControllerSessionScope, I see the complex channel is available, so reading that has allowed me to get the complex channel set by NearBy UWB.

Unfortunately it is still getting the RXPHE error on the DW3000.

So I am no closer to getting working ranging, as I was always passing the channel / preamble to the DWM3000EVB / Estimote UWB beacon via bluetooth.
It was just I hadn't realised in the new Contoller mode that you get the complex channel from the UwbControllerSessionScope.

What I need to get past now are the 33 timeouts caused by the controlee getting the RXPHE error when trying to process the PHY Header (PHR).
The FiRa config looks to match what iOS is sending over BLE, although there must be some setting that is different in the Android side UWB config.