Bug: KmlLayer-Placemarks with color different to ffFFFFFF will not be displayed [35827252]

Obsolete

Bug

Status Update

No update yet.

Description

sv...@gmail.com

created issue #1

Sep 1, 2015 11:21PM

What steps will reproduce the problem?

1. Create a KML with a Point and apply an IconStyle with a color-value different to ffFFFFFF
2. use the KML to create a KmlLayer

According to the documentation in KmlLayers color isn't supported at all for IconStyle and should be ignored(and therefore shouldn't affect the Layer in any manner).

Of course it would be possible to simply remove the color, but when you use KML exported from other applications this is not always an option.

Especially I mean My Maps, where it's not possible to use Symbols(except white symbols) when you want to use the exported KML in a KmlLayer

Demo:

http://jsfiddle.net/doktormolle/92142r6q/
related SO-question:

http://stackoverflow.com/q/32323674/459897

*********************************************************
For developers viewing this issue: please click the 'star' icon to be
notified of future changes, and to let us know how many of you are
interested in seeing it resolved.
*********************************************************

Comments

ju...@gmail.com <ju...@gmail.com> #2Sep 2, 2015 12:36PM

Hi Xiangjun, do you know what is going on here?

mi...@gmail.com <mi...@gmail.com> #3Sep 5, 2015 10:16AM

Make sure they use the same session ID/VendorID/STATIC STS IV/address on both sides.

pa...@gmail.com <pa...@gmail.com> #4Sep 7, 2015 04:34AM

Hi, can you make sure that the same session Id and session key are used on both sides? https://developer.android.com/reference/kotlin/androidx/core/uwb/RangingParameters?hl=en#sessionId()

ac...@gmail.com <ac...@gmail.com> #5Sep 10, 2015 01:43AM

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!

pa...@gmail.com <pa...@gmail.com> #6Sep 10, 2015 02:22PM

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 ]

da...@gmail.com <da...@gmail.com> #7Sep 10, 2015 08:55PM

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

da...@gmail.com <da...@gmail.com> #8Sep 10, 2015 09:19PM

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.

Xi...@sunnet.us <Xi...@sunnet.us> #9Sep 11, 2015 08:03AM

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 ]

en...@google.com <en...@google.com> #10Sep 16, 2015 04:43AM

Accepted by en...@google.com.

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 ]

ba...@gmail.com <ba...@gmail.com> #12Oct 5, 2015 07:49PM

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.

al...@gmail.com <al...@gmail.com> #13Oct 7, 2015 02:01AM

Hi, I am facing the exact same issue.

I checked what you shared.

I can’t find UWB settings about Android. my question : https://issuetracker.google.com/issues/267212992

Could you please share how you solved it?

st...@gmail.com <st...@gmail.com> #14Oct 12, 2015 10:04PM

My problem too

ar...@gmail.com <ar...@gmail.com> #15Oct 24, 2015 10:11AM

Please fix this issue ASAP! My client is depending on this functionality for their public school system!! :-)

fa...@gmail.com <fa...@gmail.com> #16Nov 3, 2015 05:06PM

I can't see any progress from September on this bug. Any news?

g....@gmail.com <g....@gmail.com> #17Nov 12, 2015 09:18AM

Please can you let us know the status. In few months the old Google API will no longer be working anymore and we need to export the DATA.

thanks

da...@gmail.com <da...@gmail.com> #18Nov 14, 2015 08:52AM

It has been quite a while since the issue was brought up. What are the plans? This needs to be addressed ASAP!!

pa...@gmail.com <pa...@gmail.com> #19Dec 4, 2015 03:41PM

We are finally noticing some progress on this issue... it appears that the IconStyle information is back, including the color, but the associated icon is ALWAYS white. We're so close here... please take the next step and resolve fully by having the icon URL reflect an icon of the correct color, as was the behavior previously.

<IconStyle>
<color>ff44AF62</color>
<scale>1.1</scale>
<Icon>
<href>

http://www.gstatic.com/mapspro/images/stock/959-wht-circle-blank.png</href>
</Icon>
</IconStyle>

example:

http://www.garfieldbrooklyn.com/listing/60-plaza-street-east/

ar...@gmail.com <ar...@gmail.com> #20Feb 5, 2016 02:15PM

This article and many other on your blog are very interesting.
I'm very interested in learning more about your site.
Do you have a fanspage on facebook or other.?

Regards:

http://www.indopinbb.com/

ja...@gmail.com <ja...@gmail.com> #21Feb 15, 2016 08:35AM

[Comment deleted]

go...@yellowcherry.uk <go...@yellowcherry.uk> #22May 3, 2016 04:46PM

Is there any progress here?

en...@google.com <en...@google.com> May 10, 2016 03:45AM

Reassigned to st...@google.com.

pa...@gmail.com <pa...@gmail.com> #23Sep 7, 2016 10:57PM

We noticed that the color URL was coming through correctly for a short while but that behavior has reverted back and the icons are again always white.

Is there any update on this issue?

sr...@gmail.com <sr...@gmail.com> #24Jan 27, 2017 09:20PM

Any update on this bug, we are stuck with this issue?

[Deleted User] <[Deleted User]> #25Feb 18, 2017 11:17PM

Anyone figure out a work around?

[Deleted User] <[Deleted User]> #26Feb 18, 2017 11:17PM

[Comment deleted]

ya...@gmail.com <ya...@gmail.com> #27Apr 28, 2017 08:57PM

So there is no way to make <color> work in <iconStyle>??
Thank you,
Yanick

[Deleted User] <[Deleted User]> #28May 8, 2017 10:40AM

Hello do you have any update on this bug?

di...@gmail.com <di...@gmail.com> #29May 24, 2017 02:41PM

AIS

wo...@google.com <wo...@google.com> Sep 12, 2017 06:57AM

Reassigned to wo...@google.com.

lu...@gmail.com <lu...@gmail.com> #30Oct 3, 2017 01:36PM

Is there any way to make <color> work in <iconStyle>?

pa...@gmail.com <pa...@gmail.com> #31Jul 14, 2018 12:58AM

For anyone still waiting on a solution for this we've found that the color icons will work if you use KMZ (they are now included in a folder in the compressed KMZ archive) and either create a new map, or if you're using a new map change all the icons to the new set that Google has added. When you go to change icons, there should be a link on the lower right of the pop-up to switch to the newer icons. This worked for us and just wanted to share here in case it is of help to others.

ra...@google.com <ra...@google.com> Dec 17, 2019 01:08AM

Status: Won't Fix (Obsolete)