search

project-chip / certification-tool

A test harness and tooling designed to simplify development, testing, and certification for devices, guided by the Connectivity Standards Alliance.
https://csa-iot.org/
Apache License 2.0
36 stars 22 forks source link

[TC-CNET-4.1] [Matter1.3] Step 14 read SupportedWiFiBands attribute, but show is a unkown attribute. #360

Open ZeddChen opened 1 month ago

ZeddChen commented 1 month ago

Describe the bug

Try to using "./chip-tool networkcommissioning read-by-id 0x8 1 0" and " ./chip-tool networkcommissioning read supported-wi-fi-bands 1 0" ro read SupportedWiFiBands attribute, but one is shown "Don't know how to log atribute value", another one shown "Unknown attribute: supported-wi-fi-bands".

Steps to reproduce the behavior

No response

Expected behavior

No response

Log files

ubuntu@ubuntu:/apps$ ./chip-tool networkcommissioning read-by-id 0x8 1 0 [1723614183.569983][3974:3974] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1723614183.570945][3974:3974] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1723614183.570988][3974:3974] CHIP:DL: ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1723614183.576355][3974:3974] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1723614183.576652][3974:3974] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1723614183.576790][3974:3974] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1723614183.577151][3974:3974] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-SZnDbW) [1723614183.577769][3974:3974] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1723614183.577826][3974:3974] CHIP:DL: NVS set: chip-counters/reboot-count = 46 (0x2E) [1723614183.578663][3974:3974] CHIP:DL: Got Ethernet interface: eth0 [1723614183.579214][3974:3974] CHIP:DL: Found the primary Ethernet interface:eth0 [1723614183.579839][3974:3974] CHIP:DL: Got WiFi interface: wlan0 [1723614183.579907][3974:3974] CHIP:DL: Failed to reset WiFi statistic counts [1723614183.579958][3974:3974] CHIP:IN: UDP::Init bind&listen port=0 [1723614183.580075][3974:3974] CHIP:IN: UDP::Init bound to port=57892 [1723614183.580096][3974:3974] CHIP:IN: BLEBase::Init - setting/overriding transport [1723614183.580114][3974:3974] CHIP:IN: TransportMgr initialized [1723614183.580145][3974:3974] CHIP:FP: Initializing FabricTable from persistent storage [1723614183.580297][3974:3974] CHIP:TS: Last Known Good Time: 2023-10-03T16:51:18 [1723614183.581644][3974:3974] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x75E566932C31CC39, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1723614183.583884][3974:3974] CHIP:ZCL: Using ZAP configuration... [1723614183.587247][3974:3974] CHIP:DL: Avahi client registered [1723614183.587401][3974:3974] CHIP:CTL: System State Initialized... [1723614183.587557][3974:3974] CHIP:CTL: Setting attestation nonce to random value [1723614183.587595][3974:3974] CHIP:CTL: Setting CSR nonce to random value [1723614183.587652][3974:3974] CHIP:IN: UDP::Init bind&listen port=5550 [1723614183.587775][3974:3974] CHIP:IN: UDP::Init bound to port=5550 [1723614183.587797][3974:3974] CHIP:IN: TransportMgr initialized [1723614183.588058][3974:3976] CHIP:DL: CHIP task running [1723614183.588238][3974:3976] CHIP:DL: HandlePlatformSpecificBLEEvent 32786 [1723614183.588560][3974:3976] CHIP:CTL: Setting attestation nonce to random value [1723614183.588724][3974:3976] CHIP:CTL: Setting CSR nonce to random value [1723614183.589563][3974:3976] CHIP:CTL: Generating NOC [1723614183.590422][3974:3976] CHIP:FP: Validating NOC chain [1723614183.592077][3974:3976] CHIP:FP: NOC chain validation successful [1723614183.592244][3974:3976] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1723614183.592276][3974:3976] CHIP:TS: Last Known Good Time: 2023-10-03T16:51:18 [1723614183.592298][3974:3976] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1723614183.592333][3974:3976] CHIP:TS: Retaining current Last Known Good Time [1723614183.599256][3974:3976] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1723614183.601009][3974:3976] CHIP:TS: Committing Last Known Good Time to storage: 2023-10-03T16:51:18 [1723614183.602969][3974:3976] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 75E566932C31CC39) [1723614183.603024][3974:3976] CHIP:IN: UDP::Init bind&listen port=5550 [1723614183.603150][3974:3976] CHIP:IN: UDP::Init bound to port=5550 [1723614183.603174][3974:3976] CHIP:IN: TransportMgr initialized [1723614183.616993][3974:3976] CHIP:TOO: Sending command to node 0x1 [1723614183.617436][3974:3976] CHIP:CSM: FindOrEstablishSession: PeerId = [1:0000000000000001] [1723614183.617464][3974:3976] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found [1723614183.617496][3974:3976] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 1 --> 2 [1723614183.617526][3974:3976] CHIP:DIS: Resolving 75E566932C31CC39:0000000000000001 ... [1723614183.619042][3974:3976] CHIP:DL: Avahi resolve found [1723614183.619101][3974:3976] CHIP:DIS: Node ID resolved for 75E566932C31CC39:0000000000000001 [1723614183.619120][3974:3976] CHIP:DIS: Hostname: 94E6863DAC24 [1723614183.619142][3974:3976] CHIP:DIS: IP Address #1: fe80::96e6:86ff:fe3d:ac24 [1723614183.619161][3974:3976] CHIP:DIS: Port: 5540 [1723614183.619179][3974:3976] CHIP:DIS: Mrp Interval idle: not present [1723614183.619196][3974:3976] CHIP:DIS: Mrp Interval active: not present [1723614183.619213][3974:3976] CHIP:DIS: TCP Supported: 0 [1723614183.619369][3974:3976] CHIP:DIS: UDP:[fe80::96e6:86ff:fe3d:ac24%eth0]:5540: new best score: 7 [1723614183.619392][3974:3976] CHIP:DIS: Checking node lookup status after 2 ms [1723614183.619409][3974:3976] CHIP:DIS: Keeping DNSSD lookup active [1723614183.817670][3974:3976] CHIP:DIS: Checking node lookup status after 200 ms [1723614183.817987][3974:3976] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: Updating device address to UDP:[fe80::96e6:86ff:fe3d:ac24%eth0]:5540 while in state 2 [1723614183.818051][3974:3976] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 2 --> 3 [1723614183.818357][3974:3976] CHIP:IN: SecureSession[0xffff9000ae70]: Allocated Type:2 LSID:5522 [1723614183.818454][3974:3976] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1723614183.820687][3974:3976] CHIP:EM: <<< [E:39443i S:0 M:34339538] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::96e6:86ff:fe3d:ac24%eth0]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) [1723614183.821078][3974:3976] CHIP:SC: Sent Sigma1 msg [1723614183.821151][3974:3976] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 3 --> 4 [1723614183.880007][3974:3976] CHIP:EM: >>> [E:39443i S:0 M:148000818 (Ack:34339538)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) [1723614183.880100][3974:3976] CHIP:EM: Found matching exchange: 39443i, Delegate: 0xffff9000dd18 [1723614183.880180][3974:3976] CHIP:EM: Rxd Ack; Removing MessageCounter:34339538 from Retrans Table on exchange 39443i [1723614184.394758][3974:3976] CHIP:EM: >>> [E:39443i S:0 M:148000819 (Ack:34339538)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) [1723614184.394861][3974:3976] CHIP:EM: Found matching exchange: 39443i, Delegate: 0xffff9000dd18 [1723614184.394922][3974:3976] CHIP:EM: CHIP MessageCounter:34339538 not in RetransTable on exchange 39443i [1723614184.395024][3974:3976] CHIP:SC: Received Sigma2 msg [1723614184.395108][3974:3976] CHIP:SC: Peer assigned session session ID 21211 [1723614184.403948][3974:3976] CHIP:SC: Found MRP parameters in the message [1723614184.404062][3974:3976] CHIP:SC: Sending Sigma3 [1723614184.405354][3974:3976] CHIP:EM: <<< [E:39443i S:0 M:34339539 (Ack:148000819)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::96e6:86ff:fe3d:ac24%eth0]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) [1723614184.405657][3974:3976] CHIP:SC: Sent Sigma3 msg [1723614184.414796][3974:3976] CHIP:EM: >>> [E:39443i S:0 M:148000820 (Ack:34339539)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) [1723614184.414827][3974:3976] CHIP:EM: Found matching exchange: 39443i, Delegate: 0xffff9000dd18 [1723614184.414856][3974:3976] CHIP:EM: Rxd Ack; Removing MessageCounter:34339539 from Retrans Table on exchange 39443i [1723614185.628623][3974:3976] CHIP:EM: >>> [E:39443i S:0 M:148000822 (Ack:34339539)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1723614185.628699][3974:3976] CHIP:EM: Found matching exchange: 39443i, Delegate: 0xffff9000dd18 [1723614185.628787][3974:3976] CHIP:EM: CHIP MessageCounter:34339539 not in RetransTable on exchange 39443i [1723614185.628860][3974:3976] CHIP:SC: Success status report received. Session was established [1723614185.635080][3974:3976] CHIP:SC: SecureSession[0xffff9000ae70, LSID:5522]: State change 'kEstablishing' --> 'kActive' [1723614185.635151][3974:3976] CHIP:IN: SecureSession[0xffff9000ae70]: Activated - Type:2 LSID:5522 [1723614185.635169][3974:3976] CHIP:IN: New secure session activated for device <0000000000000001, 1>, LSID:5522 PSID:21211! [1723614185.635197][3974:3976] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 4 --> 5 [1723614185.635307][3974:3976] CHIP:TOO: Sending ReadAttribute to: [1723614185.635408][3974:3976] CHIP:TOO: cluster 0x0000_0031, attribute: 0x0000_0008, endpoint 0 [1723614185.635450][3974:3976] CHIP:DMG: SendReadRequest ReadClient[0xffff9000a0f0]: Sending Read Request [1723614185.635928][3974:3976] CHIP:EM: <<< [E:39444i S:5522 M:131763878] (S) Msg TX to 1:0000000000000001 [CC39] [UDP:[fe80::96e6:86ff:fe3d:ac24%eth0]:5540] --- Type 0001:02 (IM:ReadRequest) [1723614185.636165][3974:3976] CHIP:DMG: MoveToState ReadClient[0xffff9000a0f0]: Moving to [AwaitingIn] [1723614185.636369][3974:3976] CHIP:EM: <<< [E:39443i S:0 M:34339540 (Ack:148000822)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::96e6:86ff:fe3d:ac24%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) [1723614185.636492][3974:3976] CHIP:EM: Flushed pending ack for MessageCounter:148000822 on exchange 39443i [1723614185.652620][3974:3976] CHIP:EM: >>> [E:39444i S:5522 M:219311066 (Ack:131763878)] (S) Msg RX from 1:0000000000000001 [CC39] --- Type 0001:05 (IM:ReportData) [1723614185.652663][3974:3976] CHIP:EM: Found matching exchange: 39444i, Delegate: 0xffff9000a100 [1723614185.652700][3974:3976] CHIP:EM: Rxd Ack; Removing MessageCounter:131763878 from Retrans Table on exchange 39444i [1723614185.652773][3974:3976] CHIP:DMG: ReportDataMessage = [1723614185.652822][3974:3976] CHIP:DMG: { [1723614185.652864][3974:3976] CHIP:DMG: AttributeReportIBs = [1723614185.652929][3974:3976] CHIP:DMG: [ [1723614185.652977][3974:3976] CHIP:DMG: AttributeReportIB = [1723614185.653045][3974:3976] CHIP:DMG: { [1723614185.653096][3974:3976] CHIP:DMG: AttributeDataIB = [1723614185.653157][3974:3976] CHIP:DMG: { [1723614185.653217][3974:3976] CHIP:DMG: DataVersion = 0x734e705e, [1723614185.653291][3974:3976] CHIP:DMG: AttributePathIB = [1723614185.653355][3974:3976] CHIP:DMG: { [1723614185.653416][3974:3976] CHIP:DMG: Endpoint = 0x0, [1723614185.653485][3974:3976] CHIP:DMG: Cluster = 0x31, [1723614185.653554][3974:3976] CHIP:DMG: Attribute = 0x0000_0008, [1723614185.653646][3974:3976] CHIP:DMG: } [1723614185.653717][3974:3976] CHIP:DMG: [1723614185.653780][3974:3976] CHIP:DMG: Data = [ [1723614185.653894][3974:3976] CHIP:DMG: 0, [1723614185.653964][3974:3976] CHIP:DMG: ], [1723614185.654024][3974:3976] CHIP:DMG: }, [1723614185.654091][3974:3976] CHIP:DMG: [1723614185.654142][3974:3976] CHIP:DMG: }, [1723614185.654205][3974:3976] CHIP:DMG: [1723614185.654252][3974:3976] CHIP:DMG: ], [1723614185.654313][3974:3976] CHIP:DMG: [1723614185.654363][3974:3976] CHIP:DMG: SuppressResponse = true, [1723614185.654412][3974:3976] CHIP:DMG: InteractionModelRevision = 11 [1723614185.654461][3974:3976] CHIP:DMG: } [1723614185.654802][3974:3976] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0031 Attribute 0x0000_0008 DataVersion: 1934520414 [1723614185.654883][3974:3976] CHIP:TOO: Don't know how to log atribute value [1723614185.655341][3974:3976] CHIP:EM: <<< [E:39444i S:5522 M:131763879 (Ack:219311066)] (S) Msg TX to 1:0000000000000001 [CC39] [UDP:[fe80::96e6:86ff:fe3d:ac24%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) [1723614185.655561][3974:3976] CHIP:EM: Flushed pending ack for MessageCounter:219311066 on exchange 39444i [1723614185.655884][3974:3974] CHIP:CTL: Shutting down the commissioner [1723614185.656048][3974:3974] CHIP:CTL: Shutting down the controller [1723614185.656167][3974:3974] CHIP:IN: Expiring all sessions for fabric 0x1!! [1723614185.656215][3974:3974] CHIP:IN: SecureSession[0xffff9000ae70]: MarkForEviction Type:2 LSID:5522 [1723614185.656260][3974:3974] CHIP:SC: SecureSession[0xffff9000ae70, LSID:5522]: State change 'kActive' --> 'kPendingEviction' [1723614185.656325][3974:3974] CHIP:IN: SecureSession[0xffff9000ae70]: Released - Type:2 LSID:5522 [1723614185.656374][3974:3974] CHIP:FP: Forgetting fabric 0x1 [1723614185.656433][3974:3974] CHIP:TS: Pending Last Known Good Time: 2023-10-03T16:51:18 [1723614185.656730][3974:3974] CHIP:TS: Previous Last Known Good Time: 2023-10-03T16:51:18 [1723614185.656803][3974:3974] CHIP:TS: Reverted Last Known Good Time to previous value [1723614185.656882][3974:3974] CHIP:CTL: Shutting down the commissioner [1723614185.657041][3974:3974] CHIP:CTL: Shutting down the controller [1723614185.657088][3974:3974] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1723614185.657370][3974:3974] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1723614185.657576][3974:3974] CHIP:FP: Shutting down FabricTable [1723614185.657635][3974:3974] CHIP:TS: Pending Last Known Good Time: 2023-10-03T16:51:18 [1723614185.657869][3974:3974] CHIP:TS: Previous Last Known Good Time: 2023-10-03T16:51:18 [1723614185.658013][3974:3974] CHIP:TS: Reverted Last Known Good Time to previous value [1723614185.658396][3974:3974] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-1XEFUd) [1723614185.659572][3974:3974] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1723614185.659693][3974:3974] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1723614185.659744][3974:3974] CHIP:DL: Inet Layer shutdown [1723614185.659786][3974:3974] CHIP:DL: BLE shutdown [1723614185.659831][3974:3974] CHIP:DL: System Layer shutdown ubuntu@ubuntu:~/apps$ ./chip-tool networkcommissioning read supported-wi-fi-bands 1 0 [1723614597.739589][4030:4030] CHIP:TOO: Unknown attribute: supported-wi-fi-bands Usage: ./chip-tool networkcommissioning read attribute-name [param1 param2 ...]

+-------------------------------------------------------------------------------------+ | Attributes: | +-------------------------------------------------------------------------------------+ | max-networks | | networks | | scan-max-time-seconds | | connect-max-time-seconds | | interface-enabled | | last-networking-status | | last-network-id | | last-connect-error-value | | generated-command-list | | accepted-command-list | | event-list | | attribute-list | | feature-map | | cluster-revision | +-------------------------------------------------------------------------------------+ [1723614597.739948][4030:4030] CHIP:TOO: Run command failure: ../../examples/chip-tool/commands/common/Commands.cpp:293: Error 0x0000002F

PICS file

No response

Screenshots

No response

Environment

TH Version: 2.10+spring2024, Sha: 5f28838 connectedhomeip/chip-cert-bins 11f94c383171b709712106362e902f65253f4968 41c4b47861c8 3 months ago 4.37GB

Additional Information

No response

rquidute commented 1 month ago

HI @cecille could you please take a look on this?