project-chip / project-chip/certification-tool

[Bug] TC-GRPKEY-2.2 : Test harness backward compatibility issue TH V1.6

Open
#1,077 2 comments 0 reactions 0 assignees View on GitHub

Nobody has claimed this yet.

Bug Needs Triage
Dominant language
Shell
Stars
83
Forks
47
Avg merge
1d 15h
Merged PRs (30d)
17

Description

Describe the bug

It is noticed that the TH V1.6 has backward compatibility issue with GroupKeyMultiCastPolicy attribute.
In TH V1.6 in step 3 ReadSetKey : DUT does not contain GroupKeyMulticastPolicy flag, which is giving FAIL for the DUT with V1.5.1 and older

however in the step 4:: DUT accepts the GroupkeyMulticastPolicy,

however again step 18 Read is does not contain the flag.

This looks like a TH backward compatibility issue as the TH V1.5.1 and older does not observe this as an issue

Should the KeySetRead with GroupKeyMulticastpolicy flag only be applicable to DUT with V1.6 ??

logs ::

Executing Test Step: Step 3: TH sends KeySetRead command to GroupKeyManagement cluster with GroupKeySetID as 0x01a
CHIPTOOL | 2026-08-07 12:17:59.695440 | CHIP:TOO Command: groupkeymanagement key-set-read 26 0xa82d9e093d2c5785 0
CHIPTOOL | 2026-08-07 12:17:59.697308 | CHIP:TOO Sending command to node 0xa82d9e093d2c5785
CHIPTOOL | 2026-08-07 12:17:59.698851 | CHIP:CSM FindOrEstablishSession: PeerId = [1:A82D9E093D2C5785]
CHIPTOOL | 2026-08-07 12:17:59.700333 | CHIP:CSM FindOrEstablishSession: No existing OperationalSessionSetup instance found
CHIPTOOL | 2026-08-07 12:17:59.701482 | CHIP:DIS Found an existing secure session to [1:A82D9E093D2C5785]!
CHIPTOOL | 2026-08-07 12:17:59.702475 | CHIP:DIS OperationalSessionSetup[1:A82D9E093D2C5785]: State change 1 --> 5
CHIPTOOL | 2026-08-07 12:17:59.703458 | CHIP:TOO Sending cluster (0x0000003F) command (0x00000001) on endpoint 0
CHIPTOOL | 2026-08-07 12:17:59.704438 | CHIP:DMG ICR moving to [AddingComm]
CHIPTOOL | 2026-08-07 12:17:59.705392 | CHIP:DMG ICR moving to [AddedComma]
CHIPTOOL | 2026-08-07 12:17:59.706337 | CHIP:DMG >> to UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540 | 180463800 | [Interaction Model (1) / InvokeCommandRequest (0x08) / Session = 36254 / Exchange = 649]
CHIPTOOL | 2026-08-07 12:17:59.707312 | CHIP:DMG Header Flags =
CHIPTOOL | 2026-08-07 12:17:59.708282 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.709330 | CHIP:DMG Exchange (0x05) =
CHIPTOOL | 2026-08-07 12:17:59.710382 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.711439 | CHIP:DMG Initiator = true
CHIPTOOL | 2026-08-07 12:17:59.712441 | CHIP:DMG NeedsAck = true
CHIPTOOL | 2026-08-07 12:17:59.713409 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.714366 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.715308 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.716215 | CHIP:DMG Decrypted Payload (32 bytes) =
CHIPTOOL | 2026-08-07 12:17:59.717118 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.718023 | CHIP:DMG data = 1528002801360215370024000024013f24020118350124001a18181824ff0c18
CHIPTOOL | 2026-08-07 12:17:59.718955 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.719841 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.720796 | CHIP:DMG InvokeRequestMessage =
CHIPTOOL | 2026-08-07 12:17:59.721710 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.722629 | CHIP:DMG suppressResponse = false,
CHIPTOOL | 2026-08-07 12:17:59.723533 | CHIP:DMG timedRequest = false,
CHIPTOOL | 2026-08-07 12:17:59.724425 | CHIP:DMG InvokeRequests =
CHIPTOOL | 2026-08-07 12:17:59.725315 | CHIP:DMG [
CHIPTOOL | 2026-08-07 12:17:59.726295 | CHIP:DMG CommandDataIB =
CHIPTOOL | 2026-08-07 12:17:59.727284 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.728201 | CHIP:DMG CommandPathIB =
CHIPTOOL | 2026-08-07 12:17:59.729107 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.730047 | CHIP:DMG EndpointId = 0x0,
CHIPTOOL | 2026-08-07 12:17:59.730925 | CHIP:DMG ClusterId = 0x3f,
CHIPTOOL | 2026-08-07 12:17:59.731821 | CHIP:DMG CommandId = 0x1,
CHIPTOOL | 2026-08-07 12:17:59.732704 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.733626 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.734522 | CHIP:DMG CommandFields =
CHIPTOOL | 2026-08-07 12:17:59.735424 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.736331 | CHIP:DMG 0x0 = 26 (unsigned),
CHIPTOOL | 2026-08-07 12:17:59.737256 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.738152 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.739046 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.739951 | CHIP:DMG ],
CHIPTOOL | 2026-08-07 12:17:59.740859 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.741761 | CHIP:DMG InteractionModelRevision = 12
CHIPTOOL | 2026-08-07 12:17:59.742652 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.743553 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.744461 | CHIP:DMG Additional Fields =
CHIPTOOL | 2026-08-07 12:17:59.745364 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.746254 | CHIP:DMG peer_address = UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540
CHIPTOOL | 2026-08-07 12:17:59.747139 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.748039 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.748915 | CHIP:EM <<< [E:649i S:7377 M:180463800] (S) Msg TX from 000000000001B669 to 1:A82D9E093D2C5785 [0FFB] [UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
CHIPTOOL | 2026-08-07 12:17:59.749917 | CHIP:EM ??1 [E:649i S:7377 M:180463800] (S) Msg Retransmission to 1:A82D9E093D2C5785 scheduled for 375ms from now [State:Active II:500 AI:300 AT:4000]
CHIPTOOL | 2026-08-07 12:17:59.750850 | CHIP:DMG ICR moving to [AwaitingRe]
CHIPTOOL | 2026-08-07 12:17:59.751757 | CHIP:DMG << from UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540 | 73092638 | [Interaction Model (1) / InvokeCommandResponse (0x09) / Session = 7377 / Exchange = 649]
CHIPTOOL | 2026-08-07 12:17:59.752664 | CHIP:DMG Header Flags =
CHIPTOOL | 2026-08-07 12:17:59.756503 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.757588 | CHIP:DMG Exchange (0x06) =
CHIPTOOL | 2026-08-07 12:17:59.758569 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.759526 | CHIP:DMG AckMsg = 180463800
CHIPTOOL | 2026-08-07 12:17:59.762514 | CHIP:DMG NeedsAck = true
CHIPTOOL | 2026-08-07 12:17:59.763596 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.764557 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.766016 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.768361 | CHIP:DMG Decrypted Payload (68 bytes) =
CHIPTOOL | 2026-08-07 12:17:59.769469 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.770434 | CHIP:DMG data = 1528003601153500370024000024013f240202183501350024001a240100340224030134042705fdffffffffffffff34062707feffffffffffffff181818181824ff0c18
CHIPTOOL | 2026-08-07 12:17:59.771387 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.774392 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.775468 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.776434 | CHIP:DMG Additional Fields =
CHIPTOOL | 2026-08-07 12:17:59.777380 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.782530 | CHIP:DMG peer_address = UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540
CHIPTOOL | 2026-08-07 12:17:59.783650 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.784639 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.785584 | CHIP:EM >>> [E:649i S:7377 M:73092638 (Ack:180463800)] (S) Msg RX from 1:A82D9E093D2C5785 [0FFB] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:102)
CHIPTOOL | 2026-08-07 12:17:59.786525 | CHIP:EM Found matching exchange: 649i, Delegate: 0xffff88004258
CHIPTOOL | 2026-08-07 12:17:59.787443 | CHIP:EM Rxd Ack; Removing MessageCounter:180463800 from Retrans Table on exchange 649i
CHIPTOOL | 2026-08-07 12:17:59.788363 | CHIP:DMG ICR moving to [ResponseRe]
CHIPTOOL | 2026-08-07 12:17:59.790336 | CHIP:DMG InvokeResponseMessage =
CHIPTOOL | 2026-08-07 12:17:59.791509 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.792497 | CHIP:DMG suppressResponse = false,
CHIPTOOL | 2026-08-07 12:17:59.793560 | CHIP:DMG InvokeResponseIBs =
CHIPTOOL | 2026-08-07 12:17:59.796984 | CHIP:DMG [
CHIPTOOL | 2026-08-07 12:17:59.798076 | CHIP:DMG InvokeResponseIB =
CHIPTOOL | 2026-08-07 12:17:59.799094 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.800069 | CHIP:DMG CommandDataIB =
CHIPTOOL | 2026-08-07 12:17:59.802369 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.803441 | CHIP:DMG CommandPathIB =
CHIPTOOL | 2026-08-07 12:17:59.804398 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.805323 | CHIP:DMG EndpointId = 0x0,
CHIPTOOL | 2026-08-07 12:17:59.807958 | CHIP:DMG ClusterId = 0x3f,
CHIPTOOL | 2026-08-07 12:17:59.809049 | CHIP:DMG CommandId = 0x2,
CHIPTOOL | 2026-08-07 12:17:59.810073 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.811038 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.813503 | CHIP:DMG CommandFields =
CHIPTOOL | 2026-08-07 12:17:59.814572 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.815535 | CHIP:DMG 0x0 =
CHIPTOOL | 2026-08-07 12:17:59.816547 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.820472 | CHIP:DMG 0x0 = 26 (unsigned),
CHIPTOOL | 2026-08-07 12:17:59.821566 | CHIP:DMG 0x1 = 0 (unsigned),
CHIPTOOL | 2026-08-07 12:17:59.822515 | CHIP:DMG 0x2 = NULL
CHIPTOOL | 2026-08-07 12:17:59.823527 | CHIP:DMG 0x3 = 1 (unsigned),
CHIPTOOL | 2026-08-07 12:17:59.824463 | CHIP:DMG 0x4 = NULL
CHIPTOOL | 2026-08-07 12:17:59.825394 | CHIP:DMG 0x5 = 18446744073709551613 (unsigned),
CHIPTOOL | 2026-08-07 12:17:59.826310 | CHIP:DMG 0x6 = NULL
CHIPTOOL | 2026-08-07 12:17:59.827257 | CHIP:DMG 0x7 = 18446744073709551614 (unsigned),
CHIPTOOL | 2026-08-07 12:17:59.829825 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.830905 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.831870 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.832786 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.835068 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.836152 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.837238 | CHIP:DMG ],
CHIPTOOL | 2026-08-07 12:17:59.838257 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.840367 | CHIP:DMG InteractionModelRevision = 12
CHIPTOOL | 2026-08-07 12:17:59.841439 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:17:59.842384 | CHIP:DMG Received Command Response Data, Endpoint=0 Cluster=0x0000_003F Command=0x0000_0002
CHIPTOOL | 2026-08-07 12:17:59.843323 | CHIP:TOO Endpoint: 0 Cluster: 0x0000_003F Command 0x0000_0002
CHIPTOOL | 2026-08-07 12:17:59.845627 | CHIP:TOO KeySetReadResponse: {
CHIPTOOL | 2026-08-07 12:17:59.846733 | CHIP:TOO groupKeySet: {
CHIPTOOL | 2026-08-07 12:17:59.847699 | CHIP:TOO GroupKeySetID: 26
CHIPTOOL | 2026-08-07 12:17:59.848629 | CHIP:TOO GroupKeySecurityPolicy: 0
CHIPTOOL | 2026-08-07 12:17:59.850693 | CHIP:TOO EpochKey0: null
CHIPTOOL | 2026-08-07 12:17:59.851695 | CHIP:TOO EpochStartTime0: 1
CHIPTOOL | 2026-08-07 12:17:59.852644 | CHIP:TOO EpochKey1: null
CHIPTOOL | 2026-08-07 12:17:59.853596 | CHIP:TOO EpochStartTime1: 18446744073709551613
CHIPTOOL | 2026-08-07 12:17:59.861892 | CHIP:TOO EpochKey2: null
CHIPTOOL | 2026-08-07 12:17:59.862958 | CHIP:TOO EpochStartTime2: 18446744073709551614
CHIPTOOL | 2026-08-07 12:17:59.863910 | CHIP:TOO GroupKeyMulticastPolicy: 0
CHIPTOOL | 2026-08-07 12:17:59.864836 | CHIP:TOO }
CHIPTOOL | 2026-08-07 12:17:59.869682 | CHIP:TOO }
CHIPTOOL | 2026-08-07 12:17:59.870722 | CHIP:DMG ICR moving to [AwaitingDe]
CHIPTOOL | 2026-08-07 12:17:59.871706 | CHIP:DMG >> to UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540 | 180463801 | [Secure Channel (0) / Standalone Ack (0x10) / Session = 36254 / Exchange = 649]
CHIPTOOL | 2026-08-07 12:17:59.872650 | CHIP:DMG Header Flags =
CHIPTOOL | 2026-08-07 12:17:59.875186 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.876212 | CHIP:DMG Exchange (0x03) =
CHIPTOOL | 2026-08-07 12:17:59.877157 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.878074 | CHIP:DMG Initiator = true
CHIPTOOL | 2026-08-07 12:17:59.881358 | CHIP:DMG AckMsg = 73092638
CHIPTOOL | 2026-08-07 12:17:59.882477 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.883433 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.884364 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.887112 | CHIP:DMG Additional Fields =
CHIPTOOL | 2026-08-07 12:17:59.888148 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:17:59.889206 | CHIP:DMG peer_address = UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540
CHIPTOOL | 2026-08-07 12:17:59.890198 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:17:59.891824 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:17:59.892842 | CHIP:EM <<< [E:649i S:7377 M:180463801 (Ack:73092638)] (S) Msg TX from 000000000001B669 to 1:A82D9E093D2C5785 [0FFB] [UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
CHIPTOOL | 2026-08-07 12:17:59.893785 | CHIP:EM Flushed pending ack for MessageCounter:73092638 on exchange 649i
WARNING | 2026-08-07 12:17:59.899437 | Test Failure: The test expectation "GroupKeySet ({'GroupKeySetID': 26, 'GroupKeySecurityPolicy': 0, 'EpochKey0': None, 'EpochStartTime0': 1, 'EpochKey1': None, 'EpochStartTime1': 18446744073709551613, 'EpochKey2': None, 'EpochStartTime2': 18446744073709551614}) == {'GroupKeySetID': 26, 'GroupKeySecurityPolicy': 0, 'EpochKey0': None, 'EpochStartTime0': 1, 'EpochKey1': None, 'EpochStartTime1': 18446744073709551613, 'EpochKey2': None, 'EpochStartTime2': 18446744073709551614, 'GroupKeyMulticastPolicy': 0}" is false
INFO | 2026-08-07 12:17:59.904827 | --------------------------------------------------------------------------------
INFO | 2026-08-07 12:17:59.906065 | Executing Test Step: Step 4: TH sends KeySetWrite command in the GroupKeyManagement cluster to DUT on EP0 with GroupKeySet fields are as follows:1)GroupKeySetID: 0x01a 2) GroupKeySecurityPolicy: TrustFirst (0) 3)EpochKey0: d0d1d2d3d4d5d6d7d8d9dadbdcdddedf 4)EpochStartTime0: 1 5)EpochKey1: null 6)EpochStartTime1:null 7)EpochKey2: null 8)EpochStartTime2:null 9)GroupKeyMulticastPolicy: 0
CHIPTOOL | 2026-08-07 12:18:00.255385 | CHIP:TOO Command: groupkeymanagement key-set-write {"groupKeySetID": 26, "groupKeySecurityPolicy": 0, "epochKey0": "hex:d0d1d2d3d4d5d6d7d8d9dadbdcdddedf", "epochStartTime0": 1, "epochKey1": null, "epochStartTime1": null, "epochKey2": null, "epochStartTime2": null, "groupKeyMulticastPolicy": 0} 0xa82d9e093d2c5785 0
CHIPTOOL | 2026-08-07 12:18:00.256913 | CHIP:TOO Sending command to node 0xa82d9e093d2c5785
CHIPTOOL | 2026-08-07 12:18:00.258145 | CHIP:CSM FindOrEstablishSession: PeerId = [1:A82D9E093D2C5785]
CHIPTOOL | 2026-08-07 12:18:00.259265 | CHIP:CSM FindOrEstablishSession: No existing OperationalSessionSetup instance found
CHIPTOOL | 2026-08-07 12:18:00.260392 | CHIP:DIS Found an existing secure session to [1:A82D9E093D2C5785]!
CHIPTOOL | 2026-08-07 12:18:00.261498 | CHIP:DIS OperationalSessionSetup[1:A82D9E093D2C5785]: State change 1 --> 5
CHIPTOOL | 2026-08-07 12:18:00.262605 | CHIP:TOO Sending cluster (0x0000003F) command (0x00000000) on endpoint 0
CHIPTOOL | 2026-08-07 12:18:00.263746 | CHIP:DMG ICR moving to [AddingComm]
CHIPTOOL | 2026-08-07 12:18:00.264836 | CHIP:DMG ICR moving to [AddedComma]
CHIPTOOL | 2026-08-07 12:18:00.265863 | CHIP:DMG >> to UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540 | 180463802 | [Interaction Model (1) / InvokeCommandRequest (0x08) / Session = 36254 / Exchange = 650]
CHIPTOOL | 2026-08-07 12:18:00.266845 | CHIP:DMG Header Flags =
CHIPTOOL | 2026-08-07 12:18:00.267770 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.268674 | CHIP:DMG Exchange (0x05) =
CHIPTOOL | 2026-08-07 12:18:00.269600 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.270535 | CHIP:DMG Initiator = true
CHIPTOOL | 2026-08-07 12:18:00.271440 | CHIP:DMG NeedsAck = true
CHIPTOOL | 2026-08-07 12:18:00.272337 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.273232 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.274160 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.275072 | CHIP:DMG Decrypted Payload (71 bytes) =
CHIPTOOL | 2026-08-07 12:18:00.275976 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.276852 | CHIP:DMG data = 1528002801360215370024000024013f240200183501350024001a240100300210d0d1d2d3d4d5d6d7d8d9dadbdcdddedf24030134043405340634072408001818181824ff0c18
CHIPTOOL | 2026-08-07 12:18:00.277768 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.278655 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.279540 | CHIP:DMG InvokeRequestMessage =
CHIPTOOL | 2026-08-07 12:18:00.280429 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.281338 | CHIP:DMG suppressResponse = false,
CHIPTOOL | 2026-08-07 12:18:00.282228 | CHIP:DMG timedRequest = false,
CHIPTOOL | 2026-08-07 12:18:00.283130 | CHIP:DMG InvokeRequests =
CHIPTOOL | 2026-08-07 12:18:00.284031 | CHIP:DMG [
CHIPTOOL | 2026-08-07 12:18:00.284980 | CHIP:DMG CommandDataIB =
CHIPTOOL | 2026-08-07 12:18:00.285902 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.286806 | CHIP:DMG CommandPathIB =
CHIPTOOL | 2026-08-07 12:18:00.287702 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.288609 | CHIP:DMG EndpointId = 0x0,
CHIPTOOL | 2026-08-07 12:18:00.289500 | CHIP:DMG ClusterId = 0x3f,
CHIPTOOL | 2026-08-07 12:18:00.290406 | CHIP:DMG CommandId = 0x0,
CHIPTOOL | 2026-08-07 12:18:00.291307 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.292214 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.293100 | CHIP:DMG CommandFields =
CHIPTOOL | 2026-08-07 12:18:00.293986 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.294878 | CHIP:DMG 0x0 =
CHIPTOOL | 2026-08-07 12:18:00.295802 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.296698 | CHIP:DMG 0x0 = 26 (unsigned),
CHIPTOOL | 2026-08-07 12:18:00.297604 | CHIP:DMG 0x1 = 0 (unsigned),
CHIPTOOL | 2026-08-07 12:18:00.298508 | CHIP:DMG 0x2 = [
CHIPTOOL | 2026-08-07 12:18:00.299419 | CHIP:DMG 0xd0, 0xd1, 0xd2, 0xd3, 0xd4, 0xd5, 0xd6, 0xd7, 0xd8, 0xd9, 0xda, 0xdb, 0xdc, 0xdd, 0xde, 0xdf,
CHIPTOOL | 2026-08-07 12:18:00.300314 | CHIP:DMG ] (16 bytes)
CHIPTOOL | 2026-08-07 12:18:00.301195 | CHIP:DMG 0x3 = 1 (unsigned),
CHIPTOOL | 2026-08-07 12:18:00.302076 | CHIP:DMG 0x4 = NULL
CHIPTOOL | 2026-08-07 12:18:00.302982 | CHIP:DMG 0x5 = NULL
CHIPTOOL | 2026-08-07 12:18:00.303843 | CHIP:DMG 0x6 = NULL
CHIPTOOL | 2026-08-07 12:18:00.304797 | CHIP:DMG 0x7 = NULL
CHIPTOOL | 2026-08-07 12:18:00.305691 | CHIP:DMG 0x8 = 0 (unsigned),
CHIPTOOL | 2026-08-07 12:18:00.306614 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.307504 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.308400 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.309296 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.310217 | CHIP:DMG ],
CHIPTOOL | 2026-08-07 12:18:00.311123 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.312022 | CHIP:DMG InteractionModelRevision = 12
CHIPTOOL | 2026-08-07 12:18:00.312906 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.316476 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.317632 | CHIP:DMG Additional Fields =
CHIPTOOL | 2026-08-07 12:18:00.318671 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.319616 | CHIP:DMG peer_address = UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540
CHIPTOOL | 2026-08-07 12:18:00.322587 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.323741 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.324707 | CHIP:EM <<< [E:650i S:7377 M:180463802] (S) Msg TX from 000000000001B669 to 1:A82D9E093D2C5785 [0FFB] [UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:101)
CHIPTOOL | 2026-08-07 12:18:00.329668 | CHIP:EM ??1 [E:650i S:7377 M:180463802] (S) Msg Retransmission to 1:A82D9E093D2C5785 scheduled for 371ms from now [State:Active II:500 AI:300 AT:4000]
CHIPTOOL | 2026-08-07 12:18:00.330825 | CHIP:DMG ICR moving to [AwaitingRe]
CHIPTOOL | 2026-08-07 12:18:00.331830 | CHIP:DMG << from UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540 | 73092639 | [Interaction Model (1) / InvokeCommandResponse (0x09) / Session = 7377 / Exchange = 650]
CHIPTOOL | 2026-08-07 12:18:00.334408 | CHIP:DMG Header Flags =
CHIPTOOL | 2026-08-07 12:18:00.335555 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.336519 | CHIP:DMG Exchange (0x06) =
CHIPTOOL | 2026-08-07 12:18:00.337462 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.340540 | CHIP:DMG AckMsg = 180463802
CHIPTOOL | 2026-08-07 12:18:00.341622 | CHIP:DMG NeedsAck = true
CHIPTOOL | 2026-08-07 12:18:00.342568 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.343996 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.345003 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.345980 | CHIP:DMG Decrypted Payload (33 bytes) =
CHIPTOOL | 2026-08-07 12:18:00.346899 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.347819 | CHIP:DMG data = 1528003601153501370024000024013f2402001835012400001818181824ff0c18
CHIPTOOL | 2026-08-07 12:18:00.351202 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.352314 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.353270 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.354187 | CHIP:DMG Additional Fields =
CHIPTOOL | 2026-08-07 12:18:00.357237 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.358330 | CHIP:DMG peer_address = UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540
CHIPTOOL | 2026-08-07 12:18:00.359409 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.360364 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.363652 | CHIP:EM >>> [E:650i S:7377 M:73092639 (Ack:180463802)] (S) Msg RX from 1:A82D9E093D2C5785 [0FFB] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
CHIPTOOL | 2026-08-07 12:18:00.364754 | CHIP:EM Found matching exchange: 650i, Delegate: 0xffff88022a88
CHIPTOOL | 2026-08-07 12:18:00.365738 | CHIP:EM Rxd Ack; Removing MessageCounter:180463802 from Retrans Table on exchange 650i
CHIPTOOL | 2026-08-07 12:18:00.368390 | CHIP:DMG ICR moving to [ResponseRe]
CHIPTOOL | 2026-08-07 12:18:00.369492 | CHIP:DMG InvokeResponseMessage =
CHIPTOOL | 2026-08-07 12:18:00.370462 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.371394 | CHIP:DMG suppressResponse = false,
CHIPTOOL | 2026-08-07 12:18:00.373521 | CHIP:DMG InvokeResponseIBs =
CHIPTOOL | 2026-08-07 12:18:00.374620 | CHIP:DMG [
CHIPTOOL | 2026-08-07 12:18:00.375590 | CHIP:DMG InvokeResponseIB =
CHIPTOOL | 2026-08-07 12:18:00.376602 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.382704 | CHIP:DMG CommandStatusIB =
CHIPTOOL | 2026-08-07 12:18:00.383776 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.384730 | CHIP:DMG CommandPathIB =
CHIPTOOL | 2026-08-07 12:18:00.385656 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.388301 | CHIP:DMG EndpointId = 0x0,
CHIPTOOL | 2026-08-07 12:18:00.389391 | CHIP:DMG ClusterId = 0x3f,
CHIPTOOL | 2026-08-07 12:18:00.390357 | CHIP:DMG CommandId = 0x0,
CHIPTOOL | 2026-08-07 12:18:00.391289 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.393772 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.394894 | CHIP:DMG StatusIB =
CHIPTOOL | 2026-08-07 12:18:00.395866 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.396817 | CHIP:DMG status = 0x00 (SUCCESS),
CHIPTOOL | 2026-08-07 12:18:00.399482 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.400582 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.401545 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.402477 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.404805 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.405873 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.406832 | CHIP:DMG ],
CHIPTOOL | 2026-08-07 12:18:00.407748 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.410486 | CHIP:DMG InteractionModelRevision = 12
CHIPTOOL | 2026-08-07 12:18:00.411587 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.412549 | CHIP:DMG Received Command Response Status for Endpoint=0 Cluster=0x0000_003F Command=0x0000_0000 Status=0x0
CHIPTOOL | 2026-08-07 12:18:00.413496 | CHIP:DMG ICR moving to [AwaitingDe]
CHIPTOOL | 2026-08-07 12:18:00.415846 | CHIP:DMG >> to UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540 | 180463803 | [Secure Channel (0) / Standalone Ack (0x10) / Session = 36254 / Exchange = 650]
CHIPTOOL | 2026-08-07 12:18:00.416968 | CHIP:DMG Header Flags =
CHIPTOOL | 2026-08-07 12:18:00.417909 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.418843 | CHIP:DMG Exchange (0x03) =
CHIPTOOL | 2026-08-07 12:18:00.421209 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.422322 | CHIP:DMG Initiator = true
CHIPTOOL | 2026-08-07 12:18:00.423277 | CHIP:DMG AckMsg = 73092639
CHIPTOOL | 2026-08-07 12:18:00.424199 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.426193 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.427262 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.428201 | CHIP:DMG Additional Fields =
CHIPTOOL | 2026-08-07 12:18:00.429125 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.432806 | CHIP:DMG peer_address = UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540
CHIPTOOL | 2026-08-07 12:18:00.433900 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.434857 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.435786 | CHIP:EM <<< [E:650i S:7377 M:180463803 (Ack:73092639)] (S) Msg TX from 000000000001B669 to 1:A82D9E093D2C5785 [0FFB] [UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
CHIPTOOL | 2026-08-07 12:18:00.438535 | CHIP:EM Flushed pending ack for MessageCounter:73092639 on exchange 650i
INFO | 2026-08-07 12:18:00.440987 | Test Step Completed [PASSED]: Step 4: TH sends KeySetWrite command in the GroupKeyManagement cluster to DUT on EP0 with GroupKeySet fields are as follows:1)GroupKeySetID: 0x01a 2) GroupKeySecurityPolicy: TrustFirst (0) 3)EpochKey0: d0d1d2d3d4d5d6d7d8d9dadbdcdddedf 4)EpochStartTime0: 1 5)EpochKey1: null 6)EpochStartTime1:null 7)EpochKey2: null 8)EpochStartTime2:null 9)GroupKeyMulticastPolicy: 0
INFO | 2026-08-07 12:18:00.443251 | --------------------------------------------------------------------------------
INFO | 2026-08-07 12:18:00.448425 | --------------------------------------------------------------------------------
INFO | 2026-08-07 12:18:00.449631 | Executing Test Step: Step 5: TH sends KeySetWrite command in the GroupKeyManagement cluster to DUT on EP0 with GroupKeySet fields are as follows:1)GroupKeySetID: 0x01a 2) GroupKeySecurityPolicy: TrustFirst (0) 3)EpochKey0: d0d1d2d3d4d5d6d7d8d9dadbdcdddedf 4)EpochStartTime0: 1 5)EpochKey1: d1d1d2d3d4d5d6d7d8d9dadbdcdddedf 6)EpochStartTime1:18446744073709551613 7)EpochKey2: null 8)EpochStartTime2:null 9)GroupKeyMulticastPolicy: 0
CHIPTOOL | 2026-08-07 12:18:00.795232 | CHIP:TOO Command: groupkeymanagement key-set-write {"groupKeySetID": 26, "groupKeySecurityPolicy": 0, "epochKey0": "hex:d0d1d2d3d4d5d6d7d8d9dadbdcdddedf", "epochStartTime0": 1, "epochKey1": "hex:d1d1d2d3d4d5d6d7d8d9dadbdcdddedf", "epochStartTime1": 18446744073709551613, "epochKey2": null, "epochStartTime2": null, "groupKeyMulticastPolicy": 0} 0xa82d9e093d2c5785 0
CHIPTOOL | 2026-08-07 12:18:00.797788 | CHIP:TOO Sending command to node 0xa82d9e093d2c5785
CHIPTOOL | 2026-08-07 12:18:00.799501 | CHIP:CSM FindOrEstablishSession: PeerId = [1:A82D9E093D2C5785]
CHIPTOOL | 2026-08-07 12:18:00.800534 | CHIP:CSM FindOrEstablishSession: No existing OperationalSessionSetup instance found
CHIPTOOL | 2026-08-07 12:18:00.802404 | CHIP:DIS Found an existing secure session to [1:A82D9E093D2C5785]!
CHIPTOOL | 2026-08-07 12:18:00.803475 | CHIP:DIS OperationalSessionSetup[1:A82D9E093D2C5785]: State change 1 --> 5
CHIPTOOL | 2026-08-07 12:18:00.804470 | CHIP:TOO Sending cluster (0x0000003F) command (0x00000000) on endpoint 0
CHIPTOOL | 2026-08-07 12:18:00.805477 | CHIP:DMG ICR moving to [AddingComm]
CHIPTOOL | 2026-08-07 12:18:00.806448 | CHIP:DMG ICR moving to [AddedComma]
CHIPTOOL | 2026-08-07 12:18:00.807447 | CHIP:DMG >> to UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540 | 180463804 | [Interaction Model (1) / InvokeCommandRequest (0x08) / Session = 36254 / Exchange = 651]
CHIPTOOL | 2026-08-07 12:18:00.808463 | CHIP:DMG Header Flags =
CHIPTOOL | 2026-08-07 12:18:00.809433 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.810376 | CHIP:DMG Exchange (0x05) =
CHIPTOOL | 2026-08-07 12:18:00.811295 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.812220 | CHIP:DMG Initiator = true
CHIPTOOL | 2026-08-07 12:18:00.813134 | CHIP:DMG NeedsAck = true
CHIPTOOL | 2026-08-07 12:18:00.814039 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.814918 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.815822 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.816724 | CHIP:DMG Decrypted Payload (96 bytes) =
CHIPTOOL | 2026-08-07 12:18:00.817639 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.818548 | CHIP:DMG data = 1528002801360215370024000024013f240200183501350024001a240100300210d0d1d2d3d4d5d6d7d8d9dadbdcdddedf240301300410d1d1d2d3d4d5d6d7d8d9dadbdcdddedf2705fdffffffffffffff340634072408001818181824ff0c18
CHIPTOOL | 2026-08-07 12:18:00.819499 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.820422 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.821333 | CHIP:DMG InvokeRequestMessage =
CHIPTOOL | 2026-08-07 12:18:00.822249 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.823142 | CHIP:DMG suppressResponse = false,
CHIPTOOL | 2026-08-07 12:18:00.824022 | CHIP:DMG timedRequest = false,
CHIPTOOL | 2026-08-07 12:18:00.824889 | CHIP:DMG InvokeRequests =
CHIPTOOL | 2026-08-07 12:18:00.825856 | CHIP:DMG [
CHIPTOOL | 2026-08-07 12:18:00.826867 | CHIP:DMG CommandDataIB =
CHIPTOOL | 2026-08-07 12:18:00.827907 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.828828 | CHIP:DMG CommandPathIB =
CHIPTOOL | 2026-08-07 12:18:00.829760 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.830659 | CHIP:DMG EndpointId = 0x0,
CHIPTOOL | 2026-08-07 12:18:00.831560 | CHIP:DMG ClusterId = 0x3f,
CHIPTOOL | 2026-08-07 12:18:00.832459 | CHIP:DMG CommandId = 0x0,
CHIPTOOL | 2026-08-07 12:18:00.833374 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.834265 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.835149 | CHIP:DMG CommandFields =
CHIPTOOL | 2026-08-07 12:18:00.836051 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.836969 | CHIP:DMG 0x0 =
CHIPTOOL | 2026-08-07 12:18:00.837889 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.838783 | CHIP:DMG 0x0 = 26 (unsigned),
CHIPTOOL | 2026-08-07 12:18:00.839679 | CHIP:DMG 0x1 = 0 (unsigned),
CHIPTOOL | 2026-08-07 12:18:00.840594 | CHIP:DMG 0x2 = [
CHIPTOOL | 2026-08-07 12:18:00.841490 | CHIP:DMG 0xd0, 0xd1, 0xd2, 0xd3, 0xd4, 0xd5, 0xd6, 0xd7, 0xd8, 0xd9, 0xda, 0xdb, 0xdc, 0xdd, 0xde, 0xdf,
CHIPTOOL | 2026-08-07 12:18:00.842391 | CHIP:DMG ] (16 bytes)
CHIPTOOL | 2026-08-07 12:18:00.843278 | CHIP:DMG 0x3 = 1 (unsigned),
CHIPTOOL | 2026-08-07 12:18:00.844176 | CHIP:DMG 0x4 = [
CHIPTOOL | 2026-08-07 12:18:00.845067 | CHIP:DMG 0xd1, 0xd1, 0xd2, 0xd3, 0xd4, 0xd5, 0xd6, 0xd7, 0xd8, 0xd9, 0xda, 0xdb, 0xdc, 0xdd, 0xde, 0xdf,
CHIPTOOL | 2026-08-07 12:18:00.845963 | CHIP:DMG ] (16 bytes)
CHIPTOOL | 2026-08-07 12:18:00.846835 | CHIP:DMG 0x5 = 18446744073709551613 (unsigned),
CHIPTOOL | 2026-08-07 12:18:00.847744 | CHIP:DMG 0x6 = NULL
CHIPTOOL | 2026-08-07 12:18:00.848714 | CHIP:DMG 0x7 = NULL
CHIPTOOL | 2026-08-07 12:18:00.849607 | CHIP:DMG 0x8 = 0 (unsigned),
CHIPTOOL | 2026-08-07 12:18:00.850514 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.851428 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.852323 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.853513 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.854516 | CHIP:DMG ],
CHIPTOOL | 2026-08-07 12:18:00.856463 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.857555 | CHIP:DMG InteractionModelRevision = 12
CHIPTOOL | 2026-08-07 12:18:00.858573 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.859504 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.862679 | CHIP:DMG Additional Fields =
CHIPTOOL | 2026-08-07 12:18:00.863877 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.864892 | CHIP:DMG peer_address = UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540
CHIPTOOL | 2026-08-07 12:18:00.865849 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.868497 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.869609 | CHIP:EM <<< [E:651i S:7377 M:180463804] (S) Msg TX from 000000000001B669 to 1:A82D9E093D2C5785 [0FFB] [UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:126)
CHIPTOOL | 2026-08-07 12:18:00.870572 | CHIP:EM ??1 [E:651i S:7377 M:180463804] (S) Msg Retransmission to 1:A82D9E093D2C5785 scheduled for 354ms from now [State:Active II:500 AI:300 AT:4000]
CHIPTOOL | 2026-08-07 12:18:00.876154 | CHIP:DMG ICR moving to [AwaitingRe]
CHIPTOOL | 2026-08-07 12:18:00.877271 | CHIP:DMG << from UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540 | 73092640 | [Interaction Model (1) / InvokeCommandResponse (0x09) / Session = 7377 / Exchange = 651]
CHIPTOOL | 2026-08-07 12:18:00.878266 | CHIP:DMG Header Flags =
CHIPTOOL | 2026-08-07 12:18:00.881081 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.882184 | CHIP:DMG Exchange (0x06) =
CHIPTOOL | 2026-08-07 12:18:00.883143 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.884083 | CHIP:DMG AckMsg = 180463804
CHIPTOOL | 2026-08-07 12:18:00.886872 | CHIP:DMG NeedsAck = true
CHIPTOOL | 2026-08-07 12:18:00.888004 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.889064 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.890053 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.893569 | CHIP:DMG Decrypted Payload (33 bytes) =
CHIPTOOL | 2026-08-07 12:18:00.894658 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.895625 | CHIP:DMG data = 1528003601153501370024000024013f2402001835012400001818181824ff0c18
CHIPTOOL | 2026-08-07 12:18:00.896647 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.900500 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.901584 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.902533 | CHIP:DMG Additional Fields =
CHIPTOOL | 2026-08-07 12:18:00.903462 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.905851 | CHIP:DMG peer_address = UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540
CHIPTOOL | 2026-08-07 12:18:00.906948 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.907917 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.908891 | CHIP:EM >>> [E:651i S:7377 M:73092640 (Ack:180463804)] (S) Msg RX from 1:A82D9E093D2C5785 [0FFB] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
CHIPTOOL | 2026-08-07 12:18:00.913201 | CHIP:EM Found matching exchange: 651i, Delegate: 0xffff88004258
CHIPTOOL | 2026-08-07 12:18:00.914332 | CHIP:EM Rxd Ack; Removing MessageCounter:180463804 from Retrans Table on exchange 651i
CHIPTOOL | 2026-08-07 12:18:00.915416 | CHIP:DMG ICR moving to [ResponseRe]
CHIPTOOL | 2026-08-07 12:18:00.918469 | CHIP:DMG InvokeResponseMessage =
CHIPTOOL | 2026-08-07 12:18:00.919558 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.920520 | CHIP:DMG suppressResponse = false,
CHIPTOOL | 2026-08-07 12:18:00.921462 | CHIP:DMG InvokeResponseIBs =
CHIPTOOL | 2026-08-07 12:18:00.926007 | CHIP:DMG [
CHIPTOOL | 2026-08-07 12:18:00.927098 | CHIP:DMG InvokeResponseIB =
CHIPTOOL | 2026-08-07 12:18:00.928082 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.929055 | CHIP:DMG CommandStatusIB =
CHIPTOOL | 2026-08-07 12:18:00.932441 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.933534 | CHIP:DMG CommandPathIB =
CHIPTOOL | 2026-08-07 12:18:00.934496 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.935430 | CHIP:DMG EndpointId = 0x0,
CHIPTOOL | 2026-08-07 12:18:00.936360 | CHIP:DMG ClusterId = 0x3f,
CHIPTOOL | 2026-08-07 12:18:00.937286 | CHIP:DMG CommandId = 0x0,
CHIPTOOL | 2026-08-07 12:18:00.938178 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.939091 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.940032 | CHIP:DMG StatusIB =
CHIPTOOL | 2026-08-07 12:18:00.940959 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.941854 | CHIP:DMG status = 0x00 (SUCCESS),
CHIPTOOL | 2026-08-07 12:18:00.942747 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.944965 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.946027 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.946972 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.947882 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.950700 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.951773 | CHIP:DMG ],
CHIPTOOL | 2026-08-07 12:18:00.952723 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.953647 | CHIP:DMG InteractionModelRevision = 12
CHIPTOOL | 2026-08-07 12:18:00.956520 | CHIP:DMG },
CHIPTOOL | 2026-08-07 12:18:00.957666 | CHIP:DMG Received Command Response Status for Endpoint=0 Cluster=0x0000_003F Command=0x0000_0000 Status=0x0
CHIPTOOL | 2026-08-07 12:18:00.958654 | CHIP:DMG ICR moving to [AwaitingDe]
CHIPTOOL | 2026-08-07 12:18:00.959607 | CHIP:DMG >> to UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540 | 180463805 | [Secure Channel (0) / Standalone Ack (0x10) / Session = 36254 / Exchange = 651]
CHIPTOOL | 2026-08-07 12:18:00.962213 | CHIP:DMG Header Flags =
CHIPTOOL | 2026-08-07 12:18:00.963348 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.964327 | CHIP:DMG Exchange (0x03) =
CHIPTOOL | 2026-08-07 12:18:00.965262 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.967904 | CHIP:DMG Initiator = true
CHIPTOOL | 2026-08-07 12:18:00.969018 | CHIP:DMG AckMsg = 73092640
CHIPTOOL | 2026-08-07 12:18:00.969996 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.970945 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.974395 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.975495 | CHIP:DMG Additional Fields =
CHIPTOOL | 2026-08-07 12:18:00.976447 | CHIP:DMG {
CHIPTOOL | 2026-08-07 12:18:00.977382 | CHIP:DMG peer_address = UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540
CHIPTOOL | 2026-08-07 12:18:00.980111 | CHIP:DMG }
CHIPTOOL | 2026-08-07 12:18:00.981193 | CHIP:DMG
CHIPTOOL | 2026-08-07 12:18:00.982149 | CHIP:EM <<< [E:651i S:7377 M:180463805 (Ack:73092640)] (S) Msg TX from 000000000001B669 to 1:A82D9E093D2C5785 [0FFB] [UDP:[fe80::829d:65ff:fecd:98a6%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
CHIPTOOL | 2026-08-07 12:18:00.984445 | CHIP:EM Flushed pending ack for MessageCounter:73092640 on exchange 651i
INFO | 2026-08-07 12:18:00.986819 | Test Step Completed [PASSED]: Step 5: TH sends KeySetWrite command in the GroupKeyManagement cluster to DUT on EP0 with GroupKeySet fields are as follows:1)GroupKeySetID: 0x01a 2) GroupKeySecurityPolicy: TrustFirst (0) 3)EpochKey0: d0d1d2d3d4d5d6d7d8d9dadbdcdddedf 4)EpochStartTime0: 1 5)EpochKey1: d1d1d2d3d4d5d6d7d8d9dadbdcdddedf 6)EpochStartTime1:18446744073709551613 7)EpochKey2: null 8)EpochStartTime2:null 9)GroupKeyMulticastPolicy: 0
INFO | 2026-08-07 12:18:00.989487 | --------------------------------------------------------------------------------
INFO | 2026-08-07 12:18:00.994604 | --------------------------------------------------------------------------------

Steps to reproduce the behavior

No response

Expected behavior

No response

Log files

No response

PICS file

No response

Screenshots

No response

Environment

No response

Additional Information

No response

Contributor guide

No contributing guide indexed for this repository

First steps

  1. Read the whole issue, then the project's contributing guide.
  2. Comment on the issue to say you are picking it up — it saves two people doing the same work.
  3. Fork the repository and make your change on a branch.
  4. Open a pull request that references the issue number.

Research direction

Reproduce the TH V1.6 behavior for a V1.5.1-or-older DUT, focusing on steps 3, 4, and 18 of TC-GRPKEY-2.2 and the KeySetRead response in the attached logs. Determine the expected handling of GroupKeyMulticastPolicy across versions, then update the harness and its compatibility coverage so the test gives the correct result.

Written by the indexing model from the issue text.

Assessment

Tech stack
shell
Domain
testing-qa, tooling
Issue type
Bug
Difficulty
4/5
Estimated time
3-5 days
Activity status
Quiet
Clarity
Needs clarification
Newbie friendliness
38/100

Get new issues in your inbox

A short digest of beginner-friendly GitHub issues.