blob: 2617632cfbb62c1e5e0b6ac34c824f7fb44519d7 [file] [log] [blame]
# Copyright (c) 2021 Project CHIP Authors
#
# Licensed under the Apache License, Version 2.0 (the "License");
# you may not use this file except in compliance with the License.
# You may obtain a copy of the License at
#
# http://www.apache.org/licenses/LICENSE-2.0
#
# Unless required by applicable law or agreed to in writing, software
# distributed under the License is distributed on an "AS IS" BASIS,
# WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
# See the License for the specific language governing permissions and
# limitations under the License.
# Auto-generated scripts for harness use only, please review before automation. The endpoints and cluster names are currently set to default
name: 42.1.2. [TC-ACE-1.2] Subscriptions - REMOVED
config:
nodeId: 0x12344321
cluster: "Basic"
endpoint: 0
tests:
- label: "Note"
verification: |
Please use Interactive mode to Verify subscription test cases
Here the command to enter interactive mode:-- ./chip-tool interactive start
disabled: true
- label: "Pre-conditions"
verification: |
N1 is the node ID of TH1
disabled: true
- label: "TH1 commissions DUT"
verification: |
DUT side:
sudo ./chip-all-clusters-app
TH side:
./chip-tool pairing ethernet 1 20202021 chiip5 matter123 3840 5540
[1650455358.501816][4366:4371] CHIP:TOO: Device commissioning completed with success
disabled: true
- label:
"TH1 writes AccessControl cluster ACL attribute, value is a list of
AccessControlEntryStruct containing 1 element: struct Privilege field:
Administer (5) AuthMode field: CASE (2) Subjects field: [N1, N2]
Targets field: [{Endpoint: 0}]"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
accesscontrol write acl '[{"fabricIndex": 1, "privilege": 5, "authMode": 2, "subjects": [112233], "targets": [{ "cluster": null, "endpoint": 0, "deviceType": null }]}]' 1 0
On TH(Chiptool)1,Verify successfully ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659508250.468539][10228:10233] CHIP:EM: Removed CHIP MessageCounter:188603873 from RetransTable on exchange 14900i
[1659508250.468582][10228:10233] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659508250.468646][10228:10233] CHIP:DMG: WriteResponseMessage =
[1659508250.468679][10228:10233] CHIP:DMG: {
[1659508250.468706][10228:10233] CHIP:DMG: AttributeStatusIBs =
[1659508250.468745][10228:10233] CHIP:DMG: [
[1659508250.468775][10228:10233] CHIP:DMG: AttributeStatusIB =
[1659508250.468809][10228:10233] CHIP:DMG: {
[1659508250.468841][10228:10233] CHIP:DMG: AttributePathIB =
[1659508250.468881][10228:10233] CHIP:DMG: {
[1659508250.468922][10228:10233] CHIP:DMG: Endpoint = 0x0,
[1659508250.468964][10228:10233] CHIP:DMG: Cluster = 0x1f,
[1659508250.469005][10228:10233] CHIP:DMG: Attribute = 0x0000_0000,
[1659508250.469044][10228:10233] CHIP:DMG: }
[1659508250.469087][10228:10233] CHIP:DMG:
[1659508250.469124][10228:10233] CHIP:DMG: StatusIB =
[1659508250.469163][10228:10233] CHIP:DMG: {
[1659508250.469202][10228:10233] CHIP:DMG: status = 0x00 (SUCCESS),
[1659508250.469240][10228:10233] CHIP:DMG: },
[1659508250.469279][10228:10233] CHIP:DMG:
[1659508250.469310][10228:10233] CHIP:DMG: },
[1659508250.469354][10228:10233] CHIP:DMG:
[1659508250.469384][10228:10233] CHIP:DMG: AttributeStatusIB =
[1659508250.469417][10228:10233] CHIP:DMG: {
[1659508250.469448][10228:10233] CHIP:DMG: AttributePathIB =
[1659508250.469485][10228:10233] CHIP:DMG: {
[1659508250.469523][10228:10233] CHIP:DMG: Endpoint = 0x0,
[1659508250.469564][10228:10233] CHIP:DMG: Cluster = 0x1f,
[1659508250.469604][10228:10233] CHIP:DMG: Attribute = 0x0000_0000,
[1659508250.469644][10228:10233] CHIP:DMG: ListIndex = Null,
[1659508250.469682][10228:10233] CHIP:DMG: }
[1659508250.469723][10228:10233] CHIP:DMG:
[1659508250.469759][10228:10233] CHIP:DMG: StatusIB =
[1659508250.469796][10228:10233] CHIP:DMG: {
[1659508250.469834][10228:10233] CHIP:DMG: status = 0x00 (SUCCESS),
[1659508250.469873][10228:10233] CHIP:DMG: },
[1659508250.469912][10228:10233] CHIP:DMG:
[1659508250.469943][10228:10233] CHIP:DMG: },
[1659508250.469979][10228:10233] CHIP:DMG:
[1659508250.470007][10228:10233] CHIP:DMG: ],
[1659508250.470048][10228:10233] CHIP:DMG:
[1659508250.470077][10228:10233] CHIP:DMG: InteractionModelRevision = 1
[1659508250.470105][10228:10233] CHIP:DMG: }
[1659508250.470228][10228:10233] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659508250.470299][10228:10233] CHIP:EM: Sending Standalone Ack for MessageCounter:48929405 on exchange 14900i
[1659508250.470380][10228:10233] CHIP:IN: Prepared secure message 0xffff994999e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 14900i with MessageCounter:188603874.
[1659508250.470426][10228:10233] CHIP:IN: Sending encrypted msg 0xffff994999e8 with MessageCounter:188603874 to 0x0000000000000001 (1) at monotonic time: 000000000598DDBE msec
[1659508250.470575][10228:10233] CHIP:EM: Flushed pending ack for MessageCounter:48929405 on exchange 14900i
[1659508250.470779][10228:10228] CHIP:CTL: Shutting down the commissioner
[1659508250.470818][10228:10228] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1659508250.470842][10228:10228] CHIP:CTL: Shutting down the controller
[1659508250.470874][10228:10228] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1659508250.470904][10228:10228] CHIP:IN: SecureSession[0xffff84001930]: MarkForEviction Type:2 LSID:16459
[1659508250.470931][10228:10228] CHIP:SC: SecureSession[0xffff84001930]: Moving from state "kActive" --> "kPendingEviction"
[1659508250.470959][10228:10228] CHIP:IN: SecureSession[0xffff84001930]: Released - Type:2 LSID:16459
[1659508250.470990][10228:10228] CHIP:FP: Forgetting fabric 0x1
[1659508250.471029][10228:10228] CHIP:TS: Pending Last Known Good Time: 2022-08-01T09:44:35
[1659508250.471248][10228:10228] CHIP:TS: Previous Last Known Good Time: 2022-08-01T09:44:35
[1659508250.471279][10228:10228] CHIP:TS: Reverted Last Known Good Time to previous value
[1659508250.471327][10228:10228] CHIP:CTL: Shutting down the commissioner
disabled: true
- label:
"TH2 subscribes to the Node Operational Credentials cluster NOCs
attribute, min interval 1, max interval 30, keep subscriptions false,
fabric-filtered false."
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
operationalcredentials subscribe nocs 1 30 2 0 --fabric-filtered false --keepSubscriptions 0
On TH(Chiptool)2,Verify TH subscribes to the Node Operational Credentials cluster NOCs attribute, min interval 1, max interval 30, keep subscriptions false, fabric-filtered false and save SubscriptionId as subscription_noc
[1659598415.554714][3022:3027] CHIP:DMG: ReportDataMessage =
[1659598415.554746][3022:3027] CHIP:DMG: {
[1659598415.554771][3022:3027] CHIP:DMG: SubscriptionId = 0x692ae42d,
[1659598415.554821][3022:3027] CHIP:DMG: AttributeReportIBs =
[1659598415.554857][3022:3027] CHIP:DMG: [
[1659598415.554883][3022:3027] CHIP:DMG: AttributeReportIB =
[1659598415.554920][3022:3027] CHIP:DMG: {
[1659598415.554948][3022:3027] CHIP:DMG: AttributeDataIB =
[1659598415.554981][3022:3027] CHIP:DMG: {
[1659598415.555012][3022:3027] CHIP:DMG: DataVersion = 0xc0e77b57,
[1659598415.555045][3022:3027] CHIP:DMG: AttributePathIB =
[1659598415.555076][3022:3027] CHIP:DMG: {
[1659598415.555111][3022:3027] CHIP:DMG: Endpoint = 0x0,
[1659598415.555146][3022:3027] CHIP:DMG: Cluster = 0x3e,
[1659598415.555182][3022:3027] CHIP:DMG: Attribute = 0x0000_0000,
[1659598415.555215][3022:3027] CHIP:DMG: }
[1659598415.555251][3022:3027] CHIP:DMG:
[1659598415.555284][3022:3027] CHIP:DMG: Data = [
[1659598415.555317][3022:3027] CHIP:DMG:
[1659598415.555352][3022:3027] CHIP:DMG: ],
[1659598415.555380][3022:3027] CHIP:DMG: },
[1659598415.555415][3022:3027] CHIP:DMG:
[1659598415.555441][3022:3027] CHIP:DMG: },
[1659598415.555481][3022:3027] CHIP:DMG:
[1659598415.555507][3022:3027] CHIP:DMG: AttributeReportIB =
[1659598415.555542][3022:3027] CHIP:DMG: {
[1659598415.555570][3022:3027] CHIP:DMG: AttributeDataIB =
[1659598415.555630][3022:3027] CHIP:DMG: {
[1659598415.555667][3022:3027] CHIP:DMG: DataVersion = 0xc0e77b57,
[1659598415.555696][3022:3027] CHIP:DMG: AttributePathIB =
[1659598415.555729][3022:3027] CHIP:DMG: {
[1659598415.555763][3022:3027] CHIP:DMG: Endpoint = 0x0,
[1659598415.555799][3022:3027] CHIP:DMG: Cluster = 0x3e,
[1659598415.555834][3022:3027] CHIP:DMG: Attribute = 0x0000_0000,
[1659598415.555868][3022:3027] CHIP:DMG: ListIndex = Null,
[1659598415.555901][3022:3027] CHIP:DMG: }
[1659598415.555936][3022:3027] CHIP:DMG:
[1659598415.555968][3022:3027] CHIP:DMG: Data =
[1659598415.556026][3022:3027] CHIP:DMG: {
[1659598415.556065][3022:3027] CHIP:DMG: 0xfe = 1,
[1659598415.556100][3022:3027] CHIP:DMG: },
[1659598415.556128][3022:3027] CHIP:DMG: },
[1659598415.556163][3022:3027] CHIP:DMG:
[1659598415.556189][3022:3027] CHIP:DMG: },
[1659598415.556228][3022:3027] CHIP:DMG:
[1659598415.556253][3022:3027] CHIP:DMG: AttributeReportIB =
[1659598415.556286][3022:3027] CHIP:DMG: {
[1659598415.556312][3022:3027] CHIP:DMG: AttributeDataIB =
[1659598415.556343][3022:3027] CHIP:DMG: {
[1659598415.556376][3022:3027] CHIP:DMG: DataVersion = 0xc0e77b57,
[1659598415.556407][3022:3027] CHIP:DMG: AttributePathIB =
[1659598415.556439][3022:3027] CHIP:DMG: {
[1659598415.556472][3022:3027] CHIP:DMG: Endpoint = 0x0,
[1659598415.556507][3022:3027] CHIP:DMG: Cluster = 0x3e,
[1659598415.556542][3022:3027] CHIP:DMG: Attribute = 0x0000_0000,
[1659598415.556576][3022:3027] CHIP:DMG: ListIndex = Null,
[1659598415.556640][3022:3027] CHIP:DMG: }
[1659598415.556678][3022:3027] CHIP:DMG:
[1659598415.556713][3022:3027] CHIP:DMG: Data =
[1659598415.556746][3022:3027] CHIP:DMG: {
[1659598415.556781][3022:3027] CHIP:DMG: 0x1 = [
[1659598415.556889][3022:3027] CHIP:DMG: 0x15, 0x30, 0x1, 0x1, 0x1, 0x24, 0x2, 0x1, 0x37, 0x3, 0x24, 0x13, 0x1, 0x18, 0x26, 0x4, 0x80, 0x22, 0x81, 0x27, 0x26, 0x5, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x6, 0x24, 0x15, 0x1, 0x24, 0x11, 0x2, 0x18, 0x24, 0x7, 0x1, 0x24, 0x8, 0x1, 0x30, 0x9, 0x41, 0x4, 0x8f, 0x5c, 0x62, 0xe1, 0x1e, 0xbe, 0x31, 0xab, 0xc3, 0xaa, 0x8, 0x98, 0x87, 0xc9, 0x26, 0x99, 0x98, 0x9a, 0xa2, 0x7c, 0xa, 0xfa, 0xa, 0xb1, 0x44, 0x6f, 0x5e, 0x78, 0x26, 0x74, 0xb1, 0x2a, 0xfe, 0x16, 0xc5, 0x7c, 0x24, 0x14, 0xce, 0x0, 0xb6, 0xe0, 0xdc, 0x96, 0x91, 0x60, 0x8c, 0xd2, 0xdb, 0xca, 0x9f, 0x8f, 0x2d, 0x7, 0xcd, 0x89, 0x9, 0xbb, 0xe3, 0x9a, 0x22, 0x57, 0xb6, 0x3e, 0x37, 0xa, 0x35, 0x1, 0x28, 0x1, 0x18, 0x24, 0x2, 0x1, 0x36, 0x3, 0x4, 0x2, 0x4, 0x1, 0x18, 0x30, 0x4, 0x14, 0xe1, 0x31, 0xf4, 0x95, 0xda, 0x55, 0x40, 0x59, 0xd4, 0xb4, 0x6d, 0x62, 0xbf, 0x7e, 0xab, 0xef, 0x88, 0x3c, 0x84, 0x3, 0x30, 0x5, 0x14, 0xc7, 0xe9, 0x58, 0xd6, 0x86, 0xe, 0x58, 0xeb, 0xff, 0xc5, 0x3, 0x75, 0x69, 0x5f, 0xa1, 0xd, 0x8d, 0x1f, 0x7b, 0xa5, 0x18, 0x30, 0xb, 0x40, 0x89, 0x44, 0xb0, 0x49, 0x79, 0x3f, 0x7, 0x29, 0xe5, 0xd9, 0xee, 0xeb, 0x6f, 0x18, 0xcf, 0x20, 0xb5, 0x63, 0x97, 0xef, 0xb4, 0x2, 0xc2, 0x73, 0xba, 0x17, 0xbe, 0x9, 0xcc, 0xcd, 0x4d, 0xd0, 0x6d, 0x6d, 0xd, 0x33, 0xf, 0xd7, 0xe9, 0x15, 0x16, 0x82, 0x79, 0x3d, 0x69, 0x38, 0x3d, 0x82, 0xbd, 0x7f, 0x17, 0x22, 0x1b, 0xc3, 0xf6, 0xc7, 0xe6, 0x8, 0x4c, 0x7b, 0xab, 0x39, 0x5c, 0xa0, 0x18,
[1659598415.556962][3022:3027] CHIP:DMG: ] (241 bytes)
[1659598415.557011][3022:3027] CHIP:DMG: 0x2 = [
[1659598415.557121][3022:3027] CHIP:DMG: 0x15, 0x30, 0x1, 0x1, 0x0, 0x24, 0x2, 0x1, 0x37, 0x3, 0x24, 0x14, 0x0, 0x18, 0x26, 0x4, 0x80, 0x22, 0x81, 0x27, 0x26, 0x5, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x6, 0x24, 0x13, 0x1, 0x18, 0x24, 0x7, 0x1, 0x24, 0x8, 0x1, 0x30, 0x9, 0x41, 0x4, 0xb2, 0x61, 0xc9, 0x38, 0xb9, 0x32, 0x73, 0xcc, 0x48, 0x95, 0x79, 0x1, 0x93, 0xd5, 0xa1, 0x54, 0x52, 0x9a, 0x3, 0x9d, 0x23, 0xe5, 0x52, 0x26, 0x3b, 0x7a, 0xa9, 0x4a, 0x8f, 0x34, 0x44, 0x81, 0x30, 0xe7, 0xa4, 0xc8, 0x8e, 0xe7, 0x1a, 0xe2, 0x91, 0xbb, 0xc6, 0x89, 0xe9, 0xb7, 0xd1, 0xf8, 0xc8, 0x1f, 0xe3, 0xfe, 0x8c, 0x5d, 0xad, 0xff, 0x98, 0x66, 0x5d, 0xbf, 0x57, 0xd0, 0x73, 0x9e, 0x37, 0xa, 0x35, 0x1, 0x29, 0x1, 0x18, 0x24, 0x2, 0x60, 0x30, 0x4, 0x14, 0xc7, 0xe9, 0x58, 0xd6, 0x86, 0xe, 0x58, 0xeb, 0xff, 0xc5, 0x3, 0x75, 0x69, 0x5f, 0xa1, 0xd, 0x8d, 0x1f, 0x7b, 0xa5, 0x30, 0x5, 0x14, 0xf6, 0xef, 0x1d, 0x69, 0xa5, 0xf7, 0x79, 0x7d, 0x53, 0x4a, 0xb6, 0x55, 0xe8, 0xe8, 0xbf, 0xa3, 0x90, 0xcb, 0x56, 0x20, 0x18, 0x30, 0xb, 0x40, 0xf2, 0xb2, 0xe6, 0xa0, 0xc2, 0xf, 0x81, 0x9d, 0xaa, 0x4d, 0xa6, 0xc5, 0xc9, 0x4b, 0x1f, 0x63, 0x68, 0x6c, 0x3e, 0x11, 0xbe, 0xd7, 0xfe, 0xf4, 0xe3, 0x56, 0x43, 0xe7, 0x95, 0xff, 0xa3, 0xae, 0xe4, 0x33, 0x3a, 0xd7, 0x26, 0xee, 0x1e, 0xc4, 0x92, 0xa2, 0x9a, 0x45, 0x62, 0x8e, 0xe9, 0x5d, 0x88, 0xcc, 0xba, 0xb0, 0x78, 0x6, 0xb, 0xfd, 0x56, 0x5c, 0xa5, 0x22, 0x98, 0xa9, 0xb0, 0xaf, 0x18,
[1659598415.557195][3022:3027] CHIP:DMG: ] (231 bytes)
[1659598415.557234][3022:3027] CHIP:DMG: 0xfe = 2,
[1659598415.557268][3022:3027] CHIP:DMG: },
[1659598415.557299][3022:3027] CHIP:DMG: },
[1659598415.557335][3022:3027] CHIP:DMG:
[1659598415.557361][3022:3027] CHIP:DMG: },
[1659598415.557393][3022:3027] CHIP:DMG:
[1659598415.557417][3022:3027] CHIP:DMG: ],
[1659598415.557458][3022:3027] CHIP:DMG:
[1659598415.557482][3022:3027] CHIP:DMG: InteractionModelRevision = 1
[1659598415.557505][3022:3027] CHIP:DMG: }
[1659598415.557850][3022:3027] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_003E Attribute 0x0000_0000 DataVersion: 3236395863
[1659598415.560482][3022:3027] CHIP:TOO: NOCs: 2 entries
[1659598415.560556][3022:3027] CHIP:TOO: [1]: {
[1659598415.560586][3022:3027] CHIP:TOO: Noc:
[1659598415.562570][3022:3027] CHIP:TOO: Icac: null
[1659598415.562663][3022:3027] CHIP:TOO: FabricIndex: 1
[1659598415.562690][3022:3027] CHIP:TOO: }
[1659598415.562741][3022:3027] CHIP:TOO: [2]: {
[1659598415.562781][3022:3027] CHIP:TOO: Noc: 1530010101240201370324130118260480228127260580254D3A370624150124110218240701240801300941048F5C62E11EBE31ABC3AA089887C92699989AA27C0AFA0AB1446F5E782674B12AFE16C57C2414CE00B6E0DC9691608CD2DBCA9F8F2D07CD8909BBE39A2257B63E370A350128011824020136030402040118300414E131F495DA554059D4B46D62BF7EABEF883C8403300514C7E958D6860E58EBFFC50375695FA10D8D1F7BA518300B408944B049793F0729E5D9EEEB6F18CF20B56397EFB402C273BA17BE09CCCD4DD06D6D0D330FD7E9151682793D69383D82BD7F17221BC3F6C7E6084C7BAB395CA018
[1659598415.562823][3022:3027] CHIP:TOO: Icac: 1530010100240201370324140018260480228127260580254D3A37062413011824070124080130094104B261C938B93273CC4895790193D5A154529A039D23E552263B7AA94A8F34448130E7A4C88EE71AE291BBC689E9B7D1F8C81FE3FE8C5DADFF98665DBF57D0739E370A3501290118240260300414C7E958D6860E58EBFFC50375695FA10D8D1F7BA5300514F6EF1D69A5F7797D534AB655E8E8BFA390CB562018300B40F2B2E6A0C20F819DAA4DA6C5C94B1F63686C3E11BED7FEF4E35643E795FFA3AEE4333AD726EE1EC492A29A45628EE95D88CCBAB078060BFD565CA52298A9B0AF18
[1659598415.562851][3022:3027] CHIP:TOO: FabricIndex: 2
[1659598415.562875][3022:3027] CHIP:TOO: }
[1659598415.562929][3022:3027] CHIP:DMG: MoveToState ReadClient[0xffff8c008e40]: Moving to [AwaitingSu]
[1659598415.563006][3022:3027] CHIP:EM: Piggybacking Ack for MessageCounter:207708623 on exchange: 29451i
[1659598415.563092][3022:3027] CHIP:IN: Prepared secure message 0xffff8c005878 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 29451i with MessageCounter:125509363.
[1659598415.563139][3022:3027] CHIP:IN: Sending encrypted msg 0xffff8c005878 with MessageCounter:125509363 to 0x0000000000000002 (1) at monotonic time: 0000000000820B0B msec
[1659598415.565134][3022:3027] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:207708624 on exchange 29451i
[1659598415.565183][3022:3027] CHIP:EM: Found matching exchange: 29451i, Delegate: 0xffff8c008e40
[1659598415.565222][3022:3027] CHIP:EM: Rxd Ack; Removing MessageCounter:125509363 from Retrans Table on exchange 29451i
[1659598415.565249][3022:3027] CHIP:EM: Removed CHIP MessageCounter:125509363 from RetransTable on exchange 29451i
[1659598415.565301][3022:3027] CHIP:DMG: SubscribeResponseMessage =
[1659598415.565330][3022:3027] CHIP:DMG: {
[1659598415.565355][3022:3027] CHIP:DMG: SubscriptionId = 0x692ae42d,
[1659598415.565388][3022:3027] CHIP:DMG: MaxInterval = 0x1e,
[1659598415.565414][3022:3027] CHIP:DMG: InteractionModelRevision = 1
[1659598415.565438][3022:3027] CHIP:DMG: }
[1659598415.565467][3022:3027] CHIP:DMG: Subscription established with SubscriptionID = 0x692ae42d MinInterval = 1s MaxInterval = 30s Peer = 01:0000000000000002
[1659598415.565496][3022:3027] CHIP:DMG: MoveToState ReadClient[0xffff8c008e40]: Moving to [Subscripti]
[1659598415.565552][3022:3027] CHIP:DMG: Refresh LivenessCheckTime for 55000 milliseconds with SubscriptionId = 0x692ae42d Peer = 01:0000000000000002
[1659598415.565587][3022:3027] CHIP:EM: Sending Standalone Ack for MessageCounter:207708624 on exchange 29451i
[1659598415.565652][3022:3027] CHIP:IN: Prepared secure message 0xffffa0dbb9e8 to 0x0000000000000002 (1) of type 0x10 and protocolId (0, 0) on exchange 29451i with MessageCounter:125509364.
[1659598415.565690][3022:3027] CHIP:IN: Sending encrypted msg 0xffffa0dbb9e8 with MessageCounter:125509364 to 0x0000000000000002 (1) at monotonic time: 0000000000820B0E msec
[1659598415.565811][3022:3027] CHIP:EM: Flushed pending ack for MessageCounter:207708624 on exchange 29451i
disabled: true
- label:
"TH2 subscribes to the Access Control cluster
AccessControlEntryChanged event, min interval 1, max interval 30, keep
subscriptions true"
verification: |
Pls run this test in chip tool interactive mode ./chip-tool interactive start
accesscontrol subscribe-event access-control-entry-changed 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify TH subscribes to the Access Control cluster AccessControlEntryChanged event, min interval 1, max interval 30, keep subscriptions true and save SubscriptionId as subscription_ace
[1659598469.304212][3022:3027] CHIP:EM: Found matching exchange: 29452i, Delegate: 0xffff8c008f30
[1659598469.304246][3022:3027] CHIP:EM: Rxd Ack; Removing MessageCounter:125509366 from Retrans Table on exchange 29452i
[1659598469.304271][3022:3027] CHIP:EM: Removed CHIP MessageCounter:125509366 from RetransTable on exchange 29452i
[1659598469.304334][3022:3027] CHIP:DMG: ReportDataMessage =
[1659598469.304362][3022:3027] CHIP:DMG: {
[1659598469.304386][3022:3027] CHIP:DMG: SubscriptionId = 0xe15e6f41,
[1659598469.304411][3022:3027] CHIP:DMG: EventReportIBs =
[1659598469.304447][3022:3027] CHIP:DMG: [
[1659598469.304471][3022:3027] CHIP:DMG: EventReportIB =
[1659598469.304509][3022:3027] CHIP:DMG: {
[1659598469.304534][3022:3027] CHIP:DMG: EventDataIB =
[1659598469.304566][3022:3027] CHIP:DMG: {
[1659598469.304595][3022:3027] CHIP:DMG: EventPath =
[1659598469.304626][3022:3027] CHIP:DMG: {
[1659598469.304657][3022:3027] CHIP:DMG: Endpoint = 0x0,
[1659598469.304692][3022:3027] CHIP:DMG: Cluster = 0x1f,
[1659598469.304726][3022:3027] CHIP:DMG: Event = 0x0,
[1659598469.304758][3022:3027] CHIP:DMG: },
[1659598469.304792][3022:3027] CHIP:DMG:
[1659598469.304824][3022:3027] CHIP:DMG: EventNumber = 0x4,
[1659598469.304857][3022:3027] CHIP:DMG: PriorityLevel = 0x1,
[1659598469.304891][3022:3027] CHIP:DMG: SystemTimestamp = 0x814318,
[1659598469.304920][3022:3027] CHIP:DMG: EventData =
[1659598469.304952][3022:3027] CHIP:DMG: {
[1659598469.304983][3022:3027] CHIP:DMG: 0x1 = NULL
[1659598469.305018][3022:3027] CHIP:DMG: 0x2 = 0,
[1659598469.305049][3022:3027] CHIP:DMG: 0x3 = 1,
[1659598469.305082][3022:3027] CHIP:DMG: 0x4 =
[1659598469.305117][3022:3027] CHIP:DMG: {
[1659598469.305152][3022:3027] CHIP:DMG: 0x1 = 5,
[1659598469.305190][3022:3027] CHIP:DMG: 0x2 = 2,
[1659598469.305225][3022:3027] CHIP:DMG: 0x3 = [
[1659598469.305287][3022:3027] CHIP:DMG: 112233,
[1659598469.305329][3022:3027] CHIP:DMG: ],
[1659598469.305366][3022:3027] CHIP:DMG: 0x4 = NULL
[1659598469.305405][3022:3027] CHIP:DMG: 0xfe = 2,
[1659598469.305442][3022:3027] CHIP:DMG: },
[1659598469.305477][3022:3027] CHIP:DMG: 0xfe = 2,
[1659598469.305510][3022:3027] CHIP:DMG: },
[1659598469.305541][3022:3027] CHIP:DMG: },
[1659598469.305579][3022:3027] CHIP:DMG:
[1659598469.305605][3022:3027] CHIP:DMG: },
[1659598469.305640][3022:3027] CHIP:DMG:
[1659598469.305663][3022:3027] CHIP:DMG: ],
[1659598469.305698][3022:3027] CHIP:DMG:
[1659598469.305722][3022:3027] CHIP:DMG: InteractionModelRevision = 1
[1659598469.305745][3022:3027] CHIP:DMG: }
[1659598469.305889][3022:3027] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000
[1659598469.305914][3022:3027] CHIP:TOO: Event number: 4
[1659598469.305937][3022:3027] CHIP:TOO: Priority: Info
[1659598469.305958][3022:3027] CHIP:TOO: Timestamp: 8471320
[1659598469.306037][3022:3027] CHIP:TOO: AccessControlEntryChanged: {
[1659598469.306065][3022:3027] CHIP:TOO: AdminNodeID: null
[1659598469.306092][3022:3027] CHIP:TOO: AdminPasscodeID: 0
[1659598469.306115][3022:3027] CHIP:TOO: ChangeType: 1
[1659598469.306138][3022:3027] CHIP:TOO: LatestValue: {
[1659598469.306161][3022:3027] CHIP:TOO: Privilege: 5
[1659598469.306183][3022:3027] CHIP:TOO: AuthMode: 2
[1659598469.306211][3022:3027] CHIP:TOO: Subjects: 1 entries
[1659598469.306241][3022:3027] CHIP:TOO: [1]: 112233
[1659598469.306267][3022:3027] CHIP:TOO: Targets: null
[1659598469.306290][3022:3027] CHIP:TOO: FabricIndex: 2
[1659598469.306312][3022:3027] CHIP:TOO: }
[1659598469.306334][3022:3027] CHIP:TOO: AdminFabricIndex: 2
[1659598469.306356][3022:3027] CHIP:TOO: }
[1659598469.306416][3022:3027] CHIP:DMG: MoveToState ReadClient[0xffff8c008f30]: Moving to [AwaitingSu]
[1659598469.306469][3022:3027] CHIP:EM: Piggybacking Ack for MessageCounter:207708627 on exchange: 29452i
[1659598469.306528][3022:3027] CHIP:IN: Prepared secure message 0xffff8c002428 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 29452i with MessageCounter:125509367.
[1659598469.306564][3022:3027] CHIP:IN: Sending encrypted msg 0xffff8c002428 with MessageCounter:125509367 to 0x0000000000000002 (1) at monotonic time: 000000000082DCFA msec
[1659598469.307715][3022:3027] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:207708628 on exchange 29452i
[1659598469.307746][3022:3027] CHIP:EM: Found matching exchange: 29452i, Delegate: 0xffff8c008f30
[1659598469.307776][3022:3027] CHIP:EM: Rxd Ack; Removing MessageCounter:125509367 from Retrans Table on exchange 29452i
[1659598469.307800][3022:3027] CHIP:EM: Removed CHIP MessageCounter:125509367 from RetransTable on exchange 29452i
[1659598469.307841][3022:3027] CHIP:DMG: SubscribeResponseMessage =
disabled: true
- label:
"TH2 subscribes to the General Commissioning cluster Breadcrumb
attribute, min interval 1, max interval 30, keep subscriptions true"
verification: |
Pls run this test in chip tool interactive mode ./chip-tool interactive start
generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify TH subscribes to the General Commissioning cluster Breadcrumb attribute, min interval 1, max interval 30, keep subscriptions true and save SubscriptionId as subscription_breadcrumb
1659598523.725570][3022:3027] CHIP:EM: Found matching exchange: 29453i, Delegate: 0xffff8c008c80
[1659598523.725650][3022:3027] CHIP:EM: Rxd Ack; Removing MessageCounter:125509372 from Retrans Table on exchange 29453i
[1659598523.725713][3022:3027] CHIP:EM: Removed CHIP MessageCounter:125509372 from RetransTable on exchange 29453i
[1659598523.725833][3022:3027] CHIP:DMG: ReportDataMessage =
[1659598523.725902][3022:3027] CHIP:DMG: {
[1659598523.725962][3022:3027] CHIP:DMG: SubscriptionId = 0x81fefffb,
[1659598523.726025][3022:3027] CHIP:DMG: AttributeReportIBs =
[1659598523.726115][3022:3027] CHIP:DMG: [
[1659598523.726177][3022:3027] CHIP:DMG: AttributeReportIB =
[1659598523.726258][3022:3027] CHIP:DMG: {
[1659598523.726327][3022:3027] CHIP:DMG: AttributeDataIB =
[1659598523.726411][3022:3027] CHIP:DMG: {
[1659598523.726494][3022:3027] CHIP:DMG: DataVersion = 0x15d70b72,
[1659598523.726572][3022:3027] CHIP:DMG: AttributePathIB =
[1659598523.726662][3022:3027] CHIP:DMG: {
[1659598523.726754][3022:3027] CHIP:DMG: Endpoint = 0x0,
[1659598523.726849][3022:3027] CHIP:DMG: Cluster = 0x30,
[1659598523.726943][3022:3027] CHIP:DMG: Attribute = 0x0000_0000,
[1659598523.727027][3022:3027] CHIP:DMG: }
[1659598523.727120][3022:3027] CHIP:DMG:
[1659598523.727213][3022:3027] CHIP:DMG: Data = 0,
[1659598523.727298][3022:3027] CHIP:DMG: },
[1659598523.727381][3022:3027] CHIP:DMG:
[1659598523.727448][3022:3027] CHIP:DMG: },
[1659598523.727525][3022:3027] CHIP:DMG:
[1659598523.727585][3022:3027] CHIP:DMG: ],
[1659598523.727703][3022:3027] CHIP:DMG:
[1659598523.727766][3022:3027] CHIP:DMG: InteractionModelRevision = 1
[1659598523.727826][3022:3027] CHIP:DMG: }
[1659598523.728037][3022:3027] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 366414706
[1659598523.728118][3022:3027] CHIP:TOO: Breadcrumb: 0
[1659598523.728204][3022:3027] CHIP:DMG: MoveToState ReadClient[0xffff8c008c80]: Moving to [AwaitingSu]
[1659598523.728312][3022:3027] CHIP:EM: Piggybacking Ack for MessageCounter:207708635 on exchange: 29453i
[1659598523.728438][3022:3027] CHIP:IN: Prepared secure message 0xffff8c002428 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 29453i with MessageCounter:125509373.
[1659598523.728524][3022:3027] CHIP:IN: Sending encrypted msg 0xffff8c002428 with MessageCounter:125509373 to 0x0000000000000002 (1) at monotonic time: 000000000083B190 msec
[1659598523.730457][3022:3027] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:207708636 on exchange 29453i
[1659598523.730536][3022:3027] CHIP:EM: Found matching exchange: 29453i, Delegate: 0xffff8c008c80
[1659598523.730608][3022:3027] CHIP:EM: Rxd Ack; Removing MessageCounter:125509373 from Retrans Table on exchange 29453i
[1659598523.730669][3022:3027] CHIP:EM: Removed CHIP MessageCounter:125509373 from RetransTable on exchange
disabled: true
- label:
"TH1 sends the OpenCommissioningWindow command to the Administrator
Commissioning Cluster"
verification: |
Pls run this test in chip tool interactive mode ./chip-tool interactive start
pairing open-commissioning-window 1 1 400 1000 3840
[1659597971.528576][2984:2991] CHIP:EM: Found matching exchange: 62289i, Delegate: 0xffff840041a8
[1659597971.528620][2984:2991] CHIP:EM: Rxd Ack; Removing MessageCounter:205264756 from Retrans Table on exchange 62289i
[1659597971.528646][2984:2991] CHIP:EM: Removed CHIP MessageCounter:205264756 from RetransTable on exchange 62289i
[1659597971.528683][2984:2991] CHIP:DMG: ICR moving to [ResponseRe]
[1659597971.528737][2984:2991] CHIP:DMG: InvokeResponseMessage =
[1659597971.528763][2984:2991] CHIP:DMG: {
[1659597971.528787][2984:2991] CHIP:DMG: suppressResponse = false,
[1659597971.528813][2984:2991] CHIP:DMG: InvokeResponseIBs =
[1659597971.528844][2984:2991] CHIP:DMG: [
[1659597971.528868][2984:2991] CHIP:DMG: InvokeResponseIB =
[1659597971.528901][2984:2991] CHIP:DMG: {
[1659597971.528927][2984:2991] CHIP:DMG: CommandStatusIB =
[1659597971.528962][2984:2991] CHIP:DMG: {
[1659597971.528992][2984:2991] CHIP:DMG: CommandPathIB =
[1659597971.529030][2984:2991] CHIP:DMG: {
[1659597971.529067][2984:2991] CHIP:DMG: EndpointId = 0x0,
[1659597971.529106][2984:2991] CHIP:DMG: ClusterId = 0x3c,
[1659597971.529143][2984:2991] CHIP:DMG: CommandId = 0x0,
[1659597971.529179][2984:2991] CHIP:DMG: },
[1659597971.529217][2984:2991] CHIP:DMG:
[1659597971.529247][2984:2991] CHIP:DMG: StatusIB =
[1659597971.529284][2984:2991] CHIP:DMG: {
[1659597971.529321][2984:2991] CHIP:DMG: status = 0x00 (SUCCESS),
[1659597971.529357][2984:2991] CHIP:DMG: },
[1659597971.529393][2984:2991] CHIP:DMG:
[1659597971.529422][2984:2991] CHIP:DMG: },
[1659597971.529455][2984:2991] CHIP:DMG:
[1659597971.529479][2984:2991] CHIP:DMG: },
[1659597971.529510][2984:2991] CHIP:DMG:
[1659597971.529533][2984:2991] CHIP:DMG: ],
[1659597971.529563][2984:2991] CHIP:DMG:
[1659597971.529587][2984:2991] CHIP:DMG: InteractionModelRevision = 1
[1659597971.529610][2984:2991] CHIP:DMG: },
[1659597971.529670][2984:2991] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003C Command=0x0000_0000 Status=0x0
[1659597971.529700][2984:2991] CHIP:CTL: Successfully opened pairing window on the device
[1659597971.529845][2984:2991] CHIP:CTL: Manual pairing code: [35059712138]
[1659597971.529885][2984:2991] CHIP:CTL: SetupQRCode: [MT:-24J0AFN00--DE4-F00]
disabled: true
- label:
"TH3 fully commissions the DUT onto its fabric. Adding this new fabric
will cause a change in the NOCs attribute that will trigger the
subscription."
verification: |
ON TH2,
./chip-tool pairing open-commissioning-window 2 1 400 1000 3840
ON TH3
./chip-tool pairing code 3 36448921425( Manual pairing code received from TH2) --commissioner-name beta
[1659598782.742279][3144:3149] CHIP:DMG: InvokeResponseMessage =
[1659598782.742309][3144:3149] CHIP:DMG: {
[1659598782.742336][3144:3149] CHIP:DMG: suppressResponse = false,
[1659598782.742364][3144:3149] CHIP:DMG: InvokeResponseIBs =
[1659598782.742400][3144:3149] CHIP:DMG: [
[1659598782.742427][3144:3149] CHIP:DMG: InvokeResponseIB =
[1659598782.742467][3144:3149] CHIP:DMG: {
[1659598782.742497][3144:3149] CHIP:DMG: CommandDataIB =
[1659598782.742532][3144:3149] CHIP:DMG: {
[1659598782.742566][3144:3149] CHIP:DMG: CommandPathIB =
[1659598782.742604][3144:3149] CHIP:DMG: {
[1659598782.742643][3144:3149] CHIP:DMG: EndpointId = 0x0,
[1659598782.742682][3144:3149] CHIP:DMG: ClusterId = 0x30,
[1659598782.742721][3144:3149] CHIP:DMG: CommandId = 0x5,
[1659598782.742757][3144:3149] CHIP:DMG: },
[1659598782.742792][3144:3149] CHIP:DMG:
[1659598782.742825][3144:3149] CHIP:DMG: CommandFields =
[1659598782.742862][3144:3149] CHIP:DMG: {
[1659598782.742901][3144:3149] CHIP:DMG: 0x0 = 0,
[1659598782.742939][3144:3149] CHIP:DMG: 0x1 = "" (0 chars),
[1659598782.742981][3144:3149] CHIP:DMG: },
[1659598782.743016][3144:3149] CHIP:DMG: },
[1659598782.743059][3144:3149] CHIP:DMG:
[1659598782.743088][3144:3149] CHIP:DMG: },
[1659598782.743123][3144:3149] CHIP:DMG:
[1659598782.743151][3144:3149] CHIP:DMG: ],
[1659598782.743185][3144:3149] CHIP:DMG:
[1659598782.743212][3144:3149] CHIP:DMG: InteractionModelRevision = 1
[1659598782.743239][3144:3149] CHIP:DMG: },
[1659598782.743306][3144:3149] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0005
[1659598782.743350][3144:3149] CHIP:CTL: Received CommissioningComplete response, errorCode=0
[1659598782.743408][3144:3149] CHIP:CTL: Successfully finished commissioning step "SendComplete"
[1659598782.743454][3144:3149] CHIP:CTL: Commissioning stage next step: "SendComplete" -> "Cleanup"
[1659598782.743501][3144:3149] CHIP:CTL: Performing next commissioning step "Cleanup"
[1659598782.743649][3144:3149] CHIP:CTL: Successfully finished commissioning step "Cleanup"
[1659598782.743708][3144:3149] CHIP:TOO: Device commissioning completed with success
[1659598782.743826][3144:3149] CHIP:DMG: ICR moving to [AwaitingDe]
disabled: true
- label: "TH2 waits for a subscription report from the DUT"
verification: |
TH2 receives a report from the DUT for subscription_noc. The AttributeReports list contains an entry with the AttributeData field present.
That should contain a Path to the Node Operational Credentials cluster NOCs attribute.
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
operationalcredentials subscribe nocs 1 30 2 0 --fabric-filtered false --keepSubscriptions 0
On TH(Chiptool)2 ,Verify TH receives a report from the DUT on subscription_noc. The AttributeReports list contains an entry with the AttributeData field present. That should contain a Path to the Node Operational Credentials cluster NOCs attribute.
[1659608932.122100][4449:4454] CHIP:DMG: ] (231 bytes)
[1659608932.122155][4449:4454] CHIP:DMG: 0xfe = 2,
[1659608932.122209][4449:4454] CHIP:DMG: },
[1659608932.122253][4449:4454] CHIP:DMG: },
[1659608932.122304][4449:4454] CHIP:DMG:
[1659608932.122344][4449:4454] CHIP:DMG: },
[1659608932.122403][4449:4454] CHIP:DMG:
[1659608932.122442][4449:4454] CHIP:DMG: AttributeReportIB =
[1659608932.122493][4449:4454] CHIP:DMG: {
[1659608932.122533][4449:4454] CHIP:DMG: AttributeDataIB =
[1659608932.122577][4449:4454] CHIP:DMG: {
[1659608932.122622][4449:4454] CHIP:DMG: DataVersion = 0xffb30683,
[1659608932.122668][4449:4454] CHIP:DMG: AttributePathIB =
[1659608932.122715][4449:4454] CHIP:DMG: {
[1659608932.122763][4449:4454] CHIP:DMG: Endpoint = 0x0,
[1659608932.122817][4449:4454] CHIP:DMG: Cluster = 0x3e,
[1659608932.122868][4449:4454] CHIP:DMG: Attribute = 0x0000_0000,
[1659608932.122921][4449:4454] CHIP:DMG: ListIndex = Null,
[1659608932.122969][4449:4454] CHIP:DMG: }
[1659608932.123019][4449:4454] CHIP:DMG:
[1659608932.123067][4449:4454] CHIP:DMG: Data =
[1659608932.123113][4449:4454] CHIP:DMG: {
[1659608932.123161][4449:4454] CHIP:DMG: 0xfe = 3,
[1659608932.123210][4449:4454] CHIP:DMG: },
[1659608932.123255][4449:4454] CHIP:DMG: },
[1659608932.123303][4449:4454] CHIP:DMG:
[1659608932.123342][4449:4454] CHIP:DMG: },
[1659608932.123387][4449:4454] CHIP:DMG:
[1659608932.123422][4449:4454] CHIP:DMG: ],
[1659608932.123487][4449:4454] CHIP:DMG:
[1659608932.123522][4449:4454] CHIP:DMG: InteractionModelRevision = 1
[1659608932.123557][4449:4454] CHIP:DMG: }
[1659608932.124097][4449:4454] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_003E Attribute 0x0000_0000 DataVersion: 4289922691
[1659608932.124211][4449:4454] CHIP:TOO: NOCs: 3 entries
[1659608932.124278][4449:4454] CHIP:TOO: [1]: {
[1659608932.124317][4449:4454] CHIP:TOO: Noc:
[1659608932.124351][4449:4454] CHIP:TOO: Icac: null
[1659608932.124403][4449:4454] CHIP:TOO: FabricIndex: 1
[1659608932.124437][4449:4454] CHIP:TOO: }
[1659608932.124483][4449:4454] CHIP:TOO: [2]: {
[1659608932.124535][4449:4454] CHIP:TOO: Noc: 1530010101240201370324130118260480228127260580254D3A37062415012411021824070124080130094104ADC43E671874BF001C1CC210AD71F455F75BE24560CB5A565E92C6BB854CE84B1A128AF3A61F28F88C35E913D527BF189055B9DF67541003F183E4093414A7ED370A3501280118240201360304020401183004140AED7FF3068DFC9984688DD15CFE6361ECD1E935300514C7E958D6860E58EBFFC50375695FA10D8D1F7BA518300B40311EC7DC99457821261CA350EB2F2C380D988FCDDC6798B695486759EA1F7D706E0204103DA42BA250AD9D6F6EA014E6BC65D74D42E3E72D109EB32B0DD547B218
[1659608932.124594][4449:4454] CHIP:TOO: Icac: 1530010100240201370324140018260480228127260580254D3A37062413011824070124080130094104B261C938B93273CC4895790193D5A154529A039D23E552263B7AA94A8F34448130E7A4C88EE71AE291BBC689E9B7D1F8C81FE3FE8C5DADFF98665DBF57D0739E370A3501290118240260300414C7E958D6860E58EBFFC50375695FA10D8D1F7BA5300514F6EF1D69A5F7797D534AB655E8E8BFA390CB562018300B40F2B2E6A0C20F819DAA4DA6C5C94B1F63686C3E11BED7FEF4E35643E795FFA3AEE4333AD726EE1EC492A29A45628EE95D88CCBAB078060BFD565CA52298A9B0AF18
[1659608932.124636][4449:4454] CHIP:TOO: FabricIndex: 2
[1659608932.124670][4449:4454] CHIP:TOO: }
[1659608932.124709][4449:4454] CHIP:TOO: [3]: {
[1659608932.124744][4449:4454] CHIP:TOO: Noc:
[1659608932.124777][4449:4454] CHIP:TOO: Icac: null
[1659608932.124809][4449:4454] CHIP:TOO: FabricIndex: 3
[1659608932.124842][4449:4454] CHIP:TOO: }
[1659608932.124912][4449:4454] CHIP:DMG: MoveToState ReadClient[0xffffa0008f20]: Moving to [AwaitingSu]
[1659608932.124981][4449:4454] CHIP:EM: Piggybacking Ack for MessageCounter:111243919 on exchange: 13953i
[1659608932.125065][4449:4454] CHIP:IN: Prepared secure message 0xffffa0007788 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 13953i with MessageCounter:128744628.
[1659608932.125118][4449:4454] CHIP:IN: Sending encrypted msg 0xffffa0007788 with MessageCounter:128744628 to 0x0000000000000002 (1) at monotonic time: 000000000122835D msec
[1659608932.126595][4449:4454] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:111243920 on exchange 13953i
[1659608932.126631][4449:4454] CHIP:EM: Found matching exchange: 13953i, Delegate: 0xffffa0008f20
[1659608932.126673][4449:4454] CHIP:EM: Rxd Ack; Removing MessageCounter:128744628 from Retrans Table on exchange 13953i
[1659608932.126701][4449:4454] CHIP:EM: Removed CHIP MessageCounter:128744628 from RetransTable on exchange 13953i
[1659608932.126747][4449:4454] CHIP:DMG: SubscribeResponseMessage =
[1659608932.126776][4449:4454] CHIP:DMG: {
disabled: true
- label:
"TH3 reads the Fabrics attribute from the Node Operational Credentials
cluster using a fabric-filtered read"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
operationalcredentials read fabrics 3 0 --commissioner-name beta
On TH(Chiptool)3,Verify Fabrics attribute from the Node Operational Credentials cluster using a fabric-filtered read and Save the FabricIndex as th3_fabric_index
[1659517037.742397][4223:4228] CHIP:EM: Flushed pending ack for MessageCounter:121918567 on exchange 15974i
[1659517037.744022][4223:4228] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:249614128 on exchange 15975i
[1659517037.744074][4223:4228] CHIP:EM: Found matching exchange: 15975i, Delegate: 0xffff94008f10
[1659517037.744125][4223:4228] CHIP:EM: Rxd Ack; Removing MessageCounter:76730247 from Retrans Table on exchange 15975i
[1659517037.744165][4223:4228] CHIP:EM: Removed CHIP MessageCounter:76730247 from RetransTable on exchange 15975i
[1659517037.744260][4223:4228] CHIP:DMG: ReportDataMessage =
[1659517037.744304][4223:4228] CHIP:DMG: {
[1659517037.744340][4223:4228] CHIP:DMG: AttributeReportIBs =
[1659517037.744391][4223:4228] CHIP:DMG: [
[1659517037.744430][4223:4228] CHIP:DMG: AttributeReportIB =
[1659517037.744486][4223:4228] CHIP:DMG: {
[1659517037.744528][4223:4228] CHIP:DMG: AttributeDataIB =
[1659517037.744579][4223:4228] CHIP:DMG: {
[1659517037.744632][4223:4228] CHIP:DMG: DataVersion = 0xffad5042,
[1659517037.744688][4223:4228] CHIP:DMG: AttributePathIB =
[1659517037.744742][4223:4228] CHIP:DMG: {
[1659517037.744936][4223:4228] CHIP:DMG: Endpoint = 0x0,
[1659517037.744997][4223:4228] CHIP:DMG: Cluster = 0x3e,
[1659517037.745054][4223:4228] CHIP:DMG: Attribute = 0x0000_0001,
[1659517037.745107][4223:4228] CHIP:DMG: }
[1659517037.745168][4223:4228] CHIP:DMG:
[1659517037.745226][4223:4228] CHIP:DMG: Data = [
[1659517037.745279][4223:4228] CHIP:DMG:
[1659517037.745339][4223:4228] CHIP:DMG: ],
[1659517037.745389][4223:4228] CHIP:DMG: },
[1659517037.745443][4223:4228] CHIP:DMG:
[1659517037.745487][4223:4228] CHIP:DMG: },
[1659517037.745550][4223:4228] CHIP:DMG:
[1659517037.745589][4223:4228] CHIP:DMG: AttributeReportIB =
[1659517037.745645][4223:4228] CHIP:DMG: {
[1659517037.745686][4223:4228] CHIP:DMG: AttributeDataIB =
[1659517037.745726][4223:4228] CHIP:DMG: {
[1659517037.745766][4223:4228] CHIP:DMG: DataVersion = 0xffad5042,
[1659517037.745823][4223:4228] CHIP:DMG: AttributePathIB =
[1659517037.745881][4223:4228] CHIP:DMG: {
[1659517037.745935][4223:4228] CHIP:DMG: Endpoint = 0x0,
[1659517037.745992][4223:4228] CHIP:DMG: Cluster = 0x3e,
[1659517037.746054][4223:4228] CHIP:DMG: Attribute = 0x0000_0001,
[1659517037.746110][4223:4228] CHIP:DMG: ListIndex = Null,
[1659517037.746167][4223:4228] CHIP:DMG: }
[1659517037.746227][4223:4228] CHIP:DMG:
[1659517037.746278][4223:4228] CHIP:DMG: Data =
[1659517037.746318][4223:4228] CHIP:DMG: {
[1659517037.746375][4223:4228] CHIP:DMG: 0x1 = [
[1659517037.746483][4223:4228] CHIP:DMG: 0x4, 0x40, 0x33, 0x1d, 0x31, 0xfe, 0x56, 0x34, 0xf8, 0x94, 0x71, 0x89, 0x8a, 0x98, 0x41, 0xbe, 0x8d, 0x76, 0x63, 0x16, 0x91, 0x79, 0x69, 0x6d, 0x7a, 0x79, 0xb5, 0x8, 0x92, 0x1e, 0x5c, 0x69, 0xc7, 0x45, 0x9d, 0xcf, 0x7c, 0x9a, 0xdc, 0xf7, 0x6, 0x2f, 0x44, 0x9e, 0xed, 0x70, 0x2d, 0x9a, 0x7e, 0x3a, 0xdb, 0xc, 0xdf, 0xd, 0xb9, 0x42, 0x7f, 0xbb, 0x93, 0x61, 0x7f, 0xb0, 0xfa, 0x50, 0xe1,
[1659517037.746531][4223:4228] CHIP:DMG: ] (65 bytes)
[1659517037.746572][4223:4228] CHIP:DMG: 0x2 = 65521,
[1659517037.746609][4223:4228] CHIP:DMG: 0x3 = 2,
[1659517037.746646][4223:4228] CHIP:DMG: 0x4 = 3,
[1659517037.746684][4223:4228] CHIP:DMG: 0x5 = "" (0 chars),
[1659517037.746720][4223:4228] CHIP:DMG: 0xfe = 3,
[1659517037.746758][4223:4228] CHIP:DMG: },
[1659517037.746790][4223:4228] CHIP:DMG: },
[1659517037.746828][4223:4228] CHIP:DMG:
[1659517037.746854][4223:4228] CHIP:DMG: },
[1659517037.746888][4223:4228] CHIP:DMG:
[1659517037.746913][4223:4228] CHIP:DMG: ],
[1659517037.746952][4223:4228] CHIP:DMG:
[1659517037.746977][4223:4228] CHIP:DMG: SuppressResponse = true,
[1659517037.747003][4223:4228] CHIP:DMG: InteractionModelRevision = 1
[1659517037.747027][4223:4228] CHIP:DMG: }
[1659517037.747303][4223:4228] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_003E Attribute 0x0000_0001 DataVersion: 4289548354
[1659517037.747392][4223:4228] CHIP:TOO: Fabrics: 1 entries
[1659517037.747450][4223:4228] CHIP:TOO: [1]: {
[1659517037.747485][4223:4228] CHIP:TOO: RootPublicKey: 0440331D31FE5634F89471898A9841BE8D7663169179696D7A79B508921E5C69C7459DCF7C9ADCF7062F449EED702D9A7E3ADB0CDF0DB9427FBB93617FB0FA50E1
[1659517037.747525][4223:4228] CHIP:TOO: VendorId: 65521
[1659517037.747551][4223:4228] CHIP:TOO: FabricId: 2
[1659517037.747575][4223:4228] CHIP:TOO: NodeId: 3
[1659517037.747598][4223:4228] CHIP:TOO: Label:
[1659517037.747622][4223:4228] CHIP:TOO: FabricIndex: 3
[1659517037.747646][4223:4228] CHIP:TOO: }
[1659517037.747731][4223:4228] CHIP:EM: Sending Standalone Ack for MessageCounter:249614128 on exchange 15975i
[1659517037.747805][4223:4228] CHIP:IN: Prepared secure message 0xffffa9b9d9e8 to 0x0000000000000003 (2) of type 0x10 and protocolId (0, 0) on exchange 15975i with MessageCounter:76730248.
[1659517037.747846][4223:4228] CHIP:IN: Sending encrypted msg 0xffffa9b9d9e8 with MessageCounter:76730248 to 0x0000000000000003 (2) at monotonic time: 0000000000CF41CF msec
[1659517037.747964][4223:4228] CHIP:EM: Flushed pending ack for MessageCounter:249614128 on exchange 15975i
[1659517037.748180][4223:4223] CHIP:CTL: Shutting down the commissioner
[1659517037.748214][4223:4223] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1659517037.748238][4223:4223] CHIP:CTL: Shutting down the controller
[1659517037.748262][4223:4223] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1659517037.748286][4223:4223] CHIP:FP: Forgetting fabric 0x1
[1659517037.748320][4223:4223] CHIP:TS: Pending Last Known Good Time: 2022-07-21T06:51:50
disabled: true
- label:
"TH1 sends a RemoveFabric command to the Node Operational Credentials
cluster with FabricIndex set to th3_fabric_index"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
operationalcredentials remove-fabric 3 1 0
On TH(Chiptool)1,Verify RemoveFabric command to the Node Operational Credentials cluster with FabricIndex set to th3_fabric_index
[1659517261.457106][10892:10897] CHIP:DMG: InvokeResponseMessage =
[1659517261.457150][10892:10897] CHIP:DMG: {
[1659517261.457175][10892:10897] CHIP:DMG: suppressResponse = false,
[1659517261.457212][10892:10897] CHIP:DMG: InvokeResponseIBs =
[1659517261.457245][10892:10897] CHIP:DMG: [
[1659517261.457281][10892:10897] CHIP:DMG: InvokeResponseIB =
[1659517261.457318][10892:10897] CHIP:DMG: {
[1659517261.457354][10892:10897] CHIP:DMG: CommandDataIB =
[1659517261.457396][10892:10897] CHIP:DMG: {
[1659517261.457426][10892:10897] CHIP:DMG: CommandPathIB =
[1659517261.457472][10892:10897] CHIP:DMG: {
[1659517261.457511][10892:10897] CHIP:DMG: EndpointId = 0x0,
[1659517261.457560][10892:10897] CHIP:DMG: ClusterId = 0x3e,
[1659517261.457609][10892:10897] CHIP:DMG: CommandId = 0x8,
[1659517261.457655][10892:10897] CHIP:DMG: },
[1659517261.457694][10892:10897] CHIP:DMG:
[1659517261.457733][10892:10897] CHIP:DMG: CommandFields =
[1659517261.457774][10892:10897] CHIP:DMG: {
[1659517261.457848][10892:10897] CHIP:DMG: 0x0 = 0,
[1659517261.457894][10892:10897] CHIP:DMG: 0x1 = 3,
[1659517261.457932][10892:10897] CHIP:DMG: },
[1659517261.457963][10892:10897] CHIP:DMG: },
[1659517261.458002][10892:10897] CHIP:DMG:
[1659517261.458027][10892:10897] CHIP:DMG: },
[1659517261.458058][10892:10897] CHIP:DMG:
[1659517261.458081][10892:10897] CHIP:DMG: ],
[1659517261.458112][10892:10897] CHIP:DMG:
[1659517261.458136][10892:10897] CHIP:DMG: InteractionModelRevision = 1
[1659517261.458159][10892:10897] CHIP:DMG: },
[1659517261.458220][10892:10897] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
[1659517261.458272][10892:10897] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_003E Command 0x0000_0008
[1659517261.458326][10892:10897] CHIP:TOO: NOCResponse: {
[1659517261.458375][10892:10897] CHIP:TOO: statusCode: 0
[1659517261.458400][10892:10897] CHIP:TOO: fabricIndex: 3
[1659517261.458424][10892:10897] CHIP:TOO: }
[1659517261.458461][10892:10897] CHIP:DMG: ICR moving to [AwaitingDe]
[1659517261.458519][10892:10897] CHIP:EM: Sending Standalone Ack for MessageCounter:175598463 on exchange 6398i
[1659517261.458597][10892:10897] CHIP:IN: Prepared secure message 0xffffac8ac9e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 6398i with MessageCounter:118554533.
[1659517261.458639][10892:10897] CHIP:IN: Sending encrypted msg 0xffffac8ac9e8 with MessageCounter:118554533 to 0x0000000000000001 (1) at monotonic time: 0000000006225CEA msec
[1659517261.458777][10892:10897] CHIP:EM: Flushed pending ack for MessageCounter:175598463 on exchange 6398i
[1659517261.459001][10892:10892] CHIP:CTL: Shutting down the commissioner
[1659517261.459062][10892:10892] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1659517261.459106][10892:10892] CHIP:CTL: Shutting down the controller
[1659517261.459158][10892:10892] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1659517261.459201][10892:10892] CHIP:IN: SecureSession[0xffff98001930]: MarkForEviction Type:2 LSID:35532
[1659517261.459244][10892:10892] CHIP:SC: SecureSession[0xffff98001930]: Moving from state "kActive" --> "kPendingEviction"
[1659517261.459287][10892:10892] CHIP:IN: SecureSession[0xffff98001930]: Released - Type:2 LSID:35532
[1659517261.459333][10892:10892] CHIP:FP: Forgetting fabric 0x1
[1659517261.459388][10892:10892] CHIP:TS: Pending Last Known Good Time: 2022-08-01T09:44:35
[1659517261.459597][10892:10892] CHIP:TS: Previous Last Known Good Time: 2022-08-01T09:44:35
[1659517261.459623][10892:10892] CHIP:TS: Reverted Last Known Good Time to previous value
[1659517261.459669][10892:10892] CHIP:CTL: Shutting down the commissioner
[1659517261.459693][10892:10892] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1659517261.459716][10892:10892] CHIP:CTL: Shutting down the controller
[1659517261.459736][10892:10892] CHIP:IN: Expiring all sessions for fabric 0x2!!
[1659517261.459787][10892:10892] CHIP:FP: Forgetting fabric 0x2
[1659517261.459816][10892:10892] CHIP:TS: Pending Last Known Good Time: 2022-08-01T09:44:35
disabled: true
- label: "TH2 waits for a subscription report from the DUT"
verification: |
TH2 receives a report from the DUT for subscription_noc. The AttributeReports list contains an entry with the AttributeData field present.
That should contain a Path to the Node Operational Credentials cluster NOCs attribute. for privious subscvription
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
operationalcredentials subscribe nocs 1 30 2 0 --fabric-filtered false --keepSubscriptions 0
On TH(Chiptool)1,Verify TH receives a report from the DUT for subscription_noc. The AttributeReports list contains an entry with the AttributeData field present. That should contain a Path to the Node Operational Credentials cluster NOCs attribute.
1659603570.948282][3879:3884] CHIP:DMG: ] (231 bytes)
[1659603570.948321][3879:3884] CHIP:DMG: 0xfe = 2,
[1659603570.948358][3879:3884] CHIP:DMG: },
[1659603570.948393][3879:3884] CHIP:DMG: },
[1659603570.948429][3879:3884] CHIP:DMG:
[1659603570.948454][3879:3884] CHIP:DMG: },
[1659603570.948486][3879:3884] CHIP:DMG:
[1659603570.948509][3879:3884] CHIP:DMG: ],
[1659603570.948550][3879:3884] CHIP:DMG:
[1659603570.948574][3879:3884] CHIP:DMG: InteractionModelRevision = 1
[1659603570.948597][3879:3884] CHIP:DMG: }
[1659603570.948872][3879:3884] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_003E Attribute 0x0000_0000 DataVersion: 1660506005
[1659603570.948932][3879:3884] CHIP:TOO: NOCs: 2 entries
[1659603570.948968][3879:3884] CHIP:TOO: [1]: {
[1659603570.948995][3879:3884] CHIP:TOO: Noc:
[1659603570.949018][3879:3884] CHIP:TOO: Icac: null
[1659603570.949044][3879:3884] CHIP:TOO: FabricIndex: 1
[1659603570.949068][3879:3884] CHIP:TOO: }
[1659603570.949102][3879:3884] CHIP:TOO: [2]: {
[1659603570.949137][3879:3884] CHIP:TOO: Noc: 1530010101240201370324130118260480228127260580254D3A3706241501241102182407012408013009410493458F560CDD3995FB822B0B71EADE5D757C8454CA5170A4F6AECC068A3060B4A3F979439078F660B1F1E1EE1CC0FD8BDC81DECEE301962A83B3ACDE6CC6752F370A3501280118240201360304020401183004143E977751BDEED7C677522FA3D5F9F85E37D0F5A4300514C7E958D6860E58EBFFC50375695FA10D8D1F7BA518300B400AC33F78A020EA1D6D975E82A7F77B9B87C37AA3D633CC903F2EF93B09FF9F11E71A54A1F2822B012B5FA9BC9D40C98F06B0A53C3BEE123A5290C2473C243BB118
[1659603570.949176][3879:3884] CHIP:TOO: Icac: 1530010100240201370324140018260480228127260580254D3A37062413011824070124080130094104B261C938B93273CC4895790193D5A154529A039D23E552263B7AA94A8F34448130E7A4C88EE71AE291BBC689E9B7D1F8C81FE3FE8C5DADFF98665DBF57D0739E370A3501290118240260300414C7E958D6860E58EBFFC50375695FA10D8D1F7BA5300514F6EF1D69A5F7797D534AB655E8E8BFA390CB562018300B40F2B2E6A0C20F819DAA4DA6C5C94B1F63686C3E11BED7FEF4E35643E795FFA3AEE4333AD726EE1EC492A29A45628EE95D88CCBAB078060BFD565CA52298A9B0AF18
[1659603570.949204][3879:3884] CHIP:TOO: FabricIndex: 2
[1659603570.949226][3879:3884] CHIP:TOO: }
[1659603570.949271][3879:3884] CHIP:DMG: MoveToState ReadClient[0xffff7c003110]: Moving to [AwaitingSu]
[1659603570.949330][3879:3884] CHIP:EM: Piggybacking Ack for MessageCounter:89095500 on exchange: 18769i
[1659603570.949404][3879:3884] CHIP:IN: Prepared secure message 0xffff7c000f08 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 18769i with MessageCounter:216513810.
[1659603570.949445][3879:3884] CHIP:IN: Sending encrypted msg 0xffff7c000f08 with MessageCounter:216513810 to 0x0000000000000002 (1) at monotonic time: 0000000000D0B545 msec
[1659603570.950949][3879:3884] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:89095501 on exchange 18769i
[1659603570.951000][3879:3884] CHIP:EM: Found matching exchange: 18769i, Delegate: 0xffff7c003110
[1659603570.951037][3879:3884] CHIP:EM: Rxd Ack; Removing MessageCounter:216513810 from Retrans Table on exchange 18769i
[1659603570.951061][3879:3884] CHIP:EM: Removed CHIP MessageCounter:216513810 from RetransTable on exchange 18769i
[1659603570.951114][3879:3884] CHIP:DMG: SubscribeResponseMessage =
[1659603570.951142][3879:3884] CHIP:DMG: {
[1659603570.951167][3879:3884] CHIP:DMG: SubscriptionId = 0xf9e7f5b6,
[1659603570.951193][3879:3884] CHIP:DMG: MaxInterval = 0x1e,
[1659603570.951217][3879:3884] CHIP:DMG: InteractionModelRevision = 1
[1659603570.951240][3879:3884] CHIP:DMG: }
[1659603570.951269][3879:3884] CHIP:DMG: Subscription established with SubscriptionID = 0xf9e7f5b6 MinInterval = 1s MaxInterval = 30s Peer = 01:0000000000000002
[1659603570.951297][3879:3884] CHIP:DMG: MoveToState ReadClient[0xffff7c003110]: Moving to [Subscripti]
[1659603570.951343][3879:3884] CHIP:DMG: Refresh LivenessCheckTime for 55000 milliseconds with SubscriptionId = 0xf9e7f5b6 Peer = 01:0000000000000002
[1659603570.951375][3879:3884] CHIP:EM: Sending Standalone Ack for MessageCounter:89095501 on exchange 18769i
[1659603570.951431][3879:3884] CHIP:IN: Prepared secure message 0xffff90c469e8 to 0x0000000000000002 (1) of type 0x10 and protocolId (0, 0) on exchange 18769i with MessageCounter:216513811.
[1659603570.951473][3879:3884] CHIP:IN: Sending encrypted msg 0xffff90c469e8 with MessageCounter:216513811 to 0x0000000000000002 (1) at monotonic time: 0000000000D0B547 msec
[1659603570.951595][3879:3884] CHIP:EM: Flushed pending ack for MessageCounter:89095501 on exchange 18769i
disabled: true
- label:
"TH1 selects a node id N_unused that is different than N1 and N2. This
node ID does not need to correspond to an actual node, it is used as a
placeholder to allow changing the ACL attribute without affecting the
access of N1 and N2. TH1 writes Access Control cluster ACL attribute,
value is a list of AccessControlEntryStruct Containing 1 element:
struct Privilege field: Administer (5) AuthMode field: CASE (2)
Subjects field: [N1, N2, N_unused] Targets field: [{Endpoint: 0}]"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
accesscontrol write acl '[{"fabricIndex": 1, "privilege": 5, "authMode": 2, "subjects": [112233,4], "targets": [{ "cluster": null, "endpoint": 0, "deviceType": null }]}]' 1 0
On TH(Chiptool)1,Verify ACL attribute list of AccessControlEntryStruct entries containing 1 element and selects a node id N_unused that is different than N1 and N2
[1659517872.814034][10929:10934] CHIP:EM: Flushed pending ack for MessageCounter:121918573 on exchange 48872i
[1659517872.821723][10929:10934] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:206343064 on exchange 48873i
[1659517872.821777][10929:10934] CHIP:EM: Found matching exchange: 48873i, Delegate: 0xffffa4003c10
[1659517872.821837][10929:10934] CHIP:EM: Rxd Ack; Removing MessageCounter:107750962 from Retrans Table on exchange 48873i
[1659517872.821873][10929:10934] CHIP:EM: Removed CHIP MessageCounter:107750962 from RetransTable on exchange 48873i
[1659517872.821924][10929:10934] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659517872.821996][10929:10934] CHIP:DMG: WriteResponseMessage =
[1659517872.822034][10929:10934] CHIP:DMG: {
[1659517872.822067][10929:10934] CHIP:DMG: AttributeStatusIBs =
[1659517872.822111][10929:10934] CHIP:DMG: [
[1659517872.822147][10929:10934] CHIP:DMG: AttributeStatusIB =
[1659517872.822190][10929:10934] CHIP:DMG: {
[1659517872.822233][10929:10934] CHIP:DMG: AttributePathIB =
[1659517872.822273][10929:10934] CHIP:DMG: {
[1659517872.822317][10929:10934] CHIP:DMG: Endpoint = 0x0,
[1659517872.822362][10929:10934] CHIP:DMG: Cluster = 0x1f,
[1659517872.822407][10929:10934] CHIP:DMG: Attribute = 0x0000_0000,
[1659517872.822446][10929:10934] CHIP:DMG: }
[1659517872.822489][10929:10934] CHIP:DMG:
[1659517872.822527][10929:10934] CHIP:DMG: StatusIB =
[1659517872.822753][10929:10934] CHIP:DMG: {
[1659517872.822805][10929:10934] CHIP:DMG: status = 0x00 (SUCCESS),
[1659517872.822848][10929:10934] CHIP:DMG: },
[1659517872.822891][10929:10934] CHIP:DMG:
[1659517872.822927][10929:10934] CHIP:DMG: },
[1659517872.822975][10929:10934] CHIP:DMG:
[1659517872.823009][10929:10934] CHIP:DMG: AttributeStatusIB =
[1659517872.823048][10929:10934] CHIP:DMG: {
[1659517872.823086][10929:10934] CHIP:DMG: AttributePathIB =
[1659517872.823127][10929:10934] CHIP:DMG: {
[1659517872.823169][10929:10934] CHIP:DMG: Endpoint = 0x0,
[1659517872.823213][10929:10934] CHIP:DMG: Cluster = 0x1f,
[1659517872.823259][10929:10934] CHIP:DMG: Attribute = 0x0000_0000,
[1659517872.823306][10929:10934] CHIP:DMG: ListIndex = Null,
[1659517872.823348][10929:10934] CHIP:DMG: }
[1659517872.823394][10929:10934] CHIP:DMG:
[1659517872.823438][10929:10934] CHIP:DMG: StatusIB =
[1659517872.823479][10929:10934] CHIP:DMG: {
[1659517872.823522][10929:10934] CHIP:DMG: status = 0x00 (SUCCESS),
[1659517872.823567][10929:10934] CHIP:DMG: },
[1659517872.823608][10929:10934] CHIP:DMG:
[1659517872.823644][10929:10934] CHIP:DMG: },
[1659517872.823684][10929:10934] CHIP:DMG:
[1659517872.823716][10929:10934] CHIP:DMG: ],
[1659517872.823785][10929:10934] CHIP:DMG:
[1659517872.823820][10929:10934] CHIP:DMG: InteractionModelRevision = 1
[1659517872.823851][10929:10934] CHIP:DMG: }
[1659517872.823984][10929:10934] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659517872.824058][10929:10934] CHIP:EM: Sending Standalone Ack for MessageCounter:206343064 on exchange 48873i
[1659517872.824154][10929:10934] CHIP:IN: Prepared secure message 0xffffae7cd9e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 48873i with MessageCounter:107750963.
[1659517872.824197][10929:10934] CHIP:IN: Sending encrypted msg 0xffffae7cd9e8 with MessageCounter:107750963 to 0x0000000000000001 (1) at monotonic time: 00000000062BB110 msec
[1659517872.824406][10929:10934] CHIP:EM: Flushed pending ack for MessageCounter:206343064 on exchange 48873i
[1659517872.824642][10929:10929] CHIP:CTL: Shutting down the commissioner
[1659517872.824674][10929:10929] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1659517872.824755][10929:10929] CHIP:CTL: Shutting down the controller
[1659517872.824812][10929:10929] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1659517872.824840][10929:10929] CHIP:IN: SecureSession[0xffffa4001930]: MarkForEviction Type:2 LSID:13780
[1659517872.824865][10929:10929] CHIP:SC: SecureSession[0xffffa4001930]: Moving from state "kActive" --> "kPendingEviction"
[1659517872.824889][10929:10929] CHIP:IN: SecureSession[0xffffa4001930]: Released - Type:2 LSID:13780
[1659517872.824915][10929:10929] CHIP:FP: Forgetting fabric 0x1
[1659517872.824949][10929:10929] CHIP:TS: Pending Last Known Good Time: 2022-08-01T09:44:35
[1659517872.825142][10929:10929] CHIP:TS: Previous Last Known Good Time: 2022-08-01T09:44:35
[1659517872.825169][10929:10929] CHIP:TS: Reverted Last Known Good Time to previous value
[1659517872.825214][10929:10929] CHIP:CTL: Shutting down the commissioner
disabled: true
- label: "TH2 waits for a subscription report from the DUT"
verification: |
accesscontrol subscribe-event access-control-entry-changed 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify it receives a report from the DUT for subscription_ace. The EventReports list contains an entry with EventData field present. That should contain a Path to the Access Control cluster AccessControlEntryStruct event.
[1659603721.185884][3879:3884] CHIP:EM: Found matching exchange: 18770i, Delegate: 0xffff7c008ff0
[1659603721.185938][3879:3884] CHIP:EM: Rxd Ack; Removing MessageCounter:216513817 from Retrans Table on exchange 18770i
[1659603721.185979][3879:3884] CHIP:EM: Removed CHIP MessageCounter:216513817 from RetransTable on exchange 18770i
[1659603721.186072][3879:3884] CHIP:DMG: ReportDataMessage =
[1659603721.186117][3879:3884] CHIP:DMG: {
[1659603721.186157][3879:3884] CHIP:DMG: SubscriptionId = 0xe570e9a7,
[1659603721.186199][3879:3884] CHIP:DMG: EventReportIBs =
[1659603721.186258][3879:3884] CHIP:DMG: [
[1659603721.186298][3879:3884] CHIP:DMG: EventReportIB =
[1659603721.186361][3879:3884] CHIP:DMG: {
[1659603721.186404][3879:3884] CHIP:DMG: EventDataIB =
[1659603721.186456][3879:3884] CHIP:DMG: {
[1659603721.186504][3879:3884] CHIP:DMG: EventPath =
[1659603721.186565][3879:3884] CHIP:DMG: {
[1659603721.186625][3879:3884] CHIP:DMG: Endpoint = 0x0,
[1659603721.186684][3879:3884] CHIP:DMG: Cluster = 0x1f,
[1659603721.186745][3879:3884] CHIP:DMG: Event = 0x0,
[1659603721.186797][3879:3884] CHIP:DMG: },
[1659603721.186852][3879:3884] CHIP:DMG:
[1659603721.186903][3879:3884] CHIP:DMG: EventNumber = 0x6,
[1659603721.186963][3879:3884] CHIP:DMG: PriorityLevel = 0x1,
[1659603721.187018][3879:3884] CHIP:DMG: SystemTimestamp = 0xca5cb2,
[1659603721.187071][3879:3884] CHIP:DMG: EventData =
[1659603721.187124][3879:3884] CHIP:DMG: {
[1659603721.187180][3879:3884] CHIP:DMG: 0x1 = NULL
[1659603721.187238][3879:3884] CHIP:DMG: 0x2 = 0,
[1659603721.187295][3879:3884] CHIP:DMG: 0x3 = 1,
[1659603721.187353][3879:3884] CHIP:DMG: 0x4 =
[1659603721.187408][3879:3884] CHIP:DMG: {
[1659603721.187465][3879:3884] CHIP:DMG: 0x1 = 5,
[1659603721.187526][3879:3884] CHIP:DMG: 0x2 = 2,
[1659603721.187585][3879:3884] CHIP:DMG: 0x3 = [
[1659603721.187706][3879:3884] CHIP:DMG: 112233,
[1659603721.187781][3879:3884] CHIP:DMG: ],
[1659603721.187844][3879:3884] CHIP:DMG: 0x4 = NULL
[1659603721.187906][3879:3884] CHIP:DMG: 0xfe = 2,
[1659603721.187960][3879:3884] CHIP:DMG: },
[1659603721.188022][3879:3884] CHIP:DMG: 0xfe = 2,
[1659603721.188080][3879:3884] CHIP:DMG: },
[1659603721.188130][3879:3884] CHIP:DMG: },
[1659603721.188193][3879:3884] CHIP:DMG:
[1659603721.188237][3879:3884] CHIP:DMG: },
[1659603721.188298][3879:3884] CHIP:DMG:
[1659603721.188336][3879:3884] CHIP:DMG: ],
[1659603721.188394][3879:3884] CHIP:DMG:
[1659603721.188434][3879:3884] CHIP:DMG: InteractionModelRevision = 1
[1659603721.188472][3879:3884] CHIP:DMG: }
[1659603721.188705][3879:3884] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000
[1659603721.188746][3879:3884] CHIP:TOO: Event number: 6
[1659603721.188782][3879:3884] CHIP:TOO: Priority: Info
[1659603721.188818][3879:3884] CHIP:TOO: Timestamp: 13262002
[1659603721.188934][3879:3884] CHIP:TOO: AccessControlEntryChanged: {
[1659603721.188978][3879:3884] CHIP:TOO: AdminNodeID: null
[1659603721.189019][3879:3884] CHIP:TOO: AdminPasscodeID: 0
[1659603721.189059][3879:3884] CHIP:TOO: ChangeType: 1
[1659603721.189098][3879:3884] CHIP:TOO: LatestValue: {
[1659603721.189134][3879:3884] CHIP:TOO: Privilege: 5
[1659603721.189172][3879:3884] CHIP:TOO: AuthMode: 2
[1659603721.189217][3879:3884] CHIP:TOO: Subjects: 1 entries
[1659603721.189263][3879:3884] CHIP:TOO: [1]: 112233
[1659603721.189305][3879:3884] CHIP:TOO: Targets: null
[1659603721.189342][3879:3884] CHIP:TOO: FabricIndex: 2
[1659603721.189379][3879:3884] CHIP:TOO: }
[1659603721.189417][3879:3884] CHIP:TOO: AdminFabricIndex: 2
[1659603721.189454][3879:3884] CHIP:TOO: }
[1659603721.189552][3879:3884] CHIP:DMG: MoveToState ReadClient[0xffff7c008ff0]: Moving to [AwaitingSu]
[1659603721.189635][3879:3884] CHIP:EM: Piggybacking Ack for MessageCounter:89095512 on exchange: 18770i
[1659603721.189726][3879:3884] CHIP:IN: Prepared secure message 0xffff7c000f08 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 18770i with MessageCounter:216513818.
[1659603721.189786][3879:3884] CHIP:IN: Sending encrypted msg 0xffff7c000f08 with MessageCounter:216513818 to 0x00
disabled: true
- label:
"TH1 writes the Breadcrumb attribute on the General Commissioning
cluster, setting the value to 1"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
generalcommissioning write breadcrumb 1 1 0
On TH(Chiptool)1,Verify Breadcrumb attribute on the General Commissioning cluster, setting the value to 1
[1659518258.514701][10974:10979] CHIP:EM: Flushed pending ack for MessageCounter:121918579 on exchange 48566i
[1659518258.516435][10974:10979] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:75008005 on exchange 48567i
[1659518258.516482][10974:10979] CHIP:EM: Found matching exchange: 48567i, Delegate: 0xffff80003c10
[1659518258.516552][10974:10979] CHIP:EM: Rxd Ack; Removing MessageCounter:179846126 from Retrans Table on exchange 48567i
[1659518258.516588][10974:10979] CHIP:EM: Removed CHIP MessageCounter:179846126 from RetransTable on exchange 48567i
[1659518258.516637][10974:10979] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659518258.516699][10974:10979] CHIP:DMG: WriteResponseMessage =
[1659518258.516735][10974:10979] CHIP:DMG: {
[1659518258.516782][10974:10979] CHIP:DMG: AttributeStatusIBs =
[1659518258.516829][10974:10979] CHIP:DMG: [
[1659518258.516874][10974:10979] CHIP:DMG: AttributeStatusIB =
[1659518258.516915][10974:10979] CHIP:DMG: {
[1659518258.516966][10974:10979] CHIP:DMG: AttributePathIB =
[1659518258.517017][10974:10979] CHIP:DMG: {
[1659518258.517073][10974:10979] CHIP:DMG: Endpoint = 0x0,
[1659518258.517131][10974:10979] CHIP:DMG: Cluster = 0x30,
[1659518258.517177][10974:10979] CHIP:DMG: Attribute = 0x0000_0000,
[1659518258.517229][10974:10979] CHIP:DMG: }
[1659518258.517288][10974:10979] CHIP:DMG:
[1659518258.517333][10974:10979] CHIP:DMG: StatusIB =
[1659518258.517390][10974:10979] CHIP:DMG: {
[1659518258.517444][10974:10979] CHIP:DMG: status = 0x00 (SUCCESS),
[1659518258.517491][10974:10979] CHIP:DMG: },
[1659518258.517532][10974:10979] CHIP:DMG:
[1659518258.517569][10974:10979] CHIP:DMG: },
[1659518258.517608][10974:10979] CHIP:DMG:
[1659518258.517640][10974:10979] CHIP:DMG: ],
[1659518258.517681][10974:10979] CHIP:DMG:
[1659518258.517713][10974:10979] CHIP:DMG: InteractionModelRevision = 1
[1659518258.517745][10974:10979] CHIP:DMG: }
[1659518258.517834][10974:10979] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659518258.517912][10974:10979] CHIP:EM: Sending Standalone Ack for MessageCounter:75008005 on exchange 48567i
[1659518258.517998][10974:10979] CHIP:IN: Prepared secure message 0xffff948d39e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 48567i with MessageCounter:179846127.
[1659518258.518048][10974:10979] CHIP:IN: Sending encrypted msg 0xffff948d39e8 with MessageCounter:179846127 to 0x0000
disabled: true
- label: "TH2 waits for a subscription report from the DUT"
verification: |
TH2 receives a report from the DUT for subscription_breadcrumb. The AttributeReports list contains an entry with the AttributeData field present. That should contain a Path to the General Commissioning cluster Breadcrumb attribute.
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify TH receives a report from the DUT for subscription_breadcrumb. The AttributeReports list contains an entry with the AttributeData field present. That should contain a Path to the General Commissioning cluster Breadcrumb attribute.
[1659603967.088241][3914:3920] CHIP:EM: Found matching exchange: 19092i, Delegate: 0xffff6c008f00
[1659603967.088289][3914:3920] CHIP:EM: Rxd Ack; Removing MessageCounter:228277923 from Retrans Table on exchange 19092i
[1659603967.088326][3914:3920] CHIP:EM: Removed CHIP MessageCounter:228277923 from RetransTable on exchange 19092i
[1659603967.088402][3914:3920] CHIP:DMG: ReportDataMessage =
[1659603967.088461][3914:3920] CHIP:DMG: {
[1659603967.088498][3914:3920] CHIP:DMG: SubscriptionId = 0xc02f7203,
[1659603967.088536][3914:3920] CHIP:DMG: AttributeReportIBs =
[1659603967.088598][3914:3920] CHIP:DMG: [
[1659603967.088647][3914:3920] CHIP:DMG: AttributeReportIB =
[1659603967.088702][3914:3920] CHIP:DMG: {
[1659603967.088744][3914:3920] CHIP:DMG: AttributeDataIB =
[1659603967.088791][3914:3920] CHIP:DMG: {
[1659603967.088840][3914:3920] CHIP:DMG: DataVersion = 0x2442fc21,
[1659603967.088887][3914:3920] CHIP:DMG: AttributePathIB =
[1659603967.088937][3914:3920] CHIP:DMG: {
[1659603967.088988][3914:3920] CHIP:DMG: Endpoint = 0x0,
[1659603967.089041][3914:3920] CHIP:DMG: Cluster = 0x30,
[1659603967.089093][3914:3920] CHIP:DMG: Attribute = 0x0000_0000,
[1659603967.089143][3914:3920] CHIP:DMG: }
[1659603967.089195][3914:3920] CHIP:DMG:
[1659603967.089248][3914:3920] CHIP:DMG: Data = 1,
[1659603967.089294][3914:3920] CHIP:DMG: },
[1659603967.089344][3914:3920] CHIP:DMG:
[1659603967.089386][3914:3920] CHIP:DMG: },
[1659603967.089520][3914:3920] CHIP:DMG:
[1659603967.089560][3914:3920] CHIP:DMG: ],
[1659603967.089605][3914:3920] CHIP:DMG:
[1659603967.089642][3914:3920] CHIP:DMG: InteractionModelRevision = 1
[1659603967.089678][3914:3920] CHIP:DMG: }
[1659603967.089848][3914:3920] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369697
[1659603967.089921][3914:3920] CHIP:TOO: Breadcrumb: 1
[1659603967.089989][3914:3920] CHIP:DMG: MoveToState ReadClient[0xffff6c008f00]: Moving to [AwaitingSu]
[1659603967.090058][3914:3920] CHIP:EM: Piggybacking Ack for MessageCounter:442958 on exchange: 19092i
[1659603967.090138][3914:3920] CHIP:IN: Prepared secure message 0xaaaae33b6218 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 19092i with MessageCounter:228277924.
[1659603967.090192][3914:3920] CHIP:IN: Sending encrypted msg 0xaaaae33b6218 with MessageCounter:228277924 to 0x0000000000000002 (1) at monotonic time: 0000000000D6C0B2 msec
disabled: true
- label:
"TH1 writes AccessControl cluster ACL attribute, value is a list of
AccessControlEntryStruct containing 2 elements: struct Privilege
field: Administer (5) AuthMode field: CASE (2) Subjects field: [N1]
Targets field: [{Endpoint: 0}] struct Privilege field: Manage (4)
AuthMode field: CASE (2) Subjects field: [N2] Targets field:
[{Endpoint: 0}]"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
accesscontrol write acl '[{"fabricIndex": 1, "privilege": 5, "authMode": 2, "subjects": [112233], "targets": [{ "cluster": null, "endpoint": 0, "deviceType": null }]},{"fabricIndex": 1, "privilege": 4, "authMode": 2, "subjects": [112233], "targets": [{ "cluster": null, "endpoint": 0, "deviceType": null }]}]' 1 0
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 2 elements
[1659520939.556159][11095:11100] CHIP:EM: Rxd Ack; Removing MessageCounter:266984011 from Retrans Table on exchange 36622i
[1659520939.556186][11095:11100] CHIP:EM: Removed CHIP MessageCounter:266984011 from RetransTable on exchange 36622i
[1659520939.556226][11095:11100] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659520939.556285][11095:11100] CHIP:DMG: WriteResponseMessage =
[1659520939.556312][11095:11100] CHIP:DMG: {
[1659520939.556335][11095:11100] CHIP:DMG: AttributeStatusIBs =
[1659520939.556385][11095:11100] CHIP:DMG: [
[1659520939.556413][11095:11100] CHIP:DMG: AttributeStatusIB =
[1659520939.556454][11095:11100] CHIP:DMG: {
[1659520939.556491][11095:11100] CHIP:DMG: AttributePathIB =
[1659520939.556536][11095:11100] CHIP:DMG: {
[1659520939.556582][11095:11100] CHIP:DMG: Endpoint = 0x0,
[1659520939.556627][11095:11100] CHIP:DMG: Cluster = 0x1f,
[1659520939.556672][11095:11100] CHIP:DMG: Attribute = 0x0000_0000,
[1659520939.556714][11095:11100] CHIP:DMG: }
[1659520939.556761][11095:11100] CHIP:DMG:
[1659520939.556802][11095:11100] CHIP:DMG: StatusIB =
[1659520939.556847][11095:11100] CHIP:DMG: {
[1659520939.556890][11095:11100] CHIP:DMG: status = 0x00 (SUCCESS),
[1659520939.556933][11095:11100] CHIP:DMG: },
[1659520939.556975][11095:11100] CHIP:DMG:
[1659520939.557012][11095:11100] CHIP:DMG: },
[1659520939.557059][11095:11100] CHIP:DMG:
[1659520939.557086][11095:11100] CHIP:DMG: AttributeStatusIB =
[1659520939.557124][11095:11100] CHIP:DMG: {
[1659520939.557162][11095:11100] CHIP:DMG: AttributePathIB =
[1659520939.557204][11095:11100] CHIP:DMG: {
[1659520939.557247][11095:11100] CHIP:DMG: Endpoint = 0x0,
[1659520939.557291][11095:11100] CHIP:DMG: Cluster = 0x1f,
[1659520939.557336][11095:11100] CHIP:DMG: Attribute = 0x0000_0000,
[1659520939.557380][11095:11100] CHIP:DMG: ListIndex = Null,
[1659520939.557421][11095:11100] CHIP:DMG: }
[1659520939.557467][11095:11100] CHIP:DMG:
[1659520939.557508][11095:11100] CHIP:DMG: StatusIB =
[1659520939.557550][11095:11100] CHIP:DMG: {
[1659520939.557592][11095:11100] CHIP:DMG: status = 0x00 (SUCCESS),
[1659520939.557633][11095:11100] CHIP:DMG: },
[1659520939.557675][11095:11100] CHIP:DMG:
[1659520939.557711][11095:11100] CHIP:DMG: },
[1659520939.557757][11095:11100] CHIP:DMG:
[1659520939.557783][11095:11100] CHIP:DMG: AttributeStatusIB =
[1659520939.557820][11095:11100] CHIP:DMG: {
[1659520939.557857][11095:11100] CHIP:DMG: AttributePathIB =
[1659520939.557900][11095:11100] CHIP:DMG: {
[1659520939.557942][11095:11100] CHIP:DMG: Endpoint = 0x0,
[1659520939.557986][11095:11100] CHIP:DMG: Cluster = 0x1f,
[1659520939.558030][11095:11100] CHIP:DMG: Attribute = 0x0000_0000,
[1659520939.558074][11095:11100] CHIP:DMG: ListIndex = Null,
[1659520939.558115][11095:11100] CHIP:DMG: }
[1659520939.558161][11095:11100] CHIP:DMG:
[1659520939.558201][11095:11100] CHIP:DMG: StatusIB =
[1659520939.558242][11095:11100] CHIP:DMG: {
[1659520939.558284][11095:11100] CHIP:DMG: status = 0x00 (SUCCESS),
[1659520939.558325][11095:11100] CHIP:DMG: },
[1659520939.558367][11095:11100] CHIP:DMG:
[1659520939.558403][11095:11100] CHIP:DMG: },
[1659520939.558437][11095:11100] CHIP:DMG:
[1659520939.558460][11095:11100] CHIP:DMG: ],
[1659520939.558496][11095:11100] CHIP:DMG:
[1659520939.558520][11095:11100] CHIP:DMG: InteractionModelRevision = 1
[1659520939.558543][11095:11100] CHIP:DMG: }
[1659520939.558675][11095:11100] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659520939.558744][11095:11100] CHIP:EM: Sending Standalone Ack for MessageCounter:55569848 on exchange 36622i
[1659520939.558835][11095:11100] CHIP:IN: Prepared secure message 0xffff8cac09e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 36622i with MessageCounter:266984012.0
disabled: true
- label:
"Repeat steps 14 to 15, incrementing the Breadcrumb value from the
last write, to verify that TH2 can still receive view-privilege
attribute reports."
verification: |
Step 14 Repeated with breadcrumb value as 2:
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
generalcommissioning write breadcrumb 2 1 0
On TH(Chiptool)1,Verify Breadcrumb attribute on the General Commissioning cluster, setting the value to 2
1659605418.602931][4094:4099] CHIP:EM: Rxd Ack; Removing MessageCounter:163910084 from Retrans Table on exchange 59207i
[1659605418.602988][4094:4099] CHIP:EM: Removed CHIP MessageCounter:163910084 from RetransTable on exchange 59207i
[1659605418.603031][4094:4099] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659605418.603082][4094:4099] CHIP:DMG: WriteResponseMessage =
[1659605418.603108][4094:4099] CHIP:DMG: {
[1659605418.603130][4094:4099] CHIP:DMG: AttributeStatusIBs =
[1659605418.603161][4094:4099] CHIP:DMG: [
[1659605418.603187][4094:4099] CHIP:DMG: AttributeStatusIB =
[1659605418.603216][4094:4099] CHIP:DMG: {
[1659605418.603313][4094:4099] CHIP:DMG: AttributePathIB =
[1659605418.603348][4094:4099] CHIP:DMG: {
[1659605418.603380][4094:4099] CHIP:DMG: Endpoint = 0x0,
[1659605418.603411][4094:4099] CHIP:DMG: Cluster = 0x30,
[1659605418.603443][4094:4099] CHIP:DMG: Attribute = 0x0000_0000,
[1659605418.603471][4094:4099] CHIP:DMG: }
[1659605418.603505][4094:4099] CHIP:DMG:
[1659605418.603533][4094:4099] CHIP:DMG: StatusIB =
[1659605418.603563][4094:4099] CHIP:DMG: {
[1659605418.603592][4094:4099] CHIP:DMG: status = 0x00 (SUCCESS),
[1659605418.603621][4094:4099] CHIP:DMG: },
[1659605418.603650][4094:4099] CHIP:DMG:
[1659605418.603676][4094:4099] CHIP:DMG: },
[1659605418.603706][4094:4099] CHIP:DMG:
[1659605418.603729][4094:4099] CHIP:DMG: ],
[1659605418.603759][4094:4099] CHIP:DMG:
[1659605418.603784][4094:4099] CHIP:DMG: InteractionModelRevision = 1
[1659605418.603808][4094:4099] CHIP:DMG: }
[1659605418.603879][4094:4099] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659605418.603936][4094:4099] CHIP:EM: Sending Standalone Ack for MessageCounter:94267829 on exchange 59207i
STEP15: generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify Breadcrumb attribute on the General Commissioning cluster as 2
05561.688913][4001:4006] CHIP:TOO: cluster 0x0000_0030, attribute: 0x0000_0000, endpoint 0
[1659605561.688949][4001:4006] CHIP:TOO: Sending SubscribeAttribute to:
[1659605561.689102][4001:4006] CHIP:IN: Prepared secure message 0xaaaafae164b8 to 0x0000000000000002 (1) of type 0x3 and protocolId (0, 1) on exchange 4664i with MessageCounter:118004440.
[1659605561.689151][4001:4006] CHIP:IN: Sending encrypted msg 0xaaaafae164b8 with MessageCounter:118004440 to 0x0000000000000002 (1) at monotonic time: 0000000000EF1599 msec
[1659605561.689374][4001:4006] CHIP:DMG: MoveToState ReadClient[0xffff68008dc0]: Moving to [AwaitingIn]
[1659605561.689434][4001:4006] CHIP:EM: Sending Standalone Ack for MessageCounter:200773513 on exchange 4663i
[1659605561.689479][4001:4006] CHIP:IN: Prepared unauthenticated message 0xffff7afdd9d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 4663i with MessageCounter:79779584.
[1659605561.689514][4001:4006] CHIP:IN: Sending unauthenticated msg 0xffff7afdd9d8 with MessageCounter:79779584 to 0x0000000000000000 at monotonic time: 0000000000EF1599 msec
[1659605561.689659][4001:4006] CHIP:EM: Flushed pending ack for MessageCounter:200773513 on exchange 4663i
[1659605561.691253][4001:4006] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:183066663 on exchange 4664i
[1659605561.691292][4001:4006] CHIP:EM: Found matching exchange: 4664i, Delegate: 0xffff68008dc0
[1659605561.691332][4001:4006] CHIP:EM: Rxd Ack; Removing MessageCounter:118004440 from Retrans Table on exchange 4664i
[1659605561.691357][4001:4006] CHIP:EM: Removed CHIP MessageCounter:118004440 from RetransTable on exchange 4664i
[1659605561.691416][4001:4006] CHIP:DMG: ReportDataMessage =
[1659605561.691445][4001:4006] CHIP:DMG: {
[1659605561.691470][4001:4006] CHIP:DMG: SubscriptionId = 0xa9f98d48,
[1659605561.691496][4001:4006] CHIP:DMG: AttributeReportIBs =
[1659605561.691527][4001:4006] CHIP:DMG: [
[1659605561.691553][4001:4006] CHIP:DMG: AttributeReportIB =
[1659605561.691586][4001:4006] CHIP:DMG: {
[1659605561.691644][4001:4006] CHIP:DMG: AttributeDataIB =
[1659605561.691686][4001:4006] CHIP:DMG: {
[1659605561.691720][4001:4006] CHIP:DMG: DataVersion = 0x2442fc24,
[1659605561.691756][4001:4006] CHIP:DMG: AttributePathIB =
[1659605561.691795][4001:4006] CHIP:DMG: {
[1659605561.691832][4001:4006] CHIP:DMG: Endpoint = 0x0,
[1659605561.691871][4001:4006] CHIP:DMG: Cluster = 0x30,
[1659605561.691911][4001:4006] CHIP:DMG: Attribute = 0x0000_0000,
[1659605561.691943][4001:4006] CHIP:DMG: }
[1659605561.691979][4001:4006] CHIP:DMG:
[1659605561.692014][4001:4006] CHIP:DMG: Data = 2,
[1659605561.692049][4001:4006] CHIP:DMG: },
[1659605561.692086][4001:4006] CHIP:DMG:
[1659605561.692115][4001:4006] CHIP:DMG: },
[1659605561.692147][4001:4006] CHIP:DMG:
[1659605561.692172][4001:4006] CHIP:DMG: ],
[1659605561.692202][4001:4006] CHIP:DMG:
[1659605561.692227][4001:4006] CHIP:DMG: InteractionModelRevision = 1
[1659605561.692254][4001:4006] CHIP:DMG: }
[1659605561.692377][4001:4006] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369700
[1659605561.692433][4001:4006] CHIP:TOO: Breadcrumb: 2
[1659605561.692482][4001:4006] CHIP:DMG: MoveToState ReadClient[0xffff68008dc0]: Moving to [AwaitingSu]
[1659605561.692544][4001:4006] CHIP:EM: Piggybacking Ack for MessageCounter:183066663 on exchange: 4664i
[1659605561.692617][4001:4006] CHIP:IN: Prepared secure message 0xaaaafae164b8 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 4664i with MessageCounter:118004441.
[1659605561.692657][4001:4006] CHIP:IN: Sending encrypted msg 0xaaaafae164b8 with MessageCounter:118004441 to 0x0000000000000002 (1) at monotonic time: 0000000000EF159D msec
disabled: true
- label:
"TH2 subscribes to the Node Operational Credentials cluster NOCs
attribute, min interval 1, max interval 30, keep subscriptions false,
fabric-filtered false."
verification: |
Pls run this test in chip tool interactive mode ./chip-tool interactive start
operationalcredentials subscribe nocs 1 30 2 0 --fabric-filtered false --keepSubscriptions 0
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659609401.305167][4449:4454] CHIP:DMG: ] (231 bytes)
[1659609401.305201][4449:4454] CHIP:DMG: 0xfe = 2,
[1659609401.305234][4449:4454] CHIP:DMG: },
[1659609401.305262][4449:4454] CHIP:DMG: },
[1659609401.305295][4449:4454] CHIP:DMG:
[1659609401.305321][4449:4454] CHIP:DMG: },
[1659609401.305354][4449:4454] CHIP:DMG:
[1659609401.305378][4449:4454] CHIP:DMG: ],
[1659609401.305419][4449:4454] CHIP:DMG:
[1659609401.305443][4449:4454] CHIP:DMG: InteractionModelRevision = 1
[1659609401.305466][4449:4454] CHIP:DMG: }
[1659609401.305752][4449:4454] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_003E Attribute 0x0000_0000 DataVersion: 4289922693
[1659609401.305819][4449:4454] CHIP:TOO: NOCs: 2 entries
[1659609401.305855][4449:4454] CHIP:TOO: [1]: {
[1659609401.305882][4449:4454] CHIP:TOO: Noc:
[1659609401.305905][4449:4454] CHIP:TOO: Icac: null
[1659609401.305932][4449:4454] CHIP:TOO: FabricIndex: 1
[1659609401.305956][4449:4454] CHIP:TOO: }
[1659609401.305990][4449:4454] CHIP:TOO: [2]: {
[1659609401.306026][4449:4454] CHIP:TOO: Noc: 1530010101240201370324130118260480228127260580254D3A37062415012411021824070124080130094104ADC43E671874BF001C1CC210AD71F455F75BE24560CB5A565E92C6BB854CE84B1A128AF3A61F28F88C35E913D527BF189055B9DF67541003F183E4093414A7ED370A3501280118240201360304020401183004140AED7FF3068DFC9984688DD15CFE6361ECD1E935300514C7E958D6860E58EBFFC50375695FA10D8D1F7BA518300B40311EC7DC99457821261CA350EB2F2C380D988FCDDC6798B695486759EA1F7D706E0204103DA42BA250AD9D6F6EA014E6BC65D74D42E3E72D109EB32B0DD547B218
[1659609401.306066][4449:4454] CHIP:TOO: Icac: 1530010100240201370324140018260480228127260580254D3A37062413011824070124080130094104B261C938B93273CC4895790193D5A154529A039D23E552263B7AA94A8F34448130E7A4C88EE71AE291BBC689E9B7D1F8C81FE3FE8C5DADFF98665DBF57D0739E370A3501290118240260300414C7E958D6860E58EBFFC50375695FA10D8D1F7BA5300514F6EF1D69A5F7797D534AB655E8E8BFA390CB562018300B40F2B2E6A0C20F819DAA4DA6C5C94B1F63686C3E11BED7FEF4E35643E795FFA3AEE4333AD726EE1EC492A29A45628EE95D88CCBAB078060BFD565CA52298A9B0AF18
[1659609401.306094][4449:4454] CHIP:TOO: FabricIndex: 2
[1659609401.306117][4449:4454] CHIP:TOO: }
[1659609401.306164][4449:4454] CHIP:DMG: MoveToState ReadClient[0xffffa0008130]: Moving to [AwaitingSu]
[1659609401.306227][4449:4454] CHIP:EM: Piggybacking Ack for MessageCounter:111243995 on exchange: 13959i
[1659609401.306310][4449:4454] CHIP:IN: Prepared secure message 0xffffa0006e58 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 13959i with MessageCounter:128744678.
[1659609401.306354][4449:4454] CHIP:IN: Sending encrypted msg 0xffffa0006e58 with MessageCounter:128744678 to 0x0000000000000002 (1) at monotonic time: 000000000129AC1A msec
[1659609401.307867][4449:4454] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:111243996 on exchange 13959i
[1659609401.307952][4449:4454] CHIP:EM: Found matching exchange: 13959i, Delegate: 0xffffa0008130
[1659609401.307993][4449:4454] CHIP:EM: Rxd Ack; Removing MessageCounter:128744678 from Retrans Table on exchange 13959i
[1659609401.308018][4449:4454] CHIP:EM: Removed CHIP MessageCounter:128744678 from RetransTable on exc
disabled: true
- label:
"TH2 subscribes to the Access Control cluster
AccessControlEntryChanged event, min interval 1, max interval 30, keep
subscriptions true"
verification: |
STEP 5:Pls run this test in chip tool interactive mode ./chip-tool interactive start
accesscontrol subscribe-event access-control-entry-changed 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659609456.028409][4449:4454] CHIP:EM: Found matching exchange: 13960i, Delegate: 0xffffa0007320
[1659609456.028492][4449:4454] CHIP:EM: Rxd Ack; Removing MessageCounter:128744681 from Retrans Table on exchange 13960i
[1659609456.028552][4449:4454] CHIP:EM: Removed CHIP MessageCounter:128744681 from RetransTable on exchange 13960i
[1659609456.028688][4449:4454] CHIP:DMG: ReportDataMessage =
[1659609456.028758][4449:4454] CHIP:DMG: {
[1659609456.028818][4449:4454] CHIP:DMG: SubscriptionId = 0x107c5876,
[1659609456.028882][4449:4454] CHIP:DMG: EventReportIBs =
[1659609456.028978][4449:4454] CHIP:DMG: [
[1659609456.029040][4449:4454] CHIP:DMG: EventReportIB =
[1659609456.029138][4449:4454] CHIP:DMG: {
[1659609456.029202][4449:4454] CHIP:DMG: EventDataIB =
[1659609456.029283][4449:4454] CHIP:DMG: {
[1659609456.029355][4449:4454] CHIP:DMG: EventPath =
[1659609456.029437][4449:4454] CHIP:DMG: {
[1659609456.029518][4449:4454] CHIP:DMG: Endpoint = 0x0,
[1659609456.029605][4449:4454] CHIP:DMG: Cluster = 0x1f,
[1659609456.029689][4449:4454] CHIP:DMG: Event = 0x0,
[1659609456.029770][4449:4454] CHIP:DMG: },
[1659609456.029848][4449:4454] CHIP:DMG:
[1659609456.029959][4449:4454] CHIP:DMG: EventNumber = 0x6,
[1659609456.030058][4449:4454] CHIP:DMG: PriorityLevel = 0x1,
[1659609456.030148][4449:4454] CHIP:DMG: SystemTimestamp = 0x11c26fa,
[1659609456.030229][4449:4454] CHIP:DMG: EventData =
[1659609456.030310][4449:4454] CHIP:DMG: {
[1659609456.030395][4449:4454] CHIP:DMG: 0x1 = NULL
[1659609456.030483][4449:4454] CHIP:DMG: 0x2 = 0,
[1659609456.030569][4449:4454] CHIP:DMG: 0x3 = 1,
[1659609456.030650][4449:4454] CHIP:DMG: 0x4 =
[1659609456.030727][4449:4454] CHIP:DMG: {
[1659609456.030825][4449:4454] CHIP:DMG: 0x1 = 5,
[1659609456.030911][4449:4454] CHIP:DMG: 0x2 = 2,
[1659609456.030992][4449:4454] CHIP:DMG: 0x3 = [
[1659609456.031077][4449:4454] CHIP:DMG: 112233,
[1659609456.031170][4449:4454] CHIP:DMG: ],
[1659609456.031253][4449:4454] CHIP:DMG: 0x4 = NULL
[1659609456.031338][4449:4454] CHIP:DMG: 0xfe = 2,
[1659609456.031420][4449:4454] CHIP:DMG: },
[1659609456.031499][4449:4454] CHIP:DMG: 0xfe = 2,
[1659609456.031576][4449:4454] CHIP:DMG: },
[1659609456.031699][4449:4454] CHIP:DMG: },
[1659609456.031812][4449:4454] CHIP:DMG:
[1659609456.031876][4449:4454] CHIP:DMG: },
[1659609456.031963][4449:4454] CHIP:DMG:
[1659609456.032023][4449:4454] CHIP:DMG: ],
[1659609456.032111][4449:4454] CHIP:DMG:
[1659609456.032169][4449:4454] CHIP:DMG: InteractionModelRevision = 1
[1659609456.032227][4449:4454] CHIP:DMG: }
[1659609456.032565][4449:4454] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000
[1659609456.032627][4449:4454] CHIP:TOO: Event number: 6
[1659609456.032676][4449:4454] CHIP:TOO: Priority: Info
[1659609456.032721][4449:4454] CHIP:TOO: Timestamp: 18622202
[1659609456.032845][4449:4454] CHIP:TOO: AccessControlEntryChanged: {
[1659609456.032910][4449:4454] CHIP:TOO: AdminNodeID: null
[1659609456.032972][4449:4454] CHIP:TOO: AdminPasscodeID: 0
[1659609456.033033][4449:4454] CHIP:TOO: ChangeType: 1
[1659609456.033091][4449:4454] CHIP:TOO: LatestValue: {
[1659609456.033146][4449:4454] CHIP:TOO: Privilege: 5
[1659609456.033203][4449:4454] CHIP:TOO: AuthMode: 2
[1659609456.033270][4449:4454] CHIP:TOO: Subjects: 1 entries
[1659609456.033339][4449:4454] CHIP:TOO: [1]: 112233
[1659609456.033403][4449:4454] CHIP:TOO: Targets: null
[1659609456.033461][4449:4454] CHIP:TOO: FabricIndex: 2
[1659609456.033517][4449:4454] CHIP:TOO: }
[1659609456.033573][4449:4454] CHIP:TOO: AdminFabricIndex: 2
[1659609456.033628][4449:4454] CHIP:TOO: }
[1659609456.033772][4449:4454] CHIP:DMG: MoveToState ReadClient[0xffffa0007320]: Moving to [AwaitingSu]
[1659609456.034018][4449:4454] CHIP:EM: Piggybacking Ack for MessageCounter:111243999 on exchange: 13960i
disabled: true
- label: "Repeat step 5 to ensure TH2 can still subscribe to view events"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659605954.366114][4022:4027] CHIP:EM: Found matching exchange: 46506i, Delegate: 0xffff94008e40
[1659605954.366193][4022:4027] CHIP:EM: Rxd Ack; Removing MessageCounter:106194597 from Retrans Table on exchange 46506i
[1659605954.366256][4022:4027] CHIP:EM: Removed CHIP MessageCounter:106194597 from RetransTable on exchange 46506i
[1659605954.366373][4022:4027] CHIP:DMG: ReportDataMessage =
[1659605954.366442][4022:4027] CHIP:DMG: {
[1659605954.366504][4022:4027] CHIP:DMG: SubscriptionId = 0x3ab5320,
[1659605954.366567][4022:4027] CHIP:DMG: AttributeReportIBs =
[1659605954.366642][4022:4027] CHIP:DMG: [
[1659605954.366703][4022:4027] CHIP:DMG: AttributeReportIB =
[1659605954.366790][4022:4027] CHIP:DMG: {
[1659605954.366855][4022:4027] CHIP:DMG: AttributeDataIB =
[1659605954.366929][4022:4027] CHIP:DMG: {
[1659605954.367011][4022:4027] CHIP:DMG: DataVersion = 0x2442fc24,
[1659605954.367090][4022:4027] CHIP:DMG: AttributePathIB =
[1659605954.367171][4022:4027] CHIP:DMG: {
[1659605954.367260][4022:4027] CHIP:DMG: Endpoint = 0x0,
[1659605954.367346][4022:4027] CHIP:DMG: Cluster = 0x30,
[1659605954.367429][4022:4027] CHIP:DMG: Attribute = 0x0000_0000,
[1659605954.367507][4022:4027] CHIP:DMG: }
[1659605954.367593][4022:4027] CHIP:DMG:
[1659605954.367724][4022:4027] CHIP:DMG: Data = 2,
[1659605954.367803][4022:4027] CHIP:DMG: },
[1659605954.367879][4022:4027] CHIP:DMG:
[1659605954.367947][4022:4027] CHIP:DMG: },
[1659605954.368020][4022:4027] CHIP:DMG:
[1659605954.368079][4022:4027] CHIP:DMG: ],
[1659605954.368151][4022:4027] CHIP:DMG:
[1659605954.368210][4022:4027] CHIP:DMG: InteractionModelRevision = 1
[1659605954.368267][4022:4027] CHIP:DMG: }
[1659605954.368474][4022:4027] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369700
[1659605954.368553][4022:4027] CHIP:TOO: Breadcrumb: 2
[1659605954.368638][4022:4027] CHIP:DMG: MoveToState ReadClient[0xffff94008e40]: Moving to [AwaitingSu]
[1659605954.368743][4022:4027] CHIP:EM: Piggybacking Ack for MessageCounter:48011447 on exchange: 46506i
[1659605954.368867][4022:4027] CHIP:IN: Prepared secure message 0xffff94005878 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 46506i with MessageCounter:106194598.
[1659605954.368952][4022:4027] CHIP:IN: Sending encrypted msg 0xffff94005878 with MessageCounter:106194598 to 0x0000000000000002 (1) at monotonic time: 0000000000F51381 msec
[1659605954.370597][4022:4027] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:48011448 on exchange 46506i
[1659605954.370674][4022:4027] CHIP:EM: Found matching exchange: 46506i, Delegate: 0xffff94008e40
[1659605954.370746][4022:4027] CHIP:EM: Rxd Ack; Removing MessageCounter:106194598 from Retrans Table on exchange 46506i
[1659605954.370808][4022:4027] CHIP:EM: Removed CHIP MessageCounter:106194598 from RetransT
disabled: true
- label:
"Repeat steps 14 to 15, incrementing the Breadcrumb value from the
last write, to verify that TH2 can still receive view-privilege
attribute reports."
verification: |
STEP 14
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
generalcommissioning write breadcrumb 3 1 0
On TH(Chiptool)1,Verify Breadcrumb attribute on the General Commissioning cluster, setting the value to 3
[1659606118.724408][4143:4148] CHIP:EM: Flushed pending ack for MessageCounter:200773521 on exchange 53589i
[1659606118.726074][4143:4148] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:133368587 on exchange 53590i
[1659606118.726120][4143:4148] CHIP:EM: Found matching exchange: 53590i, Delegate: 0xffff90003c50
[1659606118.726171][4143:4148] CHIP:EM: Rxd Ack; Removing MessageCounter:14384618 from Retrans Table on exchange 53590i
[1659606118.726207][4143:4148] CHIP:EM: Removed CHIP MessageCounter:14384618 from RetransTable on exchange 53590i
[1659606118.726249][4143:4148] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659606118.726311][4143:4148] CHIP:DMG: WriteResponseMessage =
[1659606118.726347][4143:4148] CHIP:DMG: {
[1659606118.726379][4143:4148] CHIP:DMG: AttributeStatusIBs =
[1659606118.726423][4143:4148] CHIP:DMG: [
[1659606118.726459][4143:4148] CHIP:DMG: AttributeStatusIB =
[1659606118.726502][4143:4148] CHIP:DMG: {
[1659606118.726537][4143:4148] CHIP:DMG: AttributePathIB =
[1659606118.726580][4143:4148] CHIP:DMG: {
[1659606118.726624][4143:4148] CHIP:DMG: Endpoint = 0x0,
[1659606118.726673][4143:4148] CHIP:DMG: Cluster = 0x30,
[1659606118.726718][4143:4148] CHIP:DMG: Attribute = 0x0000_0000,
[1659606118.726761][4143:4148] CHIP:DMG: }
[1659606118.726808][4143:4148] CHIP:DMG:
[1659606118.726853][4143:4148] CHIP:DMG: StatusIB =
[1659606118.726894][4143:4148] CHIP:DMG: {
[1659606118.726934][4143:4148] CHIP:DMG: status = 0x00 (SUCCESS),
[1659606118.726997][4143:4148] CHIP:DMG: },
[1659606118.727039][4143:4148] CHIP:DMG:
[1659606118.727126][4143:4148] CHIP:DMG: },
[1659606118.727170][4143:4148] CHIP:DMG:
[1659606118.727205][4143:4148] CHIP:DMG: ],
[1659606118.727273][4143:4148] CHIP:DMG:
[1659606118.727309][4143:4148] CHIP:DMG: InteractionModelRevision = 1
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 15:generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify Breadcrumb attribute on the General Commissioning cluster as 3
[1659606357.654547][4051:4056] CHIP:EM: Rxd Ack; Removing MessageCounter:35929555 from Retrans Table on exchange 22098i
[1659606357.654600][4051:4056] CHIP:EM: Removed CHIP MessageCounter:35929555 from RetransTable on exchange 22098i
[1659606357.654702][4051:4056] CHIP:DMG: ReportDataMessage =
[1659606357.654762][4051:4056] CHIP:DMG: {
[1659606357.654814][4051:4056] CHIP:DMG: SubscriptionId = 0xc7cb41ba,
[1659606357.654865][4051:4056] CHIP:DMG: AttributeReportIBs =
[1659606357.654930][4051:4056] CHIP:DMG: [
[1659606357.654982][4051:4056] CHIP:DMG: AttributeReportIB =
[1659606357.655051][4051:4056] CHIP:DMG: {
[1659606357.655105][4051:4056] CHIP:DMG: AttributeDataIB =
[1659606357.655186][4051:4056] CHIP:DMG: {
[1659606357.655256][4051:4056] CHIP:DMG: DataVersion = 0x2442fc25,
[1659606357.655320][4051:4056] CHIP:DMG: AttributePathIB =
[1659606357.655390][4051:4056] CHIP:DMG: {
[1659606357.655461][4051:4056] CHIP:DMG: Endpoint = 0x0,
[1659606357.655539][4051:4056] CHIP:DMG: Cluster = 0x30,
[1659606357.655649][4051:4056] CHIP:DMG: Attribute = 0x0000_0000,
[1659606357.655702][4051:4056] CHIP:DMG: }
[1659606357.655742][4051:4056] CHIP:DMG:
[1659606357.655778][4051:4056] CHIP:DMG: Data = 3,
[1659606357.655826][4051:4056] CHIP:DMG: },
[1659606357.655863][4051:4056] CHIP:DMG:
[1659606357.655891][4051:4056] CHIP:DMG: },
[1659606357.655923][4051:4056] CHIP:DMG:
[1659606357.655948][4051:4056] CHIP:DMG: ],
[1659606357.655980][4051:4056] CHIP:DMG:
[1659606357.656006][4051:4056] CHIP:DMG: InteractionModelRevision = 1
[1659606357.656031][4051:4056] CHIP:DMG: }
[1659606357.656158][4051:4056] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369701
[1659606357.656213][4051:4056] CHIP:TOO: Breadcrumb: 3
[1659606357.656264][4051:4056] CHIP:DMG: MoveToState ReadClient[0xffff78008ef0]: Moving to [AwaitingSu]
[1659606357.656316][4051:4056] CHIP:EM: Piggybacking Ack for MessageCounter:42249855 on exchange: 22098i
[1659606357.656374][4051:4056] CHIP:IN: Prepared secure message 0xffff78005928 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 22098i with MessageCounter:35929556.
[1659606357.656413][4051:4056] CHIP:IN: Sending encrypted msg 0xffff78005928 with MessageCounter:35929556 to 0x0000000000000002 (1) at monotonic time: 0000000000FB3AD8 msec
[1659606357.657758][4051:4056] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:42249856 on exchange 22098i
[1659606357.657791][4051:4056] CHIP:EM: Found matching exchange: 22098i, Delegate: 0xffff78008ef0
[1659606357.657822][4051:4056] CHIP:EM: Rxd Ack; Removing MessageCounter:35929556 from Retrans Table on exchange 2209
disabled: true
- label:
"TH1 writes AccessControl cluster ACL attribute, value is a list of
AccessControlEntryStruct containing 2 elements: struct Privilege
field: Administer (5) AuthMode field: CASE (2) Subjects field: [N1]
Targets field: [{Endpoint: 0}] struct Privilege field: Operate (3)
AuthMode field: CASE (2) Subjects field: [N2] Targets field:
[{Endpoint: 0}]"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
accesscontrol write acl '[{"fabricIndex": 1, "privilege": 5, "authMode": 2, "subjects": [112233], "targets": [{ "cluster": null, "endpoint": 0, "deviceType": null }]},{"fabricIndex": 1, "privilege": 3, "authMode": 2, "subjects": [112233], "targets": [{ "cluster": null, "endpoint": 0, "deviceType": null }]}]' 1 0
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659523745.098149][11363:11368] CHIP:EM: Flushed pending ack for MessageCounter:126320339 on exchange 61753i
[1659523745.112492][11363:11368] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:214127286 on exchange 61754i
[1659523745.112533][11363:11368] CHIP:EM: Found matching exchange: 61754i, Delegate: 0xffff78003f40
[1659523745.112575][11363:11368] CHIP:EM: Rxd Ack; Removing MessageCounter:89140528 from Retrans Table on exchange 61754i
[1659523745.112597][11363:11368] CHIP:EM: Removed CHIP MessageCounter:89140528 from RetransTable on exchange 61754i
[1659523745.112631][11363:11368] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659523745.112690][11363:11368] CHIP:DMG: WriteResponseMessage =
[1659523745.112713][11363:11368] CHIP:DMG: {
[1659523745.112734][11363:11368] CHIP:DMG: AttributeStatusIBs =
[1659523745.112794][11363:11368] CHIP:DMG: [
[1659523745.112822][11363:11368] CHIP:DMG: AttributeStatusIB =
[1659523745.112855][11363:11368] CHIP:DMG: {
[1659523745.112898][11363:11368] CHIP:DMG: AttributePathIB =
[1659523745.112939][11363:11368] CHIP:DMG: {
[1659523745.112974][11363:11368] CHIP:DMG: Endpoint = 0x0,
[1659523745.113011][11363:11368] CHIP:DMG: Cluster = 0x1f,
[1659523745.113050][11363:11368] CHIP:DMG: Attribute = 0x0000_0000,
[1659523745.113086][11363:11368] CHIP:DMG: }
[1659523745.113123][11363:11368] CHIP:DMG:
[1659523745.113197][11363:11368] CHIP:DMG: StatusIB =
[1659523745.113235][11363:11368] CHIP:DMG: {
[1659523745.113280][11363:11368] CHIP:DMG: status = 0x00 (SUCCESS),
[1659523745.113315][11363:11368] CHIP:DMG: },
[1659523745.113357][11363:11368] CHIP:DMG:
[1659523745.113386][11363:11368] CHIP:DMG: },
[1659523745.113434][11363:11368] CHIP:DMG:
[1659523745.113462][11363:11368] CHIP:DMG: AttributeStatusIB =
[1659523745.113507][11363:11368] CHIP:DMG: {
[1659523745.113537][11363:11368] CHIP:DMG: AttributePathIB =
[1659523745.113578][11363:11368] CHIP:DMG: {
[1659523745.113615][11363:11368] CHIP:DMG: Endpoint = 0x0,
[1659523745.113651][11363:11368] CHIP:DMG: Cluster = 0x1f,
[1659523745.113697][11363:11368] CHIP:DMG: Attribute = 0x0000_0000,
[1659523745.113742][11363:11368] CHIP:DMG: ListIndex = Null,
[1659523745.113777][11363:11368] CHIP:DMG: }
[1659523745.113824][11363:11368] CHIP:DMG:
[1659523745.113856][11363:11368] CHIP:DMG: StatusIB =
[1659523745.113898][11363:11368] CHIP:DMG: {
[1659523745.113932][11363:11368] CHIP:DMG: status = 0x00 (SUCCESS),
[1659523745.113975][11363:11368] CHIP:DMG: },
[1659523745.114009][11363:11368] CHIP:DMG:
[1659523745.114037][11363:11368] CHIP:DMG: },
[1659523745.114086][11363:11368] CHIP:DMG:
[1659523745.114121][11363:11368] CHIP:DMG: AttributeStatusIB =
[1659523745.114152][11363:11368] CHIP:DMG: {
[1659523745.114190][11363:11368] CHIP:DMG: AttributePathIB =
[1659523745.114223][11363:11368] CHIP:DMG: {
[1659523745.114267][11363:11368] CHIP:DMG: Endpoint = 0x0,
[1659523745.114302][11363:11368] CHIP:DMG: Cluster = 0x1f,
[1659523745.114346][11363:11368] CHIP:DMG: Attribute = 0x0000_0000,
[1659523745.114389][11363:11368] CHIP:DMG: ListIndex = Null,
[1659523745.114425][11363:11368] CHIP:DMG: }
[1659523745.114463][11363:11368] CHIP:DMG:
[1659523745.114494][11363:11368] CHIP:DMG: StatusIB =
[1659523745.114529][11363:11368] CHIP:DMG: {
[1659523745.114562][11363:11368] CHIP:DMG: status = 0x00 (SUCCESS),
[1659523745.114594][11363:11368] CHIP:DMG: },
[1659523745.114627][11363:11368] CHIP:DMG:
[1659523745.114655][11363:11368] CHIP:DMG: },
[1659523745.114687][11363:11368] CHIP:DMG:
[1659523745.114712][11363:11368] CHIP:DMG: ],
[1659523745.114750][11363:11368] CHIP:DMG:
[1659523745.114774][11363:11368] CHIP:DMG: InteractionModelRevision = 1
[1659523745.114799][11363:11368] CHIP:DMG: }
[1659523745.114942][11363:11368] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659523745.115009][11363:11368] CHIP:EM: Sending Standalone Ack for MessageCounter:214127286 on exchange 61754i
[1659523745.115090][11363:11368] CHIP:IN: Prepared secure message 0xffff7fffd9e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 61754i with MessageCounter:89140529.
[1659523745.115131][11363:11368] CHIP:IN: Sending encrypted msg 0xffff7fffd9e8 with MessageCounter:89140529 to 0x0000000000000001 (1) at monotonic time: 0000000006854BB3 msec
disabled: true
- label:
"Repeat steps 14 to 15, incrementing the Breadcrumb value from the
last write, to verify that TH2 can still receive view-privilege
attribute reports."
verification: |
STEP 14
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
generalcommissioning write breadcrumb 4 1 0
On TH(Chiptool)1,Verify Breadcrumb attribute on the General Commissioning cluster, setting the value to 4
[1659606523.334582][4167:4172] CHIP:EM: Found matching exchange: 31451i, Delegate: 0xffff88003f40
[1659606523.334668][4167:4172] CHIP:EM: Rxd Ack; Removing MessageCounter:78936906 from Retrans Table on exchange 31451i
[1659606523.334733][4167:4172] CHIP:EM: Removed CHIP MessageCounter:78936906 from RetransTable on exchange 31451i
[1659606523.334806][4167:4172] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659606523.334908][4167:4172] CHIP:DMG: WriteResponseMessage =
[1659606523.334973][4167:4172] CHIP:DMG: {
[1659606523.335029][4167:4172] CHIP:DMG: AttributeStatusIBs =
[1659606523.335100][4167:4172] CHIP:DMG: [
[1659606523.335154][4167:4172] CHIP:DMG: AttributeStatusIB =
[1659606523.335275][4167:4172] CHIP:DMG: {
[1659606523.335352][4167:4172] CHIP:DMG: AttributePathIB =
[1659606523.335441][4167:4172] CHIP:DMG: {
[1659606523.335533][4167:4172] CHIP:DMG: Endpoint = 0x0,
[1659606523.335627][4167:4172] CHIP:DMG: Cluster = 0x30,
[1659606523.335719][4167:4172] CHIP:DMG: Attribute = 0x0000_0000,
[1659606523.335806][4167:4172] CHIP:DMG: }
[1659606523.335903][4167:4172] CHIP:DMG:
[1659606523.335966][4167:4172] CHIP:DMG: StatusIB =
[1659606523.336054][4167:4172] CHIP:DMG: {
[1659606523.336137][4167:4172] CHIP:DMG: status = 0x00 (SUCCESS),
[1659606523.336216][4167:4172] CHIP:DMG: },
[1659606523.336289][4167:4172] CHIP:DMG:
[1659606523.336349][4167:4172] CHIP:DMG: },
[1659606523.336428][4167:4172] CHIP:DMG:
[1659606523.336487][4167:4172] CHIP:DMG: ],
[1659606523.336561][4167:4172] CHIP:DMG:
[1659606523.336620][4167:4172] CHIP:DMG: InteractionModelRevision = 1
[1659606523.336679][4167:4172] CHIP:DMG: }
[1659606523.336839][4167:4172] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659606523.336945][4167:4172] CHIP:EM: Sending Standalone Ack for MessageCounter:233663941 on exchange 31451i
[1659606523.337083][4167:4172] CHIP:IN: Prepared secure message 0xffff9e3fc9e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 31451i with MessageCounter:78936907.
[1659606523.337184][4167:4172] CHIP:IN: Sending encrypted msg 0xffff9e3fc9e8 with MessageCounter:78936907 to 0x000000
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
Step 15: generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify Breadcrumb attribute on the General Commissioning cluster as 4 [1659606586.957973][4051:4056] CHIP:IN: Sending encrypted msg 0xffff78005928 with MessageCounter:35929566 to 0x0000000000000002 (1) at monotonic time: 0000000000FEBA8E msec
[1659606586.958178][4051:4056] CHIP:DMG: MoveToState ReadClient[0xffff78008fe0]: Moving to [AwaitingIn]
[1659606586.962174][4051:4056] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:42249873 on exchange 22099i
[1659606586.962225][4051:4056] CHIP:EM: Found matching exchange: 22099i, Delegate: 0xffff78008fe0
[1659606586.962266][4051:4056] CHIP:EM: Rxd Ack; Removing MessageCounter:35929566 from Retrans Table on exchange 22099i
[1659606586.962291][4051:4056] CHIP:EM: Removed CHIP MessageCounter:35929566 from RetransTable on exchange 22099i
[1659606586.962352][4051:4056] CHIP:DMG: ReportDataMessage =
[1659606586.962382][4051:4056] CHIP:DMG: {
[1659606586.962407][4051:4056] CHIP:DMG: SubscriptionId = 0x9013dbc,
[1659606586.962433][4051:4056] CHIP:DMG: AttributeReportIBs =
[1659606586.962464][4051:4056] CHIP:DMG: [
[1659606586.962489][4051:4056] CHIP:DMG: AttributeReportIB =
[1659606586.962522][4051:4056] CHIP:DMG: {
[1659606586.962548][4051:4056] CHIP:DMG: AttributeDataIB =
[1659606586.962584][4051:4056] CHIP:DMG: {
[1659606586.962617][4051:4056] CHIP:DMG: DataVersion = 0x2442fc26,
[1659606586.962653][4051:4056] CHIP:DMG: AttributePathIB =
[1659606586.962691][4051:4056] CHIP:DMG: {
[1659606586.962730][4051:4056] CHIP:DMG: Endpoint = 0x0,
[1659606586.962769][4051:4056] CHIP:DMG: Cluster = 0x30,
[1659606586.962809][4051:4056] CHIP:DMG: Attribute = 0x0000_0000,
[1659606586.962846][4051:4056] CHIP:DMG: }
[1659606586.962881][4051:4056] CHIP:DMG:
[1659606586.962916][4051:4056] CHIP:DMG: Data = 4,
[1659606586.962951][4051:4056] CHIP:DMG: },
[1659606586.962985][4051:4056] CHIP:DMG:
[1659606586.963010][4051:4056] CHIP:DMG: },
[1659606586.963041][4051:4056] CHIP:DMG:
[1659606586.963065][4051:4056] CHIP:DMG: ],
[1659606586.963097][4051:4056] CHIP:DMG:
[1659606586.963121][4051:4056] CHIP:DMG: InteractionModelRevision = 1
[1659606586.963144][4051:4056] CHIP:DMG: }
[1659606586.963238][4051:4056] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369702
[1659606586.963276][4051:4056] CHIP:TOO: Breadcrumb: 4
[1659606586.963313][4051:4056] CHIP:DMG: MoveToState ReadClient[0xffff78008fe0]: Moving to [AwaitingSu]
[1659606586.963374][4051:4056] CHIP:EM: Piggybacking Ack for MessageCounter:42249873 on exchange: 22099i
[1659606586.963444][4051:4056] CHIP:IN: Prepared secure message 0xffff78005928 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 22099i with MessageCounter:35929567.
[1659606586.963484][4051:4056] CHIP:IN: Sending encrypted msg 0xffff78005928 with MessageCounter:35929567 to 0x0000000000000002 (1) at monotonic time: 0000000000FEBA93 msec
[1659606586.965291][4051:4056] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:42249874 on exchange 22099i
[1659606586.965336][4051:4056] CHIP:EM: Found matching exchange: 22099i, Delegate: 0xffff78008fe0
[1659606586.965371][4051:4056] CHIP:EM: Rxd Ack; Removing MessageCounter:35929567 from Retrans Table on exchange 22099i
disabled: true
- label:
"Repeat steps 18 to 19 to ensure TH2 still does not have permissions
to subscribe to administer-privilege attributes and events"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 18: operationalcredentials subscribe nocs 1 30 2 0 --fabric-filtered false --keepSubscriptions 0
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659612832.868704][4965:4970] CHIP:DMG: ] (231 bytes)
[1659612832.868755][4965:4970] CHIP:DMG: 0xfe = 2,
[1659612832.868801][4965:4970] CHIP:DMG: },
[1659612832.868846][4965:4970] CHIP:DMG: },
[1659612832.868895][4965:4970] CHIP:DMG:
[1659612832.868931][4965:4970] CHIP:DMG: },
[1659612832.868977][4965:4970] CHIP:DMG:
[1659612832.869009][4965:4970] CHIP:DMG: ],
[1659612832.869063][4965:4970] CHIP:DMG:
[1659612832.869096][4965:4970] CHIP:DMG: InteractionModelRevision = 1
[1659612832.869128][4965:4970] CHIP:DMG: }
[1659612832.869489][4965:4970] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_003E Attribute 0x0000_0000 DataVersion: 296706937
[1659612832.869578][4965:4970] CHIP:TOO: NOCs: 2 entries
[1659612832.869639][4965:4970] CHIP:TOO: [1]: {
[1659612832.869676][4965:4970] CHIP:TOO: Noc:
[1659612832.869707][4965:4970] CHIP:TOO: Icac: null
[1659612832.869741][4965:4970] CHIP:TOO: FabricIndex: 1
[1659612832.869772][4965:4970] CHIP:TOO: }
[1659612832.869816][4965:4970] CHIP:TOO: [2]: {
[1659612832.869865][4965:4970] CHIP:TOO: Noc: 1530010101240201370324130118260480228127260580254D3A37062415012411021824070124080130094104DD7D445EB8321A5B25DC788835CF2FA5332AA2CA25659D68A15168A75A20D4C72846A44FEE2AFB6E2E7EA39BB2A65D0FC2548A51E42A7D7FEAA005EBD481121F370A350128011824020136030402040118300414A02C8D19F9BF72351909681A02E5BD88122780F5300514C7E958D6860E58EBFFC50375695FA10D8D1F7BA518300B40BC7C77F5CF49D8DB52F41529C48CD8122C3AAFE1224B0035738AA447527D192699964EDA250DC340D6AFC4ED14524DF8F53EF483B292326E97E12899A8B90FD418
[1659612832.869918][4965:4970] CHIP:TOO: Icac: 1530010100240201370324140018260480228127260580254D3A37062413011824070124080130094104B261C938B93273CC4895790193D5A154529A039D23E552263B7AA94A8F34448130E7A4C88EE71AE291BBC689E9B7D1F8C81FE3FE8C5DADFF98665DBF57D0739E370A3501290118240260300414C7E958D6860E58EBFFC50375695FA10D8D1F7BA5300514F6EF1D69A5F7797D534AB655E8E8BFA390CB562018300B40F2B2E6A0C20F819DAA4DA6C5C94B1F63686C3E11BED7FEF4E35643E795FFA3AEE4333AD726EE1EC492A29A45628EE95D88CCBAB078060BFD565CA52298A9B0AF18
[1659612832.869956][4965:4970] CHIP:TOO: FabricIndex: 2
[1659612832.869986][4965:4970] CHIP:TOO: }
[1659612832.870046][4965:4970] CHIP:DMG: MoveToState ReadClient[0xffff68009050]: Moving to [AwaitingSu]
[1659612832.870110][4965:4970] CHIP:EM: Piggybacking Ack for MessageCounter:260335018 on exchange: 46941i
[1659612832.870188][4965:4970] CHIP:IN: Prepared secure message 0xaaab20de5348 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 46941i with MessageCounter:62837154.
[1659612832.870239][4965:4970] CHIP:IN: Sending encrypted msg 0xaaab20de5348 with MessageCounter:62837154 to 0x0000000000000002 (1) at monotonic time: 00000000015E08A6 msec
[1659612832.872062][4965:4970] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:260335019 on exchange 46941i
[1659612832.872107][4965:4970] CHIP:EM: Found matching exchange: 46941i, Delegate: 0xffff68009050
[1659612832.872149][4965:4970] CHIP:EM: Rxd Ack; Removing MessageCounter:62837154 from Retrans Table on exchange 46941i
[1659612832.872182][4965:4970] CHIP:EM: Removed CHIP MessageCounter:62837154 from RetransTable on exchange 46941i
[1659612832.872236][4965:4970] CHIP:DMG: SubscribeResponseMessage =
[1659612832.872273][4965:4970] CHIP:DMG: {
[1659612832.872305][4965:4970] CHIP:DMG: SubscriptionId = 0x3277cd67,
[1659612832.872340][4965:4970] CHIP:DMG: MaxInterval = 0x1e,
[1659612832.872373][4965:4970] CHIP:DMG: InteractionModelRevision = 1
[1659612832.872405][4965:4970] CHIP:DMG: }
[1659612832.872440][4965:4970] CHIP:DMG: Subscription established with SubscriptionID = 0x3277cd67 MinInterval = 1s MaxInterval = 30s Peer = 01:0000000000000002
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 19 : accesscontrol subscribe-event access-control-entry-changed 1 30 2 0 --keepSubscriptions 1
[1659610892.416754][4592:4597] CHIP:EM: Found matching exchange: 39361r, Delegate: (nil)
[1659610892.416830][4592:4597] CHIP:EM: Rxd Ack; Removing MessageCounter:22301879 from Retrans Table on exchange 39361r
[1659610892.416890][4592:4597] CHIP:EM: Removed CHIP MessageCounter:22301879 from RetransTable on exchange 39361r
[1659610922.443438][4592:4597] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:229300487 on exchange 39362r
[1659610922.443571][4592:4597] CHIP:EM: Handling via exchange: 39362r, Delegate: 0xaaaabd30d5a8
[1659610922.443783][4592:4597] CHIP:DMG: ReportDataMessage =
[1659610922.443853][4592:4597] CHIP:DMG: {
[1659610922.443915][4592:4597] CHIP:DMG: SubscriptionId = 0x731365d6,
[1659610922.443979][4592:4597] CHIP:DMG: InteractionModelRevision = 1
[1659610922.444038][4592:4597] CHIP:DMG: }
[1659610922.444136][4592:4597] CHIP:DMG: Refresh LivenessCheckTime for 55000 milliseconds with SubscriptionId = 0x731365d6 Peer = 01:0000000000000002
[1659610922.444243][4592:4597] CHIP:EM: Piggybacking Ack for MessageCounter:229300487 on exchange: 39362r
[1659610922.444369][4592:4597] CHIP:IN: Prepared secure message 0xaaaabfb9a348 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 39362r with MessageCounter:22301880.
[1659610922.444458][4592:4597] CHIP:IN: Sending encrypted msg 0xaaaabfb9a348 with MessageCounter:22301880 to 0x0000000000000002 (1) at monotonic time: 000000000140E20C msec
[1659610922.446582][4592:4597] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:229300488 on exchange 39362r
[1659610922.446661][4592:4597] CHIP:EM: Found matching exchange: 39362r, Delegate: (nil)
[1659610922.446736][4592:4597] CHIP:EM: Rxd Ack; Removing MessageCounter:22301880 from Retrans Table on exchange 39362r
[1659610922.446795][4592:4597] CHIP:EM: Removed CHIP MessageCounter:22301880 from RetransTable on exchange 39362r
disabled: true
- label: "Repeat step 5 to ensure TH2 can still subscribe to view events"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 5: generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659606749.724339][4073:4078] CHIP:EM: Removed CHIP MessageCounter:226909963 from RetransTable on exchange 4224i
[1659606749.724415][4073:4078] CHIP:DMG: ReportDataMessage =
[1659606749.724456][4073:4078] CHIP:DMG: {
[1659606749.724492][4073:4078] CHIP:DMG: SubscriptionId = 0x23a4288b,
[1659606749.724529][4073:4078] CHIP:DMG: AttributeReportIBs =
[1659606749.724574][4073:4078] CHIP:DMG: [
[1659606749.724610][4073:4078] CHIP:DMG: AttributeReportIB =
[1659606749.724657][4073:4078] CHIP:DMG: {
[1659606749.724695][4073:4078] CHIP:DMG: AttributeDataIB =
[1659606749.724740][4073:4078] CHIP:DMG: {
[1659606749.724787][4073:4078] CHIP:DMG: DataVersion = 0x2442fc26,
[1659606749.724835][4073:4078] CHIP:DMG: AttributePathIB =
[1659606749.724884][4073:4078] CHIP:DMG: {
[1659606749.724933][4073:4078] CHIP:DMG: Endpoint = 0x0,
[1659606749.724984][4073:4078] CHIP:DMG: Cluster = 0x30,
[1659606749.725034][4073:4078] CHIP:DMG: Attribute = 0x0000_0000,
[1659606749.725082][4073:4078] CHIP:DMG: }
[1659606749.725133][4073:4078] CHIP:DMG:
[1659606749.725183][4073:4078] CHIP:DMG: Data = 4,
[1659606749.725229][4073:4078] CHIP:DMG: },
[1659606749.725277][4073:4078] CHIP:DMG:
[1659606749.725314][4073:4078] CHIP:DMG: },
[1659606749.725358][4073:4078] CHIP:DMG:
[1659606749.725394][4073:4078] CHIP:DMG: ],
[1659606749.725438][4073:4078] CHIP:DMG:
[1659606749.725473][4073:4078] CHIP:DMG: InteractionModelRevision = 1
[1659606749.725507][4073:4078] CHIP:DMG: }
[1659606749.725673][4073:4078] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369702
[1659606749.725744][4073:4078] CHIP:TOO: Breadcrumb: 4
[1659606749.725813][4073:4078] CHIP:DMG: MoveToState ReadClient[0xffff68008e40]: Moving to [AwaitingSu]
[1659606749.725881][4073:4078] CHIP:EM: Piggybacking Ack for MessageCounter:54749189 on exchange: 4224i
[1659606749.725979][4073:4078] CHIP:IN: Prepared secure message 0xffff68005878 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 4224i with MessageCounter:226909964.
[1659606749.726034][4073:4078] CHIP:IN: Sending encrypted msg 0xffff68005878 with MessageCounter:226909964 to 0x0000000000000002 (1) at monotonic time: 000000000101365E msec
[1659606749.727312][4073:4078] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:54749190 on exchange 4224i
[1659606749.727361][4073:4078] CHIP:EM: Found matching exchange: 4224i, Delegate: 0xffff68008e40
[1659606749.727405][4073:4078] CHIP:EM: Rxd Ack; Removing MessageCounter:226909964 from Retrans Table on exchange 4224i
[1659606749.727440][4073:4078] CHIP:EM: Removed CHIP MessageCounter:226909964 from RetransTable on exchange 4224i
[1659606749.727501][4073:4078] CHIP:DMG: SubscribeResponseMessage =
[1659606749.727540][4073:4078] CHIP:DMG: {
disabled: true
- label:
"Repeat steps 14 to 15, incrementing the Breadcrumb value from the
last write, to verify that TH2 can still receive view-privilege
attribute reports."
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 14: generalcommissioning write breadcrumb 5 1 0
On TH(Chiptool)1,Verify Breadcrumb attribute on the General Commissioning cluster, setting the value to 5
[1659606888.234292][4313:4318] CHIP:EM: Rxd Ack; Removing MessageCounter:108149285 from Retrans Table on exchange 50383i
[1659606888.234318][4313:4318] CHIP:EM: Removed CHIP MessageCounter:108149285 from RetransTable on exchange 50383i
[1659606888.234355][4313:4318] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659606888.234408][4313:4318] CHIP:DMG: WriteResponseMessage =
[1659606888.234435][4313:4318] CHIP:DMG: {
[1659606888.234458][4313:4318] CHIP:DMG: AttributeStatusIBs =
[1659606888.234495][4313:4318] CHIP:DMG: [
[1659606888.234527][4313:4318] CHIP:DMG: AttributeStatusIB =
[1659606888.234563][4313:4318] CHIP:DMG: {
[1659606888.234594][4313:4318] CHIP:DMG: AttributePathIB =
[1659606888.234631][4313:4318] CHIP:DMG: {
[1659606888.234669][4313:4318] CHIP:DMG: Endpoint = 0x0,
[1659606888.234707][4313:4318] CHIP:DMG: Cluster = 0x30,
[1659606888.234746][4313:4318] CHIP:DMG: Attribute = 0x0000_0000,
[1659606888.234781][4313:4318] CHIP:DMG: }
[1659606888.234822][4313:4318] CHIP:DMG:
[1659606888.234856][4313:4318] CHIP:DMG: StatusIB =
[1659606888.234893][4313:4318] CHIP:DMG: {
[1659606888.234929][4313:4318] CHIP:DMG: status = 0x00 (SUCCESS),
[1659606888.234965][4313:4318] CHIP:DMG: },
[1659606888.235001][4313:4318] CHIP:DMG:
[1659606888.235033][4313:4318] CHIP:DMG: },
[1659606888.235070][4313:4318] CHIP:DMG:
[1659606888.235099][4313:4318] CHIP:DMG: ],
[1659606888.235135][4313:4318] CHIP:DMG:
[1659606888.235165][4313:4318] CHIP:DMG: InteractionModelRevision = 1
[1659606888.235194][4313:4318] CHIP:DMG: }
[1659606888.235306][4313:4318] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659606888.235378][4313:4318] CHIP:EM: Sending Standalone Ack for MessageCounter:173642077 on exchange 50383i
[1659606888.235464][4313:4318] CHIP:IN: Prepared secure message 0xffffb9e1c9e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 50383i with MessageCounter:108149286.
[1659606888.235526][4313:4318] CHIP:IN: Sending encrypted msg 0xffffb9e1c9e8 with MessageCounter:108149286 to 0x0000000000000001 (1) at monotonic time: 00000000010366D0 msec
[1659606888.235680][4313:4318] CHIP:EM: Flushed pending ack for MessageCounter:173642077 on exchange 50383i
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP15: generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify Breadcrumb attribute on the General Commissioning cluster as 5
[1659606962.525141][4073:4078] CHIP:DMG: MoveToState ReadClient[0xffff68008e40]: Moving to [AwaitingIn]
[1659606962.527188][4073:4078] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:54749194 on exchange 4225i
[1659606962.527241][4073:4078] CHIP:EM: Found matching exchange: 4225i, Delegate: 0xffff68008e40
[1659606962.527295][4073:4078] CHIP:EM: Rxd Ack; Removing MessageCounter:226909968 from Retrans Table on exchange 4225i
[1659606962.527336][4073:4078] CHIP:EM: Removed CHIP MessageCounter:226909968 from RetransTable on exchange 4225i
[1659606962.527419][4073:4078] CHIP:DMG: ReportDataMessage =
[1659606962.527465][4073:4078] CHIP:DMG: {
[1659606962.527505][4073:4078] CHIP:DMG: SubscriptionId = 0xf17ee8e5,
[1659606962.527546][4073:4078] CHIP:DMG: AttributeReportIBs =
[1659606962.527596][4073:4078] CHIP:DMG: [
[1659606962.527670][4073:4078] CHIP:DMG: AttributeReportIB =
[1659606962.527724][4073:4078] CHIP:DMG: {
[1659606962.527766][4073:4078] CHIP:DMG: AttributeDataIB =
[1659606962.527823][4073:4078] CHIP:DMG: {
[1659606962.527876][4073:4078] CHIP:DMG: DataVersion = 0x2442fc27,
[1659606962.527934][4073:4078] CHIP:DMG: AttributePathIB =
[1659606962.527991][4073:4078] CHIP:DMG: {
[1659606962.528046][4073:4078] CHIP:DMG: Endpoint = 0x0,
[1659606962.528105][4073:4078] CHIP:DMG: Cluster = 0x30,
[1659606962.528162][4073:4078] CHIP:DMG: Attribute = 0x0000_0000,
[1659606962.528218][4073:4078] CHIP:DMG: }
[1659606962.528274][4073:4078] CHIP:DMG:
[1659606962.528330][4073:4078] CHIP:DMG: Data = 5,
[1659606962.528380][4073:4078] CHIP:DMG: },
[1659606962.528433][4073:4078] CHIP:DMG:
[1659606962.528478][4073:4078] CHIP:DMG: },
[1659606962.528526][4073:4078] CHIP:DMG:
[1659606962.528565][4073:4078] CHIP:DMG: ],
[1659606962.528613][4073:4078] CHIP:DMG:
[1659606962.528651][4073:4078] CHIP:DMG: InteractionModelRevision = 1
[1659606962.528690][4073:4078] CHIP:DMG: }
[1659606962.528835][4073:4078] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369703
[1659606962.528889][4073:4078] CHIP:TOO: Breadcrumb: 5
[1659606962.528948][4073:4078] CHIP:DMG: MoveToState ReadClient[0xffff68008e40]: Moving to [AwaitingSu]
[1659606962.529021][4073:4078] CHIP:EM: Piggybacking Ack for MessageCounter:54749194 on exchange: 4225i
[1659606962.529107][4073:4078] CHIP:IN: Prepared secure message 0xffff68005878 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 4225i with MessageCounter:226909969.
[1659606962.529166][4073:4078] CHIP:IN: Sending encrypted msg 0xffff68005878 with MessageCounter:226909969 to 0x0000000000000002 (1) at monotonic time: 00000000010475A1 msec
disabled: true
- label:
"TH1 writes AccessControl cluster ACL attribute, value is a list of
AccessControlEntryStruct containing 2 elements: struct Privilege
field: Administer (5) AuthMode field: CASE (2) Subjects field: [N1]
Targets field: [{Endpoint: 0}] struct Privilege field: View (1)
AuthMode field: CASE (2) Subjects field: [N2] Targets field:
[{Endpoint: 0}]"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
accesscontrol write acl '[{"fabricIndex": 1, "privilege": 5, "authMode": 2, "subjects": [112233], "targets": [{ "cluster": null, "endpoint": 0, "deviceType": null }]},{"fabricIndex": 1, "privilege": 1, "authMode": 2, "subjects": [112233], "targets": [{ "cluster": null, "endpoint": 0, "deviceType": null }]}]' 1 0
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 2 elements
[1659523922.046434][11376:11381] CHIP:EM: Flushed pending ack for MessageCounter:126320341 on exchange 32807i
[1659523922.061134][11376:11381] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:86245732 on exchange 32808i
[1659523922.061176][11376:11381] CHIP:EM: Found matching exchange: 32808i, Delegate: 0xffff88003c20
[1659523922.061246][11376:11381] CHIP:EM: Rxd Ack; Removing MessageCounter:91478321 from Retrans Table on exchange 32808i
[1659523922.061275][11376:11381] CHIP:EM: Removed CHIP MessageCounter:91478321 from RetransTable on exchange 32808i
[1659523922.061317][11376:11381] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659523922.061379][11376:11381] CHIP:DMG: WriteResponseMessage =
[1659523922.061409][11376:11381] CHIP:DMG: {
[1659523922.061435][11376:11381] CHIP:DMG: AttributeStatusIBs =
[1659523922.061475][11376:11381] CHIP:DMG: [
[1659523922.061503][11376:11381] CHIP:DMG: AttributeStatusIB =
[1659523922.061535][11376:11381] CHIP:DMG: {
[1659523922.061563][11376:11381] CHIP:DMG: AttributePathIB =
[1659523922.061597][11376:11381] CHIP:DMG: {
[1659523922.061632][11376:11381] CHIP:DMG: Endpoint = 0x0,
[1659523922.061669][11376:11381] CHIP:DMG: Cluster = 0x1f,
[1659523922.061707][11376:11381] CHIP:DMG: Attribute = 0x0000_0000,
[1659523922.061740][11376:11381] CHIP:DMG: }
[1659523922.061777][11376:11381] CHIP:DMG:
[1659523922.061810][11376:11381] CHIP:DMG: StatusIB =
[1659523922.061844][11376:11381] CHIP:DMG: {
[1659523922.061877][11376:11381] CHIP:DMG: status = 0x00 (SUCCESS),
[1659523922.061914][11376:11381] CHIP:DMG: },
[1659523922.061949][11376:11381] CHIP:DMG:
[1659523922.061977][11376:11381] CHIP:DMG: },
[1659523922.062013][11376:11381] CHIP:DMG:
[1659523922.062039][11376:11381] CHIP:DMG: AttributeStatusIB =
[1659523922.062070][11376:11381] CHIP:DMG: {
[1659523922.062102][11376:11381] CHIP:DMG: AttributePathIB =
[1659523922.062132][11376:11381] CHIP:DMG: {
[1659523922.062165][11376:11381] CHIP:DMG: Endpoint = 0x0,
[1659523922.062203][11376:11381] CHIP:DMG: Cluster = 0x1f,
[1659523922.062240][11376:11381] CHIP:DMG: Attribute = 0x0000_0000,
[1659523922.062274][11376:11381] CHIP:DMG: ListIndex = Null,
[1659523922.062304][11376:11381] CHIP:DMG: }
[1659523922.062340][11376:11381] CHIP:DMG:
[1659523922.062372][11376:11381] CHIP:DMG: StatusIB =
[1659523922.062404][11376:11381] CHIP:DMG: {
[1659523922.062438][11376:11381] CHIP:DMG: status = 0x00 (SUCCESS),
[1659523922.062470][11376:11381] CHIP:DMG: },
[1659523922.062503][11376:11381] CHIP:DMG:
[1659523922.062530][11376:11381] CHIP:DMG: },
[1659523922.062568][11376:11381] CHIP:DMG:
[1659523922.062594][11376:11381] CHIP:DMG: AttributeStatusIB =
[1659523922.062622][11376:11381] CHIP:DMG: {
[1659523922.062649][11376:11381] CHIP:DMG: AttributePathIB =
[1659523922.062681][11376:11381] CHIP:DMG: {
[1659523922.062715][11376:11381] CHIP:DMG: Endpoint = 0x0,
[1659523922.062750][11376:11381] CHIP:DMG: Cluster = 0x1f,
[1659523922.062786][11376:11381] CHIP:DMG: Attribute = 0x0000_0000,
[1659523922.062820][11376:11381] CHIP:DMG: ListIndex = Null,
[1659523922.062852][11376:11381] CHIP:DMG: }
[1659523922.062892][11376:11381] CHIP:DMG:
[1659523922.062927][11376:11381] CHIP:DMG: StatusIB =
[1659523922.062959][11376:11381] CHIP:DMG: {
[1659523922.062992][11376:11381] CHIP:DMG: status = 0x00 (SUCCESS),
[1659523922.063025][11376:11381] CHIP:DMG: },
[1659523922.063059][11376:11381] CHIP:DMG:
[1659523922.063086][11376:11381] CHIP:DMG: },
[1659523922.063118][11376:11381] CHIP:DMG:
[1659523922.063143][11376:11381] CHIP:DMG: ],
[1659523922.063182][11376:11381] CHIP:DMG:
[1659523922.063208][11376:11381] CHIP:DMG: InteractionModelRevision = 1
[1659523922.063233][11376:11381] CHIP:DMG: }
[1659523922.063366][11376:11381] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659523922.063432][11376:11381] CHIP:EM: Sending Standalone Ack for MessageCounter:86245732 on exchange 32808i
[1659523922.063512][11376:11381] CHIP:IN: Prepared secure message 0xffff9df749e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 32808i with MessageCounter:91478322.
[1659523922.063554][11376:11381] CHIP:IN: Sending encrypted msg 0xffff9df749e8 with MessageCounter:91478322 to 0x0000000000000001 (1) at mono
disabled: true
- label:
"Repeat steps 14 to 15, incrementing the Breadcrumb value from the
last write, to verify that TH2 can still receive view-privilege
attribute reports."
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 14:generalcommissioning write breadcrumb 6 1 0
On TH(Chiptool)1,Verify Breadcrumb attribute on the General Commissioning cluster, setting the value to 6
[1659607281.691044][4331:4336] CHIP:DMG: WriteClient moving to [AwaitingRe]
[1659607281.692885][4331:4336] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:3467785 on exchange 18037i
[1659607281.692924][4331:4336] CHIP:EM: Found matching exchange: 18037i, Delegate: 0xffff74003c20
[1659607281.692964][4331:4336] CHIP:EM: Rxd Ack; Removing MessageCounter:223044118 from Retrans Table on exchange 18037i
[1659607281.692985][4331:4336] CHIP:EM: Removed CHIP MessageCounter:223044118 from RetransTable on exchange 18037i
[1659607281.693018][4331:4336] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659607281.693065][4331:4336] CHIP:DMG: WriteResponseMessage =
[1659607281.693087][4331:4336] CHIP:DMG: {
[1659607281.693106][4331:4336] CHIP:DMG: AttributeStatusIBs =
[1659607281.693134][4331:4336] CHIP:DMG: [
[1659607281.693154][4331:4336] CHIP:DMG: AttributeStatusIB =
[1659607281.693179][4331:4336] CHIP:DMG: {
[1659607281.693201][4331:4336] CHIP:DMG: AttributePathIB =
[1659607281.693226][4331:4336] CHIP:DMG: {
[1659607281.693252][4331:4336] CHIP:DMG: Endpoint = 0x0,
[1659607281.693279][4331:4336] CHIP:DMG: Cluster = 0x30,
[1659607281.693305][4331:4336] CHIP:DMG: Attribute = 0x0000_0000,
[1659607281.693355][4331:4336] CHIP:DMG: }
[1659607281.693387][4331:4336] CHIP:DMG:
[1659607281.693411][4331:4336] CHIP:DMG: StatusIB =
[1659607281.693438][4331:4336] CHIP:DMG: {
[1659607281.693463][4331:4336] CHIP:DMG: status = 0x00 (SUCCESS),
[1659607281.693500][4331:4336] CHIP:DMG: },
[1659607281.693526][4331:4336] CHIP:DMG:
[1659607281.693548][4331:4336] CHIP:DMG: },
[1659607281.693589][4331:4336] CHIP:DMG:
[1659607281.693619][4331:4336] CHIP:DMG: ],
[1659607281.693651][4331:4336] CHIP:DMG:
[1659607281.693677][4331:4336] CHIP:DMG: InteractionModelRevision = 1
[1659607281.693702][4331:4336] CHIP:DMG: }
[1659607281.693777][4331:4336] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659607281.694035][4331:4336] CHIP:EM: Sending Standalone Ack for MessageCounter:3467785 on exchange 18037i
[1659607281.694128][4331:4336] CHIP:IN: Prepared secure message 0xffff877ed9e8 to 0x0000000000000001 (1) of type 0x10 and
STEP 15:generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify Breadcrumb attribute on the General Commissioning cluster as 6
[1659607398.718297][4114:4119] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:46194079 on exchange 27950i
[1659607398.718406][4114:4119] CHIP:EM: Found matching exchange: 27950i, Delegate: 0xffff68008dd0
[1659607398.718473][4114:4119] CHIP:EM: CHIP MessageCounter:134576333 not in RetransTable on exchange 27950i
[1659607398.718609][4114:4119] CHIP:DMG: ReportDataMessage =
[1659607398.718678][4114:4119] CHIP:DMG: {
[1659607398.718740][4114:4119] CHIP:DMG: SubscriptionId = 0x932316af,
[1659607398.718803][4114:4119] CHIP:DMG: AttributeReportIBs =
[1659607398.718879][4114:4119] CHIP:DMG: [
[1659607398.718941][4114:4119] CHIP:DMG: AttributeReportIB =
[1659607398.719021][4114:4119] CHIP:DMG: {
[1659607398.719085][4114:4119] CHIP:DMG: AttributeDataIB =
[1659607398.719163][4114:4119] CHIP:DMG: {
[1659607398.719243][4114:4119] CHIP:DMG: DataVersion = 0x2442fc28,
[1659607398.719322][4114:4119] CHIP:DMG: AttributePathIB =
[1659607398.719409][4114:4119] CHIP:DMG: {
[1659607398.719493][4114:4119] CHIP:DMG: Endpoint = 0x0,
[1659607398.719580][4114:4119] CHIP:DMG: Cluster = 0x30,
[1659607398.719721][4114:4119] CHIP:DMG: Attribute = 0x0000_0000,
[1659607398.719805][4114:4119] CHIP:DMG: }
[1659607398.719891][4114:4119] CHIP:DMG:
[1659607398.719975][4114:4119] CHIP:DMG: Data = 6,
[1659607398.720053][4114:4119] CHIP:DMG: },
[1659607398.720133][4114:4119] CHIP:DMG:
[1659607398.720197][4114:4119] CHIP:DMG: },
[1659607398.720270][4114:4119] CHIP:DMG:
[1659607398.720329][4114:4119] CHIP:DMG: ],
[1659607398.720402][4114:4119] CHIP:DMG:
[1659607398.720461][4114:4119] CHIP:DMG: InteractionModelRevision = 1
[1659607398.720519][4114:4119] CHIP:DMG: }
[1659607398.720790][4114:4119] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369704
[1659607398.720911][4114:4119] CHIP:TOO: Breadcrumb: 6
[1659607398.721021][4114:4119] CHIP:DMG: MoveToState ReadClient[0xffff68008dd0]: Moving to [AwaitingSu]
[1659607398.721137][4114:4119] CHIP:EM: Piggybacking Ack for MessageCounter:46194079 on exchange: 27950i
[1659607398.721267][4114:4119] CHIP:IN: Prepared secure message 0xaaaae096a348 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 27950i with MessageCounter:134576334.
[1659607398.721354][4114:4119] CHIP:IN: Sending encrypted msg 0xaaaae096a348 with MessageCounter:134576334 to 0x0000000000000002 (1) at monotonic time: 00000000010B1D81 msec
[1659607398.723449][4114:4119] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:46194080 on exchange 27950i
[1659607398.723526][4114:4119] CHIP:EM: Found matching exchange: 27950i, Delegate: 0xffff68008dd0
[1659607398.723599][4114:4119] CHIP:EM: Rxd Ack; Removing MessageCounter:134576334 from Retrans T
disabled: true
- label:
"Repeat steps 18 to 19 to ensure TH2 still does not have permissions
to subscribe to administer-privilege attributes and events"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 18:operationalcredentials subscribe nocs 1 30 2 0 --fabric-filtered false --keepSubscriptions 0
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659612879.114874][4965:4970] CHIP:DMG: ] (231 bytes)
[1659612879.114940][4965:4970] CHIP:DMG: 0xfe = 2,
[1659612879.115007][4965:4970] CHIP:DMG: },
[1659612879.115063][4965:4970] CHIP:DMG: },
[1659612879.115126][4965:4970] CHIP:DMG:
[1659612879.115176][4965:4970] CHIP:DMG: },
[1659612879.115234][4965:4970] CHIP:DMG:
[1659612879.115278][4965:4970] CHIP:DMG: ],
[1659612879.115351][4965:4970] CHIP:DMG:
[1659612879.115394][4965:4970] CHIP:DMG: InteractionModelRevision = 1
[1659612879.115437][4965:4970] CHIP:DMG: }
[1659612879.115935][4965:4970] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_003E Attribute 0x0000_0000 DataVersion: 296706937
[1659612879.116027][4965:4970] CHIP:TOO: NOCs: 2 entries
[1659612879.116087][4965:4970] CHIP:TOO: [1]: {
[1659612879.116135][4965:4970] CHIP:TOO: Noc:
[1659612879.116177][4965:4970] CHIP:TOO: Icac: null
[1659612879.116222][4965:4970] CHIP:TOO: FabricIndex: 1
[1659612879.116264][4965:4970] CHIP:TOO: }
[1659612879.116323][4965:4970] CHIP:TOO: [2]: {
[1659612879.116387][4965:4970] CHIP:TOO: Noc: 1530010101240201370324130118260480228127260580254D3A37062415012411021824070124080130094104DD7D445EB8321A5B25DC788835CF2FA5332AA2CA25659D68A15168A75A20D4C72846A44FEE2AFB6E2E7EA39BB2A65D0FC2548A51E42A7D7FEAA005EBD481121F370A350128011824020136030402040118300414A02C8D19F9BF72351909681A02E5BD88122780F5300514C7E958D6860E58EBFFC50375695FA10D8D1F7BA518300B40BC7C77F5CF49D8DB52F41529C48CD8122C3AAFE1224B0035738AA447527D192699964EDA250DC340D6AFC4ED14524DF8F53EF483B292326E97E12899A8B90FD418
[1659612879.116461][4965:4970] CHIP:TOO: Icac: 1530010100240201370324140018260480228127260580254D3A37062413011824070124080130094104B261C938B93273CC4895790193D5A154529A039D23E552263B7AA94A8F34448130E7A4C88EE71AE291BBC689E9B7D1F8C81FE3FE8C5DADFF98665DBF57D0739E370A3501290118240260300414C7E958D6860E58EBFFC50375695FA10D8D1F7BA5300514F6EF1D69A5F7797D534AB655E8E8BFA390CB562018300B40F2B2E6A0C20F819DAA4DA6C5C94B1F63686C3E11BED7FEF4E35643E795FFA3AEE4333AD726EE1EC492A29A45628EE95D88CCBAB078060BFD565CA52298A9B0AF18
[1659612879.116513][4965:4970] CHIP:TOO: FabricIndex: 2
[1659612879.116554][4965:4970] CHIP:TOO: }
[1659612879.116635][4965:4970] CHIP:DMG: MoveToState ReadClient[0xffff68008f60]: Moving to [AwaitingSu]
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 19: accesscontrol subscribe-event access-control-entry-changed 1 30 2 0 --keepSubscriptions 1
1659613023.586969][4977:4983] CHIP:EM: Found matching exchange: 10139i, Delegate: 0xffff78008e40
[1659613023.587008][4977:4983] CHIP:EM: Rxd Ack; Removing MessageCounter:44723459 from Retrans Table on exchange 10139i
[1659613023.587033][4977:4983] CHIP:EM: Removed CHIP MessageCounter:44723459 from RetransTable on exchange 10139i
[1659613023.587086][4977:4983] CHIP:DMG: ReportDataMessage =
[1659613023.587115][4977:4983] CHIP:DMG: {
[1659613023.587140][4977:4983] CHIP:DMG: SubscriptionId = 0x389e3b8e,
[1659613023.587171][4977:4983] CHIP:DMG: InteractionModelRevision = 1
[1659613023.587195][4977:4983] CHIP:DMG: }
[1659613023.587233][4977:4983] CHIP:DMG: MoveToState ReadClient[0xffff78008e40]: Moving to [AwaitingSu]
[1659613023.587286][4977:4983] CHIP:EM: Piggybacking Ack for MessageCounter:139554636 on exchange: 10139i
[1659613023.587346][4977:4983] CHIP:IN: Prepared secure message 0xffff78005878 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 10139i with MessageCounter:44723460.
[1659613023.587384][4977:4983] CHIP:IN: Sending encrypted msg 0xffff78005878 with MessageCounter:44723460 to 0x0000000000000002 (1) at monotonic time: 000000000160F1A3 msec
[1659613023.588676][4977:4983] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:139554637 on exchange 10139i
[1659613023.588725][4977:4983] CHIP:EM: Found matching exchange: 10139i, Delegate: 0xffff78008e40
[1659613023.588761][4977:4983] CHIP:EM: Rxd Ack; Removing MessageCounter:44723460 from Retrans Table on exchange 10139i
[1659613023.588786][4977:4983] CHIP:EM: Removed CHIP MessageCounter:44723460 from RetransTable on exchange 10139i
[1659613023.588836][4977:4983] CHIP:DMG: SubscribeResponseMessage =
[1659613023.588864][4977:4983] CHIP:DMG: {
[1659613023.588903][4977:4983] CHIP:DMG: SubscriptionId = 0x389e3b8e,
[1659613023.588930][4977:4983] CHIP:DMG: MaxInterval = 0x1e,
[1659613023.588955][4977:4983] CHIP:DMG: InteractionModelRevision = 1
[1659613023.588979][4977:4983] CHIP:DMG: }
[1659613023.589007][4977:4983] CHIP:DMG: Subscription established with SubscriptionID = 0x389e3b8e MinInterval = 1s MaxInterval = 30s Peer = 01:0000000000000002
disabled: true
- label: "Repeat step 5 to ensure TH2 can still subscribe to view events"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 5:
generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659607605.814318][4122:4127] CHIP:EM: Flushed pending ack for MessageCounter:200773535 on exchange 32772i
[1659607605.815815][4122:4127] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:48719299 on exchange 32773i
[1659607605.815867][4122:4127] CHIP:EM: Found matching exchange: 32773i, Delegate: 0xffff94008e40
[1659607605.815918][4122:4127] CHIP:EM: Rxd Ack; Removing MessageCounter:33435416 from Retrans Table on exchange 32773i
[1659607605.815958][4122:4127] CHIP:EM: Removed CHIP MessageCounter:33435416 from RetransTable on exchange 32773i
[1659607605.816042][4122:4127] CHIP:DMG: ReportDataMessage =
[1659607605.816086][4122:4127] CHIP:DMG: {
[1659607605.816126][4122:4127] CHIP:DMG: SubscriptionId = 0xd8f56277,
[1659607605.816167][4122:4127] CHIP:DMG: AttributeReportIBs =
[1659607605.816218][4122:4127] CHIP:DMG: [
[1659607605.816258][4122:4127] CHIP:DMG: AttributeReportIB =
[1659607605.816311][4122:4127] CHIP:DMG: {
[1659607605.816353][4122:4127] CHIP:DMG: AttributeDataIB =
[1659607605.816410][4122:4127] CHIP:DMG: {
[1659607605.816464][4122:4127] CHIP:DMG: DataVersion = 0x2442fc28,
[1659607605.816516][4122:4127] CHIP:DMG: AttributePathIB =
[1659607605.816575][4122:4127] CHIP:DMG: {
[1659607605.816630][4122:4127] CHIP:DMG: Endpoint = 0x0,
[1659607605.816689][4122:4127] CHIP:DMG: Cluster = 0x30,
[1659607605.816751][4122:4127] CHIP:DMG: Attribute = 0x0000_0000,
[1659607605.816808][4122:4127] CHIP:DMG: }
[1659607605.816869][4122:4127] CHIP:DMG:
[1659607605.816929][4122:4127] CHIP:DMG: Data = 6,
[1659607605.816980][4122:4127] CHIP:DMG: },
[1659607605.817035][4122:4127] CHIP:DMG:
[1659607605.817081][4122:4127] CHIP:DMG: },
[1659607605.817136][4122:4127] CHIP:DMG:
[1659607605.817176][4122:4127] CHIP:DMG: ],
[1659607605.817225][4122:4127] CHIP:DMG:
[1659607605.817265][4122:4127] CHIP:DMG: InteractionModelRevision = 1
[1659607605.817303][4122:4127] CHIP:DMG: }
[1659607605.817488][4122:4127] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369704
[1659607605.817566][4122:4127] CHIP:TOO: Breadcrumb: 6
[1659607605.817641][4122:4127] CHIP:DMG: MoveToState ReadClient[0xffff94008e40]: Moving to [AwaitingSu]
[1659607605.817714][4122:4127] CHIP:EM: Piggybacking Ack for MessageCounter:48719299 on exchange: 32773i
[1659607605.817797][4122:4127] CHIP:IN: Prepared secure message 0xffff94005878 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 32773i with MessageCounter:33435417.
[1659607605.817855][4122:4127] CHIP:IN: Sending encrypted msg 0xffff94005878 with MessageCounter:33435417 to 0x0000000000000002 (1) at monotonic time: 00000000010E467A msec
[1659607605.819160][4122:4127] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:48719300 on exchange 32773i
[1659607605.819195][4122:4127] CHIP:EM: Found matching exchange: 32773i, Delegate: 0xffff94008e40
[1659607605.819227][4122:4127] CHIP:EM: Rxd Ack; Removing MessageCounter:33435417 from Retrans Table on exchange 32773i
[1659607605.819253][4122:4127] CHIP:EM: Removed CHIP MessageCounter:33435417 from RetransTable on exchange 32773i
[1659607605.819296][4122:4127] CHIP:DMG: SubscribeResponseMessage =
[1659607605.819325][4122:4127] CHIP:DMG: {
disabled: true
- label:
"Repeat steps 14 to 15, incrementing the Breadcrumb value from the
last write, to verify that TH2 can still receive view-privilege
attribute reports."
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 14: generalcommissioning write breadcrumb 7 1 0
On TH(Chiptool)1,Verify Breadcrumb attribute on the General Commissioning cluster, setting the value to 7
[1659607708.484957][4361:4366] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659607708.485014][4361:4366] CHIP:DMG: WriteResponseMessage =
[1659607708.485048][4361:4366] CHIP:DMG: {
[1659607708.485076][4361:4366] CHIP:DMG: AttributeStatusIBs =
[1659607708.485115][4361:4366] CHIP:DMG: [
[1659607708.485146][4361:4366] CHIP:DMG: AttributeStatusIB =
[1659607708.485185][4361:4366] CHIP:DMG: {
[1659607708.485215][4361:4366] CHIP:DMG: AttributePathIB =
[1659607708.485252][4361:4366] CHIP:DMG: {
[1659607708.485316][4361:4366] CHIP:DMG: Endpoint = 0x0,
[1659607708.485356][4361:4366] CHIP:DMG: Cluster = 0x30,
[1659607708.485394][4361:4366] CHIP:DMG: Attribute = 0x0000_0000,
[1659607708.485427][4361:4366] CHIP:DMG: }
[1659607708.485469][4361:4366] CHIP:DMG:
[1659607708.485503][4361:4366] CHIP:DMG: StatusIB =
[1659607708.485539][4361:4366] CHIP:DMG: {
[1659607708.485572][4361:4366] CHIP:DMG: status = 0x00 (SUCCESS),
[1659607708.485608][4361:4366] CHIP:DMG: },
[1659607708.485645][4361:4366] CHIP:DMG:
[1659607708.485677][4361:4366] CHIP:DMG: },
[1659607708.485714][4361:4366] CHIP:DMG:
[1659607708.485744][4361:4366] CHIP:DMG: ],
[1659607708.485781][4361:4366] CHIP:DMG:
[1659607708.485811][4361:4366] CHIP:DMG: InteractionModelRevision = 1
[1659607708.485840][4361:4366] CHIP:DMG: }
[1659607708.485927][4361:4366] CHIP:DMG: WriteClient moving to [AwaitingDe]
[1659607708.485996][4361:4366] CHIP:EM: Sending Standalone Ack for MessageCounter:27924490 on exchange 43720i
[1659607708.486076][4361:4366] CHIP:IN: Prepared secure message 0xffffa222a9e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 43720i with MessageCounter:128818467.
[1659607708.486125][4361:4366] CHIP:IN: Sending encrypted msg 0xffffa222a9e8 with MessageCounter:128818467 to 0x0000000000000001 (1) at monotonic time: 00000000010FEAEA msec
[1659607708.486264][4361:4366] CHIP:EM: Flushed pending ack for MessageCounter:27924490 on exch [1659607772.385611][4122:4127] CHIP:EM: Rxd Ack; Removing MessageCounter:33435425 from Retrans Table on exchange 32774i
[1659607772.385673][4122:4127] CHIP:EM: Removed CHIP MessageCounter:33435425 from RetransTable on exchange 32774i
[1659607772.385792][4122:4127] CHIP:DMG: ReportDataMessage =
[1659607772.385858][4122:4127] CHIP:DMG: {
[1659607772.385917][4122:4127] CHIP:DMG: SubscriptionId = 0x7819b5fe,
[1659607772.385979][4122:4127] CHIP:DMG: AttributeReportIBs =
[1659607772.386055][4122:4127] CHIP:DMG: [
[1659607772.386116][4122:4127] CHIP:DMG: AttributeReportIB =
[1659607772.386204][4122:4127] CHIP:DMG: {
[1659607772.386273][4122:4127] CHIP:DMG: AttributeDataIB =
[1659607772.386351][4122:4127] CHIP:DMG: {
[1659607772.386430][4122:4127] CHIP:DMG: DataVersion = 0x2442fc29,
[1659607772.386516][4122:4127] CHIP:DMG: AttributePathIB =
[1659607772.386606][4122:4127] CHIP:DMG: {
[1659607772.386689][4122:4127] CHIP:DMG: Endpoint = 0x0,
[1659607772.386777][4122:4127] CHIP:DMG: Cluster = 0x30,
[1659607772.386863][4122:4127] CHIP:DMG: Attribute = 0x0000_0000,
[1659607772.386946][4122:4127] CHIP:DMG: }
[1659607772.387031][4122:4127] CHIP:DMG:
[1659607772.387117][4122:4127] CHIP:DMG: Data = 7,
[1659607772.387202][4122:4127] CHIP:DMG: },
[1659607772.387284][4122:4127] CHIP:DMG:
[1659607772.387352][4122:4127] CHIP:DMG: },
[1659607772.387430][4122:4127] CHIP:DMG:
[1659607772.387490][4122:4127] CHIP:DMG: ],
[1659607772.387563][4122:4127] CHIP:DMG:
[1659607772.387675][4122:4127] CHIP:DMG: InteractionModelRevision = 1
[1659607772.387735][4122:4127] CHIP:DMG: }
[1659607772.387945][4122:4127] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 608369705
[1659607772.388023][4122:4127] CHIP:TOO: Breadcrumb: 7
[1659607772.388109][4122:4127] CHIP:DMG: MoveToState ReadClient[0xffff94008f30]: Moving to [AwaitingSu]
[1659607772.388211][4122:4127] CHIP:EM: Piggybacking Ack for MessageCounter:48719313 on exchange: 32774i
[1659607772.388331][4122:4127] CHIP:IN: Prepared secure message 0xffff94005878 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 32774i with MessageCounter:33435426.
[1659607772.388481][4122:4127] CHIP:IN: Sending encrypted msg 0xffff94005878 with MessageCounter:33435426 to 0x0000 STEP 15:generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)2,Verify Breadcrumb attribute on the General Commissioning cluster as 7
659614281.384988][5032:5037] CHIP:EM: Rxd Ack; Removing MessageCounter:18257811 from Retrans Table on exchange 48330i
[1659614281.385048][5032:5037] CHIP:EM: Removed CHIP MessageCounter:18257811 from RetransTable on exchange 48330i
[1659614281.385169][5032:5037] CHIP:DMG: ReportDataMessage =
[1659614281.385260][5032:5037] CHIP:DMG: {
[1659614281.385322][5032:5037] CHIP:DMG: SubscriptionId = 0x4e2c0871,
[1659614281.385383][5032:5037] CHIP:DMG: AttributeReportIBs =
[1659614281.385480][5032:5037] CHIP:DMG: [
[1659614281.385540][5032:5037] CHIP:DMG: AttributeReportIB =
[1659614281.385642][5032:5037] CHIP:DMG: {
[1659614281.385725][5032:5037] CHIP:DMG: AttributeDataIB =
[1659614281.385803][5032:5037] CHIP:DMG: {
[1659614281.385904][5032:5037] CHIP:DMG: DataVersion = 0xdfa9a381,
[1659614281.385992][5032:5037] CHIP:DMG: AttributePathIB =
[1659614281.386100][5032:5037] CHIP:DMG: {
[1659614281.386203][5032:5037] CHIP:DMG: Endpoint = 0x0,
[1659614281.386293][5032:5037] CHIP:DMG: Cluster = 0x30,
[1659614281.386404][5032:5037] CHIP:DMG: Attribute = 0x0000_0000,
[1659614281.386485][5032:5037] CHIP:DMG: }
[1659614281.386589][5032:5037] CHIP:DMG:
[1659614281.386674][5032:5037] CHIP:DMG: Data = 7,
[1659614281.386771][5032:5037] CHIP:DMG: },
[1659614281.386872][5032:5037] CHIP:DMG:
[1659614281.386944][5032:5037] CHIP:DMG: },
[1659614281.387018][5032:5037] CHIP:DMG:
[1659614281.387076][5032:5037] CHIP:DMG: ],
[1659614281.387149][5032:5037] CHIP:DMG:
[1659614281.387208][5032:5037] CHIP:DMG: InteractionModelRevision = 1
[1659614281.387265][5032:5037] CHIP:DMG: }
[1659614281.387480][5032:5037] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 3752436609
[1659614281.387559][5032:5037] CHIP:TOO: Breadcrumb: 7
[1659614281.387707][5032:5037] CHIP:DMG: MoveToState ReadClient[0xffff84008fe0]: Moving to [AwaitingSu]
[1659614281.387817][5032:5037] CHIP:EM: Piggybacking Ack for MessageCounter:52083233 on exchange: 48330i
[1659614281.387940][5032:5037] CHIP:IN: Prepared secure message 0xffff84005928 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 48330i with MessageCounter:18257812.
[1659614281.388026][5032:5037] CHIP:IN: Sending encrypted msg 0xffff84005928 with MessageCounter:18257812 to 0x0000000000000002 (1) at monotonic time: 00000000017422EC msec
[1659614281.389602][5032:5037] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:52083234
disabled: true
- label:
"TH1 writes AccessControl cluster ACL attribute, value is a list of
AccessControlEntryStruct containing 1 element struct Privilege field:
Administer (5) AuthMode field: CASE (2) Subjects field: [N1] Targets
field: [{Endpoint: 0}]"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
accesscontrol write acl '[{"fabricIndex": 1, "privilege": 5, "authMode": 2, "subjects": [112233], "targets": [{ "cluster": null, "endpoint": 0, "deviceType": null }]}]' 1 0
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659607856.168257][4361:4366] CHIP:DMG: WriteClient moving to [ResponseRe]
[1659607856.168336][4361:4366] CHIP:DMG: WriteResponseMessage =
[1659607856.168366][4361:4366] CHIP:DMG: {
[1659607856.168392][4361:4366] CHIP:DMG: AttributeStatusIBs =
[1659607856.168440][4361:4366] CHIP:DMG: [
[1659607856.168468][4361:4366] CHIP:DMG: AttributeStatusIB =
[1659607856.168511][4361:4366] CHIP:DMG: {
[1659607856.168540][4361:4366] CHIP:DMG: AttributePathIB =
[1659607856.168590][4361:4366] CHIP:DMG: {
[1659607856.168638][4361:4366] CHIP:DMG: Endpoint = 0x0,
[1659607856.168677][4361:4366] CHIP:DMG: Cluster = 0x1f,
[1659607856.168727][4361:4366] CHIP:DMG: Attribute = 0x0000_0000,
[1659607856.168772][4361:4366] CHIP:DMG: }
[1659607856.168813][4361:4366] CHIP:DMG:
[1659607856.168846][4361:4366] CHIP:DMG: StatusIB =
[1659607856.168884][4361:4366] CHIP:DMG: {
[1659607856.168921][4361:4366] CHIP:DMG: status = 0x00 (SUCCESS),
[1659607856.168957][4361:4366] CHIP:DMG: },
[1659607856.168991][4361:4366] CHIP:DMG:
[1659607856.169021][4361:4366] CHIP:DMG: },
[1659607856.169061][4361:4366] CHIP:DMG:
[1659607856.169089][4361:4366] CHIP:DMG: AttributeStatusIB =
[1659607856.169119][4361:4366] CHIP:DMG: {
[1659607856.169148][4361:4366] CHIP:DMG: AttributePathIB =
[1659607856.169181][4361:4366] CHIP:DMG: {
[1659607856.169217][4361:4366] CHIP:DMG: Endpoint = 0x0,
[1659607856.169256][4361:4366] CHIP:DMG: Cluster = 0x1f,
[1659607856.169293][4361:4366] CHIP:DMG: Attribute = 0x0000_0000,
[1659607856.169329][4361:4366] CHIP:DMG: ListIndex = Null,
[1659607856.169364][4361:4366] CHIP:DMG: }
[1659607856.169401][4361:4366] CHIP:DMG:
[1659607856.169435][4361:4366] CHIP:DMG: StatusIB =
[1659607856.169469][4361:4366] CHIP:DMG: {
[1659607856.169505][4361:4366] CHIP:DMG: status = 0x00 (SUCCESS),
[1659607856.169543][4361:4366] CHIP:DMG: },
[1659607856.169578][4361:4366] CHIP:DMG:
[1659607856.169608][4361:4366] CHIP:DMG: },
[1659607856.169641][4361:4366] CHIP:DMG:
[1659607856.169668][4361:4366] CHIP:DMG: ],
[1659607856.169705][4361:4366] CHIP:DMG:
[1659607856.169734][4361:4366] CHIP:DMG: InteractionModelRevision = 1
[1659607856.169761][4361:4366] CHIP:DMG: }
[1659607856.169873][4361:4366] CHIP:DMG: WriteClient moving to [AwaitingDe]
disabled: true
- label:
"Repeat steps 18 to 19 to ensure TH2 still does not have permissions
to subscribe to administer-privilege attributes and events"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 18: operationalcredentials subscribe nocs 1 30 2 0 --fabric-filtered false --keepSubscriptions 0
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
[1659614413.742617][5032:5037] CHIP:DMG: ] (231 bytes)
[1659614413.742706][5032:5037] CHIP:DMG: 0xfe = 2,
[1659614413.742790][5032:5037] CHIP:DMG: },
[1659614413.742873][5032:5037] CHIP:DMG: },
[1659614413.742959][5032:5037] CHIP:DMG:
[1659614413.743027][5032:5037] CHIP:DMG: },
[1659614413.743110][5032:5037] CHIP:DMG:
[1659614413.743169][5032:5037] CHIP:DMG: ],
[1659614413.743268][5032:5037] CHIP:DMG:
[1659614413.743327][5032:5037] CHIP:DMG: InteractionModelRevision = 1
[1659614413.743384][5032:5037] CHIP:DMG: }
[1659614413.744052][5032:5037] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_003E Attribute 0x0000_0000 DataVersion: 296706937
[1659614413.744187][5032:5037] CHIP:TOO: NOCs: 2 entries
[1659614413.744238][5032:5037] CHIP:TOO: [1]: {
[1659614413.744323][5032:5037] CHIP:TOO: Noc:
[1659614413.744353][5032:5037] CHIP:TOO: Icac: null
[1659614413.744382][5032:5037] CHIP:TOO: FabricIndex: 1
[1659614413.744408][5032:5037] CHIP:TOO: }
[1659614413.744445][5032:5037] CHIP:TOO: [2]: {
[1659614413.744485][5032:5037] CHIP:TOO: Noc: 1530010101240201370324130118260480228127260580254D3A37062415012411021824070124080130094104DD7D445EB8321A5B25DC788835CF2FA5332AA2CA25659D68A15168A75A20D4C72846A44FEE2AFB6E2E7EA39BB2A65D0FC2548A51E42A7D7FEAA005EBD481121F370A350128011824020136030402040118300414A02C8D19F9BF72351909681A02E5BD88122780F5300514C7E958D6860E58EBFFC50375695FA10D8D1F7BA518300B40BC7C77F5CF49D8DB52F41529C48CD8122C3AAFE1224B0035738AA447527D192699964EDA250DC340D6AFC4ED14524DF8F53EF483B292326E97E12899A8B90FD418
[1659614413.744531][5032:5037] CHIP:TOO: Icac: 1530010100240201370324140018260480228127260580254D3A37062413011824070124080130094104B261C938B93273CC4895790193D5A154529A039D23E552263B7AA94A8F34448130E7A4C88EE71AE291BBC689E9B7D1F8C81FE3FE8C5DADFF98665DBF57D0739E370A3501290118240260300414C7E958D6860E58EBFFC50375695FA10D8D1F7BA5300514F6EF1D69A5F7797D534AB655E8E8BFA390CB562018300B40F2B2E6A0C20F819DAA4DA6C5C94B1F63686C3E11BED7FEF4E35643E795FFA3AEE4333AD726EE1EC492A29A45628EE95D88CCBAB078060BFD565CA52298A9B0AF18
[1659614413.744563][5032:5037] CHIP:TOO: FabricIndex: 2
[1659614413.744589][5032:5037] CHIP:TOO: }
[1659614413.744640][5032:5037] CHIP:DMG: MoveToState ReadClient[0xffff84008d30]: Moving to [AwaitingSu]
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
STEP 19 : accesscontrol subscribe-event access-control-entry-changed 1 30 2 0 --keepSubscriptions 1
9614473.812436][5032:5037] CHIP:DMG: ReportDataMessage =
[1659614473.812505][5032:5037] CHIP:DMG: {
[1659614473.812565][5032:5037] CHIP:DMG: SubscriptionId = 0xd3866e6c,
[1659614473.812629][5032:5037] CHIP:DMG: InteractionModelRevision = 1
[1659614473.812687][5032:5037] CHIP:DMG: }
[1659614473.812787][5032:5037] CHIP:DMG: Refresh LivenessCheckTime for 55000 milliseconds with SubscriptionId = 0xd3866e6c Peer = 01:0000000000000002
[1659614473.812895][5032:5037] CHIP:EM: Piggybacking Ack for MessageCounter:52083255 on exchange: 13991r
[1659614473.813023][5032:5037] CHIP:IN: Prepared secure message 0xffff84005928 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 13991r with MessageCounter:18257826.
[1659614473.813111][5032:5037] CHIP:IN: Sending encrypted msg 0xffff84005928 with MessageCounter:18257826 to 0x0000000000000002 (1) at monotonic time: 0000000001771295 msec
[1659614473.815247][5032:5037] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:52083256 on exchange 13991r
[1659614473.815327][5032:5037] CHIP:EM: Found matching exchange: 13991r, Delegate: (nil)
[1659614473.815401][5032:5037] CHIP:EM: Rxd Ack; Removing MessageCounter:18257826 from Retrans Table on exchange 13991r
[1659614473.815461][5032:5037] CHIP:EM: Removed CHIP MessageCounter:18257826 from RetransTable on exchange 13991r
accesscontrol subscribe-event access-control-entry-changed 1 30 2 0 --keepSubscriptions 1
[1659614490.650654][5032:5037] CHIP:TOO: Sending command to node 0x2
[1659614490.650707][5032:5037] CHIP:CSM: FindOrEstablishSession: PeerId = 690F38784FDA9270:0000000000000002
[1659614490.650753][5032:5037] CHIP:TOO: Sending SubscribeEvent to:
[1659614490.650778][5032:5037] CHIP:TOO: cluster 0x0000_001F, event: 0x0000_0000, endpoint 0
[1659614490.650951][5032:5037] CHIP:IN: Prepared secure message 0xffff84005928 to 0x0000000000000002 (1) of type 0x3 and protocolId (0, 1) on exchange 48332i with MessageCounter:18257827.
[1659614490.650996][5032:5037] CHIP:IN: Sending encrypted msg 0xffff84005928 with MessageCounter:18257827 to 0x0000000000000002 (1) at monotonic time: 000000000177545B msec
[1659614490.651281][5032:5037] CHIP:DMG: MoveToState ReadClient[0xffff84008fe0]: Moving to [AwaitingIn]
[1659614490.656378][5032:5037] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:52083257 on exchange 48332i
[1659614490.656464][5032:5037] CHIP:EM: Found matching exchange: 48332i, Delegate: 0xffff84008fe0
[1659614490.656543][5032:5037] CHIP:EM: Rxd Ack; Removing MessageCounter:18257827 from Retrans Table on exchange 48332i
[1659614490.656604][5032:5037] CHIP:EM: Removed CHIP MessageCounter:18257827 from RetransTable on exchange 48332i
[1659614490.656710][5032:5037] CHIP:DMG: ReportDataMessage =
[1659614490.656778][5032:5037] CHIP:DMG: {
[1659614490.656837][5032:5037] CHIP:DMG: SubscriptionId = 0xd2d4866d,
[1659614490.656912][5032:5037] CHIP:DMG: InteractionModelRevision = 1
[1659614490.656972][5032:5037] CHIP:DMG: }
[1659614490.657061][5032:5037] CHIP:DMG: MoveToState ReadClient[0xffff84008fe0]: Moving to [AwaitingSu]
[1659614490.657163][5032:5037] CHIP:EM: Piggybacking Ack for MessageCounter:52083257 on exchange: 48332i
[1659614490.657280][5032:5037] CHIP:IN: Prepared secure message 0xaaaacab8a548 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 48332i with MessageCounter:18257828.
[1659614490.657368][5032:5037] CHIP:IN: Sending encrypted msg 0xaaaacab8a548 with MessageCounter:18257828 to 0x0000000000000002 (1) at monotonic time: 0000000001775461 msec
[1659614490.659206][5032:5037] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:52083258 on exchange 48332i
[1659614490.659284][5032:5037] CHIP:EM: Found matching exchange: 48332i, Delegate: 0xffff84008fe0
[1659614490.659357][5032:5037] CHIP:EM: Rxd Ack; Removing MessageCounter:18257828 from Retrans Table on exchange 48332i
[1659614490.659417][5032:5037] CHIP:EM: Removed CHIP MessageCounter:18257828 from RetransTable on exchange 48332i
[1659614490.659512][5032:5037] CHIP:DMG: SubscribeResponseMessage =
[1659614490.659576][5032:5037] CHIP:DMG: {
[1659614490.659678][5032:5037] CHIP:DMG: SubscriptionId = 0xd2d4866d,
[1659614490.659750][5032:5037] CHIP:DMG: MaxInterval = 0x1e,
[1659614490.659813][5032:5037] CHIP:DMG: InteractionModelRevision = 1
disabled: true
- label:
"TH2 subscribes to the General Commissioning cluster Breadcrumb
attribute, min interval 1, max interval 30, keep subscriptions true"
verification: |
Pls run this test in chip tool interactive mode using the command ./chip-tool interactive start
generalcommissioning subscribe breadcrumb 1 30 2 0 --keepSubscriptions 1
On TH(Chiptool)1,Verify ACL attribute with a list of AccessControlEntryStruct entries containing 1 element.
9614536.314670][5032:5037] CHIP:EM: Found matching exchange: 48333i, Delegate: 0xffff84008ef0
[1659614536.314749][5032:5037] CHIP:EM: Rxd Ack; Removing MessageCounter:18257833 from Retrans Table on exchange 48333i
[1659614536.314810][5032:5037] CHIP:EM: Removed CHIP MessageCounter:18257833 from RetransTable on exchange 48333i
[1659614536.314933][5032:5037] CHIP:DMG: ReportDataMessage =
[1659614536.315028][5032:5037] CHIP:DMG: {
[1659614536.315090][5032:5037] CHIP:DMG: SubscriptionId = 0x8ec61c89,
[1659614536.315151][5032:5037] CHIP:DMG: AttributeReportIBs =
[1659614536.315249][5032:5037] CHIP:DMG: [
[1659614536.315309][5032:5037] CHIP:DMG: AttributeReportIB =
[1659614536.315410][5032:5037] CHIP:DMG: {
[1659614536.315474][5032:5037] CHIP:DMG: AttributeDataIB =
[1659614536.315579][5032:5037] CHIP:DMG: {
[1659614536.315714][5032:5037] CHIP:DMG: DataVersion = 0xdfa9a381,
[1659614536.315801][5032:5037] CHIP:DMG: AttributePathIB =
[1659614536.315884][5032:5037] CHIP:DMG: {
[1659614536.315961][5032:5037] CHIP:DMG: Endpoint = 0x0,
[1659614536.316052][5032:5037] CHIP:DMG: Cluster = 0x30,
[1659614536.316134][5032:5037] CHIP:DMG: Attribute = 0x0000_0000,
[1659614536.316215][5032:5037] CHIP:DMG: }
[1659614536.316322][5032:5037] CHIP:DMG:
[1659614536.316410][5032:5037] CHIP:DMG: Data = 7,
[1659614536.316515][5032:5037] CHIP:DMG: },
[1659614536.316623][5032:5037] CHIP:DMG:
[1659614536.316687][5032:5037] CHIP:DMG: },
[1659614536.316782][5032:5037] CHIP:DMG:
[1659614536.316841][5032:5037] CHIP:DMG: ],
[1659614536.316914][5032:5037] CHIP:DMG:
[1659614536.316973][5032:5037] CHIP:DMG: InteractionModelRevision = 1
[1659614536.317030][5032:5037] CHIP:DMG: }
[1659614536.317245][5032:5037] CHIP:TOO: Endpoint: 0 Cluster: 0x0000_0030 Attribute 0x0000_0000 DataVersion: 3752436609
[1659614536.317325][5032:5037] CHIP:TOO: Breadcrumb: 7
[1659614536.317414][5032:5037] CHIP:DMG: MoveToState ReadClient[0xffff84008ef0]: Moving to [AwaitingSu]
[1659614536.317518][5032:5037] CHIP:EM: Piggybacking Ack for MessageCounter:52083265 on exchange: 48333i
[1659614536.317643][5032:5037] CHIP:IN: Prepared secure message 0xffff84005928 to 0x0000000000000002 (1) of type 0x1 and protocolId (0, 1) on exchange 48333i with MessageCounter:18257834.
[1659614536.317731][5032:5037] CHIP:IN: Sending encrypted msg 0xffff84005928 with MessageCounter:18257834 to 0x0000000000000002 (1) at monotonic time: 00000000017806BE msec
[1659614536.319058][5032:5037] CHIP:EM: Received message of type 0x4 with protocolId (0, 1) and MessageCounter:520832
disabled: true