blob: c2a3f26a82d510a0fada4d1729398866b5170c97 [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:
116.3.3. [TC-DRLK-3.3] Verification for the Door Lock Events [DUT - Client]
PICS:
- DRLK.C
config:
nodeId: 0x12344321
cluster: "Basic Information"
endpoint: 0
tests:
- label: "Note"
verification: |
For DUT as client test cases, Chip-tool command used below are an example to verify the functionality. For certification test, we expect DUT should have a capability or way to run the equivalent command.
disabled: true
- label: "Note"
verification: |
"NOTE: https://github.com/project-chip/connectedhomeip/tree/master/examples/lock-app/linux#readme
Events to be executed as following
1. Compile app using below command in connectedhomeip folder
a. ./scripts/run_in_build_env.sh "./scripts/build/build_examples.py --target linux-arm64-all-clusters-no-ble-asan-clang build"
2. Build respective app (lock-app)
3. Commission DUT to TH
4. Open 2nd terminal of DUT and provide the below command to obtain PID of DUT
ps -aef|grep lock-app
5. Follow the Verification step below to generate the event in 2nd terminal of DUT "
"Pre-Conditions
Before sending the Events proceed following step:
1. Send Set User Command and Get User for setting User.
2. Send Set Credential Command and Get Credential Status for setting PIN code.
After sending Events with all condition proceed following step
1. Send Clear Credential and Clear User Command.
"
disabled: true
- label: "TH will initiate DoorLockAlarm Event (LockJammed scenario)"
PICS: DRLK.C.E00
verification: |
To trigger the event give the below command by opening an another terminal in DUT (Below is the example command developed in lock-app to generate the event, Vendor Dut should have capability to generate this event)
Product maker needs to provide instructions for how to trigger the command on the DUT. For comparison, the DUT behavior for this test step can be simulated using chip-tool (when DUT is a commissioner) and TH as lock-app.
To generate the event give below command
echo '{"Cmd": "SendDoorLockAlarm", "Params": { "EndpointId": 1, "AlarmCode": 0 } }' > /tmp/chip_lock_app_fifo-<PID> (PID of lock app)
./chip-tool doorlock read-event door-lock-alarm 1 1
Verify "DUT receives the DoorLockAlarm event " on the TH(Lock-app) Log:
[1667212903.691682][14571:14571] CHIP:EM: Handling via exchange: 64458r, Delegate: 0xaaaae921d988
[1667212903.691776][14571:14571] CHIP:IM: Received Read request
[1667212903.691948][14571:14571] CHIP:DMG: ReadRequestMessage =
[1667212903.692014][14571:14571] CHIP:DMG: {
[1667212903.692069][14571:14571] CHIP:DMG: EventPathIBs =
[1667212903.692134][14571:14571] CHIP:DMG: [
[1667212903.692194][14571:14571] CHIP:DMG: EventPath =
[1667212903.692273][14571:14571] CHIP:DMG: {
[1667212903.692349][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212903.692438][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212903.692524][14571:14571] CHIP:DMG: Event = 0x0,
[1667212903.692605][14571:14571] CHIP:DMG: },
[1667212903.692682][14571:14571] CHIP:DMG:
[1667212903.692742][14571:14571] CHIP:DMG: ],
[1667212903.692812][14571:14571] CHIP:DMG:
[1667212903.692875][14571:14571] CHIP:DMG: isFabricFiltered = true,
[1667212903.692939][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667212903.692999][14571:14571] CHIP:DMG: },
[1667212903.693152][14571:14571] CHIP:DMG: IM RH moving to [GeneratingReports]
[1667212903.693365][14571:14571] CHIP:DMG: Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0
[1667212903.693602][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212903.693685][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212903.695145][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212903.695221][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212903.695349][14571:14571] CHIP:DMG: Fetched 1 events
[1667212903.695418][14571:14571] CHIP:DMG: <RE> Sending report (payload has 46 bytes)...
[1667212903.696119][14571:14571] CHIP:EM: <<< [E:64458r M:245645836 (Ack:124517844)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:05 (IM:ReportData)
[1667212903.696219][14571:14571] CHIP:IN: (S) Sending msg 245645836 on secure session with LSID: 42493
[1667212903.696894][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:56504 | 245645836 | [Interaction Model (1) / Report Data (0x05) / Session = 2457 / Exchange = 64458]
[1667212903.696993][14571:14571] CHIP:DMG: Header Flags =
[1667212903.697043][14571:14571] CHIP:DMG: {
[1667212903.697119][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667212903.697175][14571:14571] CHIP:DMG: {
[1667212903.697235][14571:14571] CHIP:DMG: AckMsg = 124517844
[1667212903.697291][14571:14571] CHIP:DMG: NeedsAck = true
[1667212903.697346][14571:14571] CHIP:DMG: }
[1667212903.697417][14571:14571] CHIP:DMG: }
[1667212903.697472][14571:14571] CHIP:DMG:
[1667212903.697541][14571:14571] CHIP:DMG: Encrypted Payload (80 bytes) =
[1667212903.697596][14571:14571] CHIP:DMG: {
[1667212903.697651][14571:14571] CHIP:DMG: data = 009909000c42a40e4f2f234428df2a0988446ed90d2276636fe98e0f0f364944a965130d58e35ceec58aea33c2a23b814e45f8869432562b8a71973e4bd1e7b88dbce86db4779a676d08ead189d07983
[1667212903.697710][14571:14571] CHIP:DMG: buffer_ptr = 187651867783392
[1667212903.697763][14571:14571] CHIP:DMG: }
[1667212903.697815][14571:14571] CHIP:DMG:
[1667212903.697890][14571:14571] CHIP:DMG: Decrypted Payload (46 bytes) =
[1667212903.697946][14571:14571] CHIP:DMG: {
[1667212903.697999][14571:14571] CHIP:DMG: data = 1536021535013700240101250201012403001824011524020226044f5f3a01350724000018181818290424ff0118
[1667212903.698055][14571:14571] CHIP:DMG: }
[1667212903.698107][14571:14571] CHIP:DMG:
[1667212903.698248][14571:14571] CHIP:DMG: ReportDataMessage =
[1667212903.698311][14571:14571] CHIP:DMG: {
[1667212903.698365][14571:14571] CHIP:DMG: EventReportIBs =
[1667212903.698452][14571:14571] CHIP:DMG: [
[1667212903.698579][14571:14571] CHIP:DMG: EventReportIB =
[1667212903.698675][14571:14571] CHIP:DMG: {
[1667212903.698747][14571:14571] CHIP:DMG: EventDataIB =
[1667212903.698835][14571:14571] CHIP:DMG: {
[1667212903.698918][14571:14571] CHIP:DMG: EventPath =
[1667212903.699011][14571:14571] CHIP:DMG: {
[1667212903.699105][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212903.699204][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212903.699291][14571:14571] CHIP:DMG: Event = 0x0,
[1667212903.699370][14571:14571] CHIP:DMG: },
[1667212903.699456][14571:14571] CHIP:DMG:
[1667212903.699537][14571:14571] CHIP:DMG: EventNumber = 0x15,
[1667212903.699623][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212903.699717][14571:14571] CHIP:DMG: SystemTimestamp = 0x13a5f4f,
[1667212903.699799][14571:14571] CHIP:DMG: EventData =
[1667212903.699891][14571:14571] CHIP:DMG: {
[1667212903.699986][14571:14571] CHIP:DMG: 0x0 = 0,
[1667212903.700075][14571:14571] CHIP:DMG: },
[1667212903.700149][14571:14571] CHIP:DMG: },
disabled: true
- label: "DUT sends the Unlock Door command to the TH with valid PINCode"
PICS: DRLK.C.C01.Tx
verification: |
./chip-tool doorlock set-user 0 1 xxx 6452 1 0 0 1 1 --timedInteractionTimeoutMs 1000
Verify "DUT receives the set-user response " on the TH(Lock-app) Log:
[1667212365.328102][14571:14571] CHIP:DMG: Handing timed invoke to IM engine: handler 0xaaab1aeba770 exchange 17749r
[1667212365.328212][14571:14571] CHIP:DMG: InvokeRequestMessage =
[1667212365.328275][14571:14571] CHIP:DMG: {
[1667212365.328333][14571:14571] CHIP:DMG: suppressResponse = false,
[1667212365.328400][14571:14571] CHIP:DMG: timedRequest = true,
[1667212365.328462][14571:14571] CHIP:DMG: InvokeRequests =
[1667212365.328546][14571:14571] CHIP:DMG: [
[1667212365.328609][14571:14571] CHIP:DMG: CommandDataIB =
[1667212365.328692][14571:14571] CHIP:DMG: {
[1667212365.328763][14571:14571] CHIP:DMG: CommandPathIB =
[1667212365.328851][14571:14571] CHIP:DMG: {
[1667212365.328936][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667212365.329029][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667212365.329114][14571:14571] CHIP:DMG: CommandId = 0x1a,
[1667212365.329194][14571:14571] CHIP:DMG: },
[1667212365.329279][14571:14571] CHIP:DMG:
[1667212365.329352][14571:14571] CHIP:DMG: CommandFields =
[1667212365.329432][14571:14571] CHIP:DMG: {
[1667212365.329522][14571:14571] CHIP:DMG: 0x0 = 0,
[1667212365.329617][14571:14571] CHIP:DMG: 0x1 = 1,
[1667212365.329707][14571:14571] CHIP:DMG: 0x2 = "xxx" (3 chars),
[1667212365.329794][14571:14571] CHIP:DMG: 0x3 = 6452,
[1667212365.329880][14571:14571] CHIP:DMG: 0x4 = 1,
[1667212365.329966][14571:14571] CHIP:DMG: 0x5 = 0,
[1667212365.330052][14571:14571] CHIP:DMG: 0x6 = 0,
[1667212365.330135][14571:14571] CHIP:DMG: },
[1667212365.330203][14571:14571] CHIP:DMG: },
[1667212365.330278][14571:14571] CHIP:DMG:
[1667212365.330329][14571:14571] CHIP:DMG: ],
[1667212365.330412][14571:14571] CHIP:DMG:
[1667212365.330505][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667212365.330567][14571:14571] CHIP:DMG: },
[1667212365.330727][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=a
[1667212365.330808][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212365.330879][14571:14571] CHIP:DMG: Received command for Endpoint=1 Cluster=0x0000_0101 Command=0x0000_001A
[1667212365.330990][14571:14571] CHIP:ZCL: [SetUser] Incoming command [endpointId=1,userIndex=1]
[1667212365.331088][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=1]
[1667212365.331146][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=0]
[1667212365.331204][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::SetUser [endpoint=1,userIndex=1,creator=1,modifier=1,userName="xxx",uniqueId=1934,userStatus=1,userType=0,credentialRule=0,credentials=(nil),totalCredentials=0]
[1667212365.331271][14571:14571] CHIP:ZCL: Successfully set the user [mEndpointId=1,index=1,adjustedIndex=0]
[1667212365.331325][14571:14571] CHIP:ZCL: [createUser] User created [endpointId=1,creatorFabricId=1,userIndex=1,userName="xxx",userUniqueId=0x1934,userStatus=1,userType=0,credentialRule=0,totalCredentials=0]
[1667212365.331592][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 1
[1667212365.331711][14571:14571] CHIP:EVL: LogEvent event number: 0x0000000000000011 priority: 1, endpoint id: 0x1 cluster id: 0x0000_0101 event id: 0x4 Sys timestamp: 0x00000000013259CC
[1667212365.331782][14571:14571] CHIP:ZCL: [RemoteLockUserChange] Sent lock user change event [endpointId=1,eventNumber=17,dataType=2,operation=0,nodeId=112233,fabricIndex=1]
[1667212365.331861][14571:14571] CHIP:DMG: Command handler moving to [ Preparing]
[1667212365.331930][14571:14571] CHIP:DMG: Command handler moving to [AddingComm]
[1667212365.331996][14571:14571] CHIP:DMG: Command handler moving to [AddedComma]
[1667212365.332105][14571:14571] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1667212365.332608][14571:14571] CHIP:EM: <<< [E:17749r M:158518861 (Ack:6131454)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:09 (IM:InvokeCommandResponse)
[1667212365.332708][14571:14571] CHIP:IN: (S) Sending msg 158518861 on secure session with LSID: 42488
[1667212365.333364][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:40912 | 158518861 | [Interaction Model (1) / InvokeCommandResponse (0x09) / Session = 22372 / Exchange = 17749]
[1667212365.333462][14571:14571] CHIP:DMG: Header Flags =
[1667212365.333520][14571:14571] CHIP:DMG: {
[1667212365.333606][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667212365.333663][14571:14571] CHIP:DMG: {
[1667212365.333722][14571:14571] CHIP:DMG: AckMsg = 6131454
[1667212365.333778][14571:14571] CHIP:DMG: NeedsAck = true
[1667212365.333832][14571:14571] CHIP:DMG: }
[1667212365.333903][14571:14571] CHIP:DMG: }
[1667212365.333958][14571:14571] CHIP:DMG:
[1667212365.334027][14571:14571] CHIP:DMG: Encrypted Payload (68 bytes) =
[1667212365.334083][14571:14571] CHIP:DMG: {
[1667212365.334137][14571:14571] CHIP:DMG: data = 006457004dce720972b6edbcfc25397da0b462091c6a77f965c77b496ff8623203b76965c8b071825d99bd2e5fcc2962d6bc8bea24c5b1a0106ac76f5841476f791dfe9f
[1667212365.334195][14571:14571] CHIP:DMG: buffer_ptr = 187651867795824
[1667212365.334249][14571:14571] CHIP:DMG: }
[1667212365.334302][14571:14571] CHIP:DMG:
[1667212365.334376][14571:14571] CHIP:DMG: Decrypted Payload (34 bytes) =
[1667212365.334432][14571:14571] CHIP:DMG: {
[1667212365.334560][14571:14571] CHIP:DMG: data = 152800360115350137002400012501010124021a1835012400001818181824ff0118
[1667212365.334619][14571:14571] CHIP:DMG: }
[1667212365.334672][14571:14571] CHIP:DMG:
[1667212365.334806][14571:14571] CHIP:DMG: InvokeResponseMessage =
[1667212365.334870][14571:14571] CHIP:DMG: {
[1667212365.334929][14571:14571] CHIP:DMG: suppressResponse = false,
[1667212365.334991][14571:14571] CHIP:DMG: InvokeResponseIBs =
[1667212365.335068][14571:14571] CHIP:DMG: [
[1667212365.335131][14571:14571] CHIP:DMG: InvokeResponseIB =
[1667212365.335220][14571:14571] CHIP:DMG: {
[1667212365.335291][14571:14571] CHIP:DMG: CommandStatusIB =
[1667212365.335382][14571:14571] CHIP:DMG: {
[1667212365.335454][14571:14571] CHIP:DMG: CommandPathIB =
[1667212365.335540][14571:14571] CHIP:DMG: {
[1667212365.335628][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667212365.335725][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667212365.335821][14571:14571] CHIP:DMG: CommandId = 0x1a,
[1667212365.335906][14571:14571] CHIP:DMG: },
[1667212365.336001][14571:14571] CHIP:DMG:
[1667212365.336084][14571:14571] CHIP:DMG: StatusIB =
[1667212365.336169][14571:14571] CHIP:DMG: {
[1667212365.336255][14571:14571] CHIP:DMG: status = 0x00 (SUCCESS),
[1667212365.336347][14571:14571] CHIP:DMG: },
[1667212365.336440][14571:14571] CHIP:DMG:
[1667212365.336517][14571:14571] CHIP:DMG: },
[1667212365.336610][14571:14571] CHIP:DMG:
[1667212365.336681][14571:14571] CHIP:DMG: },
./chip-tool doorlock set-credential 0 '{ "credentialType" : 1 , "credentialIndex" : 1 }' 123456 1 null null 1 1 --timedInteractionTimeoutMs 1000
Verify "DUT receives the set-credential response " on the TH(Lock-app) Log:
[1667212430.070636][14571:14571] CHIP:DMG: Handing timed invoke to IM engine: handler 0xaaab1aeb7ed0 exchange 360r
[1667212430.070756][14571:14571] CHIP:DMG: InvokeRequestMessage =
[1667212430.070821][14571:14571] CHIP:DMG: {
[1667212430.070880][14571:14571] CHIP:DMG: suppressResponse = false,
[1667212430.070950][14571:14571] CHIP:DMG: timedRequest = true,
[1667212430.071013][14571:14571] CHIP:DMG: InvokeRequests =
[1667212430.071100][14571:14571] CHIP:DMG: [
[1667212430.071163][14571:14571] CHIP:DMG: CommandDataIB =
[1667212430.071233][14571:14571] CHIP:DMG: {
[1667212430.071299][14571:14571] CHIP:DMG: CommandPathIB =
[1667212430.071386][14571:14571] CHIP:DMG: {
[1667212430.071471][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667212430.071560][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667212430.071645][14571:14571] CHIP:DMG: CommandId = 0x22,
[1667212430.071727][14571:14571] CHIP:DMG: },
[1667212430.071814][14571:14571] CHIP:DMG:
[1667212430.071890][14571:14571] CHIP:DMG: CommandFields =
[1667212430.071974][14571:14571] CHIP:DMG: {
[1667212430.072059][14571:14571] CHIP:DMG: 0x0 = 0,
[1667212430.072134][14571:14571] CHIP:DMG: 0x1 =
[1667212430.072217][14571:14571] CHIP:DMG: {
[1667212430.072308][14571:14571] CHIP:DMG: 0x0 = 1,
[1667212430.072390][14571:14571] CHIP:DMG: 0x1 = 1,
[1667212430.072480][14571:14571] CHIP:DMG: },
[1667212430.072567][14571:14571] CHIP:DMG: 0x2 = [
[1667212430.072654][14571:14571] CHIP:DMG: 0x31, 0x32, 0x33, 0x34, 0x35, 0x36,
[1667212430.072748][14571:14571] CHIP:DMG: ] (6 bytes)
[1667212430.072828][14571:14571] CHIP:DMG: 0x3 = 1,
[1667212430.072914][14571:14571] CHIP:DMG: 0x4 = NULL
[1667212430.073002][14571:14571] CHIP:DMG: 0x5 = NULL
[1667212430.073087][14571:14571] CHIP:DMG: },
[1667212430.073163][14571:14571] CHIP:DMG: },
[1667212430.073249][14571:14571] CHIP:DMG:
[1667212430.073310][14571:14571] CHIP:DMG: ],
[1667212430.073394][14571:14571] CHIP:DMG:
[1667212430.073455][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667212430.073515][14571:14571] CHIP:DMG: },
[1667212430.073684][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=a
[1667212430.073767][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212430.073838][14571:14571] CHIP:DMG: Received command for Endpoint=1 Cluster=0x0000_0101 Command=0x0000_0022
[1667212430.073946][14571:14571] CHIP:ZCL: [SetCredential] Incoming command [endpointId=1]
[1667212430.074037][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=2,credentialType=1]
[1667212430.074099][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=2]
[1667212430.074165][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=1,credentialType=1]
[1667212430.074222][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=1]
[1667212430.074276][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=2,credentialType=1]
[1667212430.074331][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=2]
[1667212430.074384][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=3,credentialType=1]
[1667212430.074439][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=3]
[1667212430.074543][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=4,credentialType=1]
[1667212430.074601][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=4]
[1667212430.074654][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=5,credentialType=1]
[1667212430.074709][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=5]
[1667212430.074762][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=6,credentialType=1]
[1667212430.074815][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=6]
[1667212430.074869][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=7,credentialType=1]
[1667212430.074924][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=7]
[1667212430.074977][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=8,credentialType=1]
[1667212430.075031][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=8]
[1667212430.075084][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=9,credentialType=1]
[1667212430.075142][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=9]
[1667212430.075195][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=10,credentialType=1]
[1667212430.075250][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=10]
[1667212430.075303][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=1,credentialType=1]
[1667212430.075358][14571:14571] CHIP:ZCL: Found unoccupied credential [endpoint=1,index=1]
[1667212430.075425][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=1]
[1667212430.075484][14571:14571] CHIP:ZCL: Found occupied user [endpoint=1,adjustedIndex=0,name="xxx",credentialsCount=0,uniqueId=1934,type=0,credentialRule=0,createdBy=1,lastModifiedBy=1]
[1667212430.075546][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=1]
[1667212430.075601][14571:14571] CHIP:ZCL: Found occupied user [endpoint=1,adjustedIndex=0,name="xxx",credentialsCount=0,uniqueId=1934,type=0,credentialRule=0,createdBy=1,lastModifiedBy=1]
[1667212430.075671][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::SetUser [endpoint=1,userIndex=1,creator=1,modifier=1,userName="xxx",uniqueId=1934,userStatus=1,userType=0,credentialRule=0,credentials=0xaaab1aeaf2a0,totalCredentials=1]
[1667212430.075738][14571:14571] CHIP:ZCL: Successfully set the user [mEndpointId=1,index=1,adjustedIndex=0]
[1667212430.075794][14571:14571] CHIP:ZCL: [AddCredentialToUser] Credential added to user [endpointId=1,userIndex=1,credentialType=1,credentialIndex=1,userTotalCredentials=1]
[1667212430.075855][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::SetCredential [endpoint=1,credentialIndex=1,credentialStatus=1,credentialType=1,credentialDataSize=6,creator=1,modifier=1]
[1667212430.075915][14571:14571] CHIP:ZCL: Successfully set the credential [mEndpointId=1,index=1,credentialType=1,creator=1,modifier=1]
[1667212430.076187][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 1
[1667212430.076467][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 2
[1667212430.076669][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 1
[1667212430.076787][14571:14571] CHIP:EVL: LogEvent event number: 0x0000000000000012 priority: 1, endpoint id: 0x1 cluster id: 0x0000_0101 event id: 0x4 Sys timestamp: 0x00000000013356B5
[1667212430.076861][14571:14571] CHIP:ZCL: [RemoteLockUserChange] Sent lock user change event [endpointId=1,eventNumber=18,dataType=6,operation=0,nodeId=112233,fabricIndex=1]
[1667212430.076945][14571:14571] CHIP:DMG: Command handler moving to [ Preparing]
[1667212430.077019][14571:14571] CHIP:DMG: Command handler moving to [AddingComm]
[1667212430.077090][14571:14571] CHIP:DMG: Command handler moving to [AddedComma]
[1667212430.077208][14571:14571] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1667212430.077776][14571:14571] CHIP:EM: <<< [E:360r M:34891597 (Ack:70866396)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:09 (IM:InvokeCommandResponse)
[1667212430.077889][14571:14571] CHIP:IN: (S) Sending msg 34891597 on secure session with LSID: 42489
[1667212430.078657][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:47641 | 34891597 | [Interaction Model (1) / InvokeCommandResponse (0x09) / Session = 55036 / Exchange = 360]
[1667212430.078768][14571:14571] CHIP:DMG: Header Flags =
[1667212430.078827][14571:14571] CHIP:DMG: {
[1667212430.078914][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667212430.078971][14571:14571] CHIP:DMG: {
[1667212430.079040][14571:14571] CHIP:DMG: AckMsg = 70866396
[1667212430.079097][14571:14571] CHIP:DMG: NeedsAck = true
[1667212430.079152][14571:14571] CHIP:DMG: }
[1667212430.079225][14571:14571] CHIP:DMG: }
[1667212430.079281][14571:14571] CHIP:DMG:
[1667212430.079352][14571:14571] CHIP:DMG: Encrypted Payload (73 bytes) =
[1667212430.079408][14571:14571] CHIP:DMG: {
[1667212430.079464][14571:14571] CHIP:DMG: data = 00fcd6004d6714022e20afabbfe9d6e0216e6197c088f75e8666116aba5af25e3bebe35f32967dae83f4b3a1ce17e479091a56822ac3b2046b66858e01f103c81fa073910b5525b797
[1667212430.079524][14571:14571] CHIP:DMG: buffer_ptr = 187651867796608
[1667212430.079578][14571:14571] CHIP:DMG: }
[1667212430.079631][14571:14571] CHIP:DMG:
[1667212430.079706][14571:14571] CHIP:DMG: Decrypted Payload (39 bytes) =
[1667212430.079763][14571:14571] CHIP:DMG: {
[1667212430.079817][14571:14571] CHIP:DMG: data = 152800360115350037002400012501010124022318350124000034012402021818181824ff0118
[1667212430.079873][14571:14571] CHIP:DMG: }
[1667212430.079927][14571:14571] CHIP:DMG:
[1667212430.080082][14571:14571] CHIP:DMG: InvokeResponseMessage =
[1667212430.080149][14571:14571] CHIP:DMG: {
[1667212430.080208][14571:14571] CHIP:DMG: suppressResponse = false,
[1667212430.080279][14571:14571] CHIP:DMG: InvokeResponseIBs =
[1667212430.080361][14571:14571] CHIP:DMG: [
[1667212430.080424][14571:14571] CHIP:DMG: InvokeResponseIB =
[1667212430.080517][14571:14571] CHIP:DMG: {
[1667212430.080592][14571:14571] CHIP:DMG: CommandDataIB =
[1667212430.080674][14571:14571] CHIP:DMG: {
[1667212430.080754][14571:14571] CHIP:DMG: CommandPathIB =
[1667212430.080840][14571:14571] CHIP:DMG: {
[1667212430.080929][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667212430.081011][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667212430.081101][14571:14571] CHIP:DMG: CommandId = 0x23,
[1667212430.081185][14571:14571] CHIP:DMG: },
[1667212430.081275][14571:14571] CHIP:DMG:
[1667212430.081355][14571:14571] CHIP:DMG: CommandFields =
[1667212430.081436][14571:14571] CHIP:DMG: {
[1667212430.081527][14571:14571] CHIP:DMG: 0x0 = 0,
[1667212430.081618][14571:14571] CHIP:DMG: 0x1 = NULL
[1667212430.081709][14571:14571] CHIP:DMG: 0x2 = 2,
[1667212430.081798][14571:14571] CHIP:DMG: },
[1667212430.081880][14571:14571] CHIP:DMG: },
[1667212430.081971][14571:14571] CHIP:DMG:
[1667212430.082043][14571:14571] CHIP:DMG: },
[1667212430.082131][14571:14571] CHIP:DMG:
./chip-tool doorlock unlock-door 1 1 --timedInteractionTimeoutMs 1000 --PINCode 123456
Verify "DUT receives the unlock-door response " on the TH(Lock-app) Log:
[1667212464.510236][14571:14571] CHIP:DMG: Handing timed invoke to IM engine: handler 0xaaab1aebdc20 exchange 12795r
[1667212464.510342][14571:14571] CHIP:DMG: InvokeRequestMessage =
[1667212464.510406][14571:14571] CHIP:DMG: {
[1667212464.510519][14571:14571] CHIP:DMG: suppressResponse = false,
[1667212464.510592][14571:14571] CHIP:DMG: timedRequest = true,
[1667212464.510655][14571:14571] CHIP:DMG: InvokeRequests =
[1667212464.510741][14571:14571] CHIP:DMG: [
[1667212464.510804][14571:14571] CHIP:DMG: CommandDataIB =
[1667212464.510885][14571:14571] CHIP:DMG: {
[1667212464.510956][14571:14571] CHIP:DMG: CommandPathIB =
[1667212464.511045][14571:14571] CHIP:DMG: {
[1667212464.511136][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667212464.511225][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667212464.511470][14571:14571] CHIP:DMG: CommandId = 0x1,
[1667212464.511562][14571:14571] CHIP:DMG: },
[1667212464.511654][14571:14571] CHIP:DMG:
[1667212464.511730][14571:14571] CHIP:DMG: CommandFields =
[1667212464.511815][14571:14571] CHIP:DMG: {
[1667212464.511904][14571:14571] CHIP:DMG: 0x0 = [
[1667212464.511990][14571:14571] CHIP:DMG: 0x31, 0x32, 0x33, 0x34, 0x35, 0x36,
[1667212464.512086][14571:14571] CHIP:DMG: ] (6 bytes)
[1667212464.512170][14571:14571] CHIP:DMG: },
[1667212464.512246][14571:14571] CHIP:DMG: },
[1667212464.512329][14571:14571] CHIP:DMG:
[1667212464.512396][14571:14571] CHIP:DMG: ],
[1667212464.512478][14571:14571] CHIP:DMG:
[1667212464.512538][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667212464.512602][14571:14571] CHIP:DMG: },
[1667212464.512750][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=o
[1667212464.512831][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212464.512899][14571:14571] CHIP:DMG: Received command for Endpoint=1 Cluster=0x0000_0101 Command=0x0000_0001
[1667212464.512980][14571:14571] CHIP:ZCL: Received command: UnlockDoor
[1667212464.513082][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=1]
[1667212464.513143][14571:14571] CHIP:ZCL: Found occupied user [endpoint=1,adjustedIndex=0,name="xxx",credentialsCount=1,uniqueId=1934,type=0,credentialRule=0,createdBy=1,lastModifiedBy=1]
[1667212464.513208][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=1,credentialType=1]
[1667212464.513265][14571:14571] CHIP:ZCL: Found occupied credential [endpoint=1,index=1,type=1,dataSize=6,createdBy=1,modifiedBy=1]
[1667212464.513342][14571:14571] CHIP:ZCL: Lock App: specified PIN code was found in the database, setting door lock state to "Unlocked" [endpointId=1,userIndex=0]
[1667212464.513756][14571:14571] CHIP:DL: writing settings to file (/tmp/chip_kvs-yqY5YM)
[1667212464.515612][14571:14571] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1667212464.515762][14571:14571] CHIP:DMG: Endpoint 1, Cluster 0x0000_0101 update version to b353ec56
[1667212464.515830][14571:14571] CHIP:ZCL: Door Lock attribute changed
[1667212464.515916][14571:14571] CHIP:DMG: Command handler moving to [ Preparing]
[1667212464.515990][14571:14571] CHIP:DMG: Command handler moving to [AddingComm]
[1667212464.516249][14571:14571] CHIP:DMG: Command handler moving to [AddedComma]
[1667212464.516609][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 2
[1667212464.516789][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 2
[1667212464.516979][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 1
[1667212464.517092][14571:14571] CHIP:EVL: LogEvent event number: 0x0000000000000013 priority: 2, endpoint id: 0x1 cluster id: 0x0000_0101 event id: 0x2 Sys timestamp: 0x000000000133DD3D
[1667212464.517233][14571:14571] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1667212464.517805][14571:14571] CHIP:EM: <<< [E:12795r M:107038128 (Ack:214336998)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:09 (IM:InvokeCommandResponse)
[1667212464.517912][14571:14571] CHIP:IN: (S) Sending msg 107038128 on secure session with LSID: 42490
[1667212464.518683][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:48312 | 107038128 | [Interaction Model (1) / InvokeCommandResponse (0x09) / Session = 2999 / Exchange = 12795]
[1667212464.518782][14571:14571] CHIP:DMG: Header Flags =
[1667212464.518840][14571:14571] CHIP:DMG: {
[1667212464.518925][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667212464.518981][14571:14571] CHIP:DMG: {
[1667212464.519040][14571:14571] CHIP:DMG: AckMsg = 214336998
[1667212464.519096][14571:14571] CHIP:DMG: NeedsAck = true
[1667212464.519150][14571:14571] CHIP:DMG: }
[1667212464.519221][14571:14571] CHIP:DMG: }
[1667212464.519276][14571:14571] CHIP:DMG:
[1667212464.519345][14571:14571] CHIP:DMG: Encrypted Payload (68 bytes) =
[1667212464.519401][14571:14571] CHIP:DMG: {
[1667212464.519456][14571:14571] CHIP:DMG: data = 00b70b00b045610604e9744f44f9cf05413cd952a8d72d9e5188de7c50555f86afd3e60f34239a506837d9a128945e521a5e6a25f895ca8fde3588ef010e3edb1d2d5cbc
[1667212464.519514][14571:14571] CHIP:DMG: buffer_ptr = 187651867797152
[1667212464.519567][14571:14571] CHIP:DMG: }
[1667212464.519627][14571:14571] CHIP:DMG:
[1667212464.519702][14571:14571] CHIP:DMG: Decrypted Payload (34 bytes) =
[1667212464.519760][14571:14571] CHIP:DMG: {
[1667212464.519813][14571:14571] CHIP:DMG: data = 15280036011535013700240001250101012402011835012400001818181824ff0118
[1667212464.519868][14571:14571] CHIP:DMG: }
[1667212464.519921][14571:14571] CHIP:DMG:
[1667212464.520057][14571:14571] CHIP:DMG: InvokeResponseMessage =
[1667212464.520120][14571:14571] CHIP:DMG: {
[1667212464.520180][14571:14571] CHIP:DMG: suppressResponse = false,
[1667212464.520252][14571:14571] CHIP:DMG: InvokeResponseIBs =
[1667212464.520329][14571:14571] CHIP:DMG: [
[1667212464.520397][14571:14571] CHIP:DMG: InvokeResponseIB =
[1667212464.520494][14571:14571] CHIP:DMG: {
[1667212464.520575][14571:14571] CHIP:DMG: CommandStatusIB =
[1667212464.520658][14571:14571] CHIP:DMG: {
[1667212464.520742][14571:14571] CHIP:DMG: CommandPathIB =
[1667212464.520828][14571:14571] CHIP:DMG: {
[1667212464.520923][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667212464.521018][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667212464.521108][14571:14571] CHIP:DMG: CommandId = 0x1,
[1667212464.521206][14571:14571] CHIP:DMG: },
[1667212464.521307][14571:14571] CHIP:DMG:
[1667212464.521394][14571:14571] CHIP:DMG: StatusIB =
[1667212464.521488][14571:14571] CHIP:DMG: {
[1667212464.521570][14571:14571] CHIP:DMG: status = 0x00 (SUCCESS),
[1667212464.521665][14571:14571] CHIP:DMG: },
[1667212464.521761][14571:14571] CHIP:DMG:
[1667212464.521844][14571:14571] CHIP:DMG: },
[1667212464.521932][14571:14571] CHIP:DMG:
[1667212464.522044][14571:14571] CHIP:DMG: },
[1667212464.522131][14571:14571] CHIP:DMG:
disabled: true
- label: "TH initiates DoorStateChange event with Doorstate set to DoorOpen"
PICS: DRLK.C.F05 && DRLK.C.E01
verification: |
To trigger the event give below command in another terminal of DUT
echo '{"Cmd": "SetDoorState", "Params": { "EndpointId": 1, "DoorState": 1 } }' > /tmp/chip_lock_app_fifo-<PID> (PID of lock-app)
./chip-tool doorlock read-event door-state-change 1 1
Verify "DUT receives DoorStateChange Event" on the TH(Lock-app) Log:
[1667212766.570915][14571:14571] CHIP:EM: Handling via exchange: 6593r, Delegate: 0xaaaae921d988
[1667212766.571014][14571:14571] CHIP:IM: Received Read request
[1667212766.571186][14571:14571] CHIP:DMG: ReadRequestMessage =
[1667212766.571253][14571:14571] CHIP:DMG: {
[1667212766.571307][14571:14571] CHIP:DMG: EventPathIBs =
[1667212766.571373][14571:14571] CHIP:DMG: [
[1667212766.571434][14571:14571] CHIP:DMG: EventPath =
[1667212766.571510][14571:14571] CHIP:DMG: {
[1667212766.571587][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212766.571668][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212766.571755][14571:14571] CHIP:DMG: Event = 0x1,
[1667212766.571837][14571:14571] CHIP:DMG: },
[1667212766.571913][14571:14571] CHIP:DMG:
[1667212766.571975][14571:14571] CHIP:DMG: ],
[1667212766.572044][14571:14571] CHIP:DMG:
[1667212766.572107][14571:14571] CHIP:DMG: isFabricFiltered = true,
[1667212766.572172][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667212766.572233][14571:14571] CHIP:DMG: },
[1667212766.572387][14571:14571] CHIP:DMG: IM RH moving to [GeneratingReports]
[1667212766.572605][14571:14571] CHIP:DMG: Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0
[1667212766.572695][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212766.572775][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212766.572960][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212766.573030][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212766.574386][14571:14571] CHIP:DMG: Fetched 1 events
[1667212766.574503][14571:14571] CHIP:DMG: <RE> Sending report (payload has 46 bytes)...
[1667212766.575051][14571:14571] CHIP:EM: <<< [E:6593r M:8415234 (Ack:120770608)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:05 (IM:ReportData)
[1667212766.575154][14571:14571] CHIP:IN: (S) Sending msg 8415234 on secure session with LSID: 42491
[1667212766.575827][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:49871 | 8415234 | [Interaction Model (1) / Report Data (0x05) / Session = 60369 / Exchange = 6593]
[1667212766.575925][14571:14571] CHIP:DMG: Header Flags =
[1667212766.575984][14571:14571] CHIP:DMG: {
[1667212766.576071][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667212766.576127][14571:14571] CHIP:DMG: {
[1667212766.576187][14571:14571] CHIP:DMG: AckMsg = 120770608
[1667212766.576243][14571:14571] CHIP:DMG: NeedsAck = true
[1667212766.576296][14571:14571] CHIP:DMG: }
[1667212766.576367][14571:14571] CHIP:DMG: }
[1667212766.576424][14571:14571] CHIP:DMG:
[1667212766.576497][14571:14571] CHIP:DMG: Encrypted Payload (80 bytes) =
[1667212766.576554][14571:14571] CHIP:DMG: {
[1667212766.576609][14571:14571] CHIP:DMG: data = 00d1eb0002688000e58723a7a21404db30fdddb6d56e6d74245cbe730b2e6b7caf945c72d2b053042993d7dc00eb1a5fa89432020b713b83ba898302c2d5c421fe79be57586dfd1bb4c1c5d5f39284dd
[1667212766.576665][14571:14571] CHIP:DMG: buffer_ptr = 187651867798128
[1667212766.576718][14571:14571] CHIP:DMG: }
[1667212766.576770][14571:14571] CHIP:DMG:
[1667212766.576846][14571:14571] CHIP:DMG: Decrypted Payload (46 bytes) =
[1667212766.576902][14571:14571] CHIP:DMG: {
[1667212766.576955][14571:14571] CHIP:DMG: data = 153602153501370024010125020101240301182401012402022604d2cf1b01350724000118181818290424ff0118
[1667212766.577012][14571:14571] CHIP:DMG: }
[1667212766.577067][14571:14571] CHIP:DMG:
[1667212766.577212][14571:14571] CHIP:DMG: ReportDataMessage =
[1667212766.577276][14571:14571] CHIP:DMG: {
[1667212766.577330][14571:14571] CHIP:DMG: EventReportIBs =
[1667212766.577410][14571:14571] CHIP:DMG: [
[1667212766.577472][14571:14571] CHIP:DMG: EventReportIB =
[1667212766.577562][14571:14571] CHIP:DMG: {
[1667212766.577628][14571:14571] CHIP:DMG: EventDataIB =
[1667212766.577715][14571:14571] CHIP:DMG: {
[1667212766.577785][14571:14571] CHIP:DMG: EventPath =
[1667212766.577877][14571:14571] CHIP:DMG: {
[1667212766.577971][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212766.578066][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212766.578156][14571:14571] CHIP:DMG: Event = 0x1,
[1667212766.578241][14571:14571] CHIP:DMG: },
[1667212766.578331][14571:14571] CHIP:DMG:
[1667212766.578410][14571:14571] CHIP:DMG: EventNumber = 0x1,
[1667212766.578544][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212766.578631][14571:14571] CHIP:DMG: SystemTimestamp = 0x11bcfd2,
[1667212766.578714][14571:14571] CHIP:DMG: EventData =
[1667212766.578805][14571:14571] CHIP:DMG: {
[1667212766.578912][14571:14571] CHIP:DMG: 0x0 = 1,
[1667212766.579035][14571:14571] CHIP:DMG: },
[1667212766.579122][14571:14571] CHIP:DMG: },
[1667212766.579222][14571:14571] CHIP:DMG:
[1667212766.579292][14571:14571] CHIP:DMG: },
disabled: true
- label: "DUT sends the Lock Door command to the TH with valid PINCode"
PICS: DRLK.C.C00.Tx
verification: |
./chip-tool doorlock lock-door 1 1 --timedInteractionTimeoutMs 1000 --PINCode 123456
Verify "DUT receives lock Door response" on the TH(Lock-app) Log:
[1667212950.673688][14571:14571] CHIP:DMG: Handing timed invoke to IM engine: handler 0xaaab1aebb880 exchange 65395r
[1667212950.673797][14571:14571] CHIP:DMG: InvokeRequestMessage =
[1667212950.673863][14571:14571] CHIP:DMG: {
[1667212950.673921][14571:14571] CHIP:DMG: suppressResponse = false,
[1667212950.673989][14571:14571] CHIP:DMG: timedRequest = true,
[1667212950.674052][14571:14571] CHIP:DMG: InvokeRequests =
[1667212950.674130][14571:14571] CHIP:DMG: [
[1667212950.674193][14571:14571] CHIP:DMG: CommandDataIB =
[1667212950.674279][14571:14571] CHIP:DMG: {
[1667212950.674354][14571:14571] CHIP:DMG: CommandPathIB =
[1667212950.674447][14571:14571] CHIP:DMG: {
[1667212950.674708][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667212950.674811][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667212950.674907][14571:14571] CHIP:DMG: CommandId = 0x0,
[1667212950.674996][14571:14571] CHIP:DMG: },
[1667212950.675092][14571:14571] CHIP:DMG:
[1667212950.675168][14571:14571] CHIP:DMG: CommandFields =
[1667212950.675258][14571:14571] CHIP:DMG: {
[1667212950.675351][14571:14571] CHIP:DMG: 0x0 = [
[1667212950.675448][14571:14571] CHIP:DMG: 0x31, 0x32, 0x33, 0x34, 0x35, 0x36,
[1667212950.675554][14571:14571] CHIP:DMG: ] (6 bytes)
[1667212950.675650][14571:14571] CHIP:DMG: },
[1667212950.675736][14571:14571] CHIP:DMG: },
[1667212950.675820][14571:14571] CHIP:DMG:
[1667212950.675882][14571:14571] CHIP:DMG: ],
[1667212950.675960][14571:14571] CHIP:DMG:
[1667212950.676022][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667212950.676083][14571:14571] CHIP:DMG: },
[1667212950.676227][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=o
[1667212950.676310][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212950.676373][14571:14571] CHIP:DMG: Received command for Endpoint=1 Cluster=0x0000_0101 Command=0x0000_0000
[1667212950.676455][14571:14571] CHIP:ZCL: Received command: LockDoor
[1667212950.676557][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=1]
[1667212950.676619][14571:14571] CHIP:ZCL: Found occupied user [endpoint=1,adjustedIndex=0,name="xxx",credentialsCount=1,uniqueId=1934,type=0,credentialRule=0,createdBy=1,lastModifiedBy=1]
[1667212950.676684][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=1,credentialType=1]
[1667212950.676741][14571:14571] CHIP:ZCL: Found occupied credential [endpoint=1,index=1,type=1,dataSize=6,createdBy=1,modifiedBy=1]
[1667212950.676819][14571:14571] CHIP:ZCL: Lock App: specified PIN code was found in the database, setting door lock state to "Locked" [endpointId=1,userIndex=0]
[1667212950.677220][14571:14571] CHIP:DL: writing settings to file (/tmp/chip_kvs-1rz1ja)
[1667212950.679102][14571:14571] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1667212950.679268][14571:14571] CHIP:DMG: Endpoint 1, Cluster 0x0000_0101 update version to b353ec58
[1667212950.679337][14571:14571] CHIP:ZCL: Door Lock attribute changed
[1667212950.679427][14571:14571] CHIP:DMG: Command handler moving to [ Preparing]
[1667212950.679712][14571:14571] CHIP:DMG: Command handler moving to [AddingComm]
[1667212950.679800][14571:14571] CHIP:DMG: Command handler moving to [AddedComma]
[1667212950.680182][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 2
[1667212950.680393][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 1
[1667212950.680506][14571:14571] CHIP:EVL: LogEvent event number: 0x0000000000000016 priority: 2, endpoint id: 0x1 cluster id: 0x0000_0101 event id: 0x2 Sys timestamp: 0x00000000013B4851
[1667212950.680628][14571:14571] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1667212950.681229][14571:14571] CHIP:EM: <<< [E:65395r M:196266597 (Ack:191601854)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:09 (IM:InvokeCommandResponse)
[1667212950.681439][14571:14571] CHIP:IN: (S) Sending msg 196266597 on secure session with LSID: 42494
[1667212950.682200][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:47460 | 196266597 | [Interaction Model (1) / InvokeCommandResponse (0x09) / Session = 26681 / Exchange = 65395]
[1667212950.682325][14571:14571] CHIP:DMG: Header Flags =
[1667212950.682376][14571:14571] CHIP:DMG: {
[1667212950.682454][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667212950.682542][14571:14571] CHIP:DMG: {
[1667212950.682595][14571:14571] CHIP:DMG: AckMsg = 191601854
[1667212950.682643][14571:14571] CHIP:DMG: NeedsAck = true
[1667212950.682689][14571:14571] CHIP:DMG: }
[1667212950.682754][14571:14571] CHIP:DMG: }
[1667212950.682800][14571:14571] CHIP:DMG:
[1667212950.682867][14571:14571] CHIP:DMG: Encrypted Payload (68 bytes) =
[1667212950.682914][14571:14571] CHIP:DMG: {
[1667212950.682961][14571:14571] CHIP:DMG: data = 0039680065cab20b92b1da6e682afa8046fad28447b821f0ae1b49599d439bf10147f2f79fbe9eb4c6be40ce47a2de6b6da7d57f825ab0ff06306c059fa5eb8404735bbf
[1667212950.683011][14571:14571] CHIP:DMG: buffer_ptr = 187651867800416
[1667212950.683055][14571:14571] CHIP:DMG: }
[1667212950.683098][14571:14571] CHIP:DMG:
[1667212950.683167][14571:14571] CHIP:DMG: Decrypted Payload (34 bytes) =
[1667212950.683215][14571:14571] CHIP:DMG: {
[1667212950.683261][14571:14571] CHIP:DMG: data = 15280036011535013700240001250101012402001835012400001818181824ff0118
[1667212950.683309][14571:14571] CHIP:DMG: }
[1667212950.683353][14571:14571] CHIP:DMG:
[1667212950.683494][14571:14571] CHIP:DMG: InvokeResponseMessage =
[1667212950.683551][14571:14571] CHIP:DMG: {
[1667212950.683601][14571:14571] CHIP:DMG: suppressResponse = false,
[1667212950.683655][14571:14571] CHIP:DMG: InvokeResponseIBs =
[1667212950.683724][14571:14571] CHIP:DMG: [
[1667212950.683779][14571:14571] CHIP:DMG: InvokeResponseIB =
[1667212950.683853][14571:14571] CHIP:DMG: {
[1667212950.683910][14571:14571] CHIP:DMG: CommandStatusIB =
[1667212950.684012][14571:14571] CHIP:DMG: {
[1667212950.684099][14571:14571] CHIP:DMG: CommandPathIB =
[1667212950.684196][14571:14571] CHIP:DMG: {
[1667212950.684286][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667212950.684378][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667212950.684468][14571:14571] CHIP:DMG: CommandId = 0x0,
[1667212950.684555][14571:14571] CHIP:DMG: },
[1667212950.684655][14571:14571] CHIP:DMG:
[1667212950.684738][14571:14571] CHIP:DMG: StatusIB =
[1667212950.684827][14571:14571] CHIP:DMG: {
[1667212950.684918][14571:14571] CHIP:DMG: status = 0x00 (SUCCESS),
[1667212950.685005][14571:14571] CHIP:DMG: },
[1667212950.685094][14571:14571] CHIP:DMG:
[1667212950.685174][14571:14571] CHIP:DMG: },
disabled: true
- label:
"TH will initiate LockOperation event with LockOperationType to Lock"
PICS: DRLK.C.E02
verification: |
./chip-tool doorlock read-event lock-operation 1 1
Verify "DUT receives LockOperation event response" on the TH(Lock-app) Log:
[1667212998.260957][14571:14571] CHIP:EM: Handling via exchange: 59972r, Delegate: 0xaaaae921d988
[1667212998.261061][14571:14571] CHIP:IM: Received Read request
[1667212998.261236][14571:14571] CHIP:DMG: ReadRequestMessage =
[1667212998.261304][14571:14571] CHIP:DMG: {
[1667212998.261358][14571:14571] CHIP:DMG: EventPathIBs =
[1667212998.261440][14571:14571] CHIP:DMG: [
[1667212998.261508][14571:14571] CHIP:DMG: EventPath =
[1667212998.261600][14571:14571] CHIP:DMG: {
[1667212998.261679][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212998.261975][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212998.262079][14571:14571] CHIP:DMG: Event = 0x2,
[1667212998.262165][14571:14571] CHIP:DMG: },
[1667212998.262245][14571:14571] CHIP:DMG:
[1667212998.262307][14571:14571] CHIP:DMG: ],
[1667212998.262379][14571:14571] CHIP:DMG:
[1667212998.262443][14571:14571] CHIP:DMG: isFabricFiltered = true,
[1667212998.262563][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667212998.262631][14571:14571] CHIP:DMG: },
[1667212998.262794][14571:14571] CHIP:DMG: IM RH moving to [GeneratingReports]
[1667212998.263134][14571:14571] CHIP:DMG: Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0
[1667212998.263478][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212998.263559][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212998.263867][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212998.263941][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212998.264211][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212998.264290][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212998.264469][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212998.264537][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212998.264710][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212998.264778][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212998.265019][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212998.265088][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212998.265857][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212998.265926][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212998.266115][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212998.266183][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212998.266414][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667212998.266519][14571:14571] CHIP:DMG: AccessControl: allowed
[1667212998.266669][14571:14571] CHIP:DMG: Fetched 8 events
[1667212998.266738][14571:14571] CHIP:DMG: <RE> Sending report (payload has 424 bytes)...
[1667212998.267484][14571:14571] CHIP:EM: <<< [E:59972r M:71236657 (Ack:70479114)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:05 (IM:ReportData)
[1667212998.267597][14571:14571] CHIP:IN: (S) Sending msg 71236657 on secure session with LSID: 42495
[1667212998.268415][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:57774 | 71236657 | [Interaction Model (1) / Report Data (0x05) / Session = 21154 / Exchange = 59972]
[1667212998.268519][14571:14571] CHIP:DMG: Header Flags =
[1667212998.268577][14571:14571] CHIP:DMG: {
[1667212998.268665][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667212998.268722][14571:14571] CHIP:DMG: {
[1667212998.268782][14571:14571] CHIP:DMG: AckMsg = 70479114
[1667212998.268838][14571:14571] CHIP:DMG: NeedsAck = true
[1667212998.268893][14571:14571] CHIP:DMG: }
[1667212998.268966][14571:14571] CHIP:DMG: }
[1667212998.269021][14571:14571] CHIP:DMG:
[1667212998.269093][14571:14571] CHIP:DMG: Encrypted Payload (458 bytes) =
[1667212998.269148][14571:14571] CHIP:DMG: {
[1667212998.269204][14571:14571] CHIP:DMG: data = 00a2520031fc3e04115c499465c1cd208e2e32927edc63413acabf4a43897864db050a4d3ae72bb5cd01ada66903eb1c782e21fad83efc535c582d490b190bae3ae14075de5f410c2f36a73d0f702af497b69630ac60f396aa24145878b7dfd891a7f967501dd8633687a64970cb4626e21cb78a34a25142b8ef2dfdb184269392d2ea0f975c8ab626f337b21c26f31f7a2e71413c04c6682195a0df1cf7f284f23990b4d5554d6c03d9e49a9e2584d0248b3778135222c219e02df8b7d66738c06d53c0645fffec7406a3607c18a14f9e10a01aed088633d3f6de1311ce3d2c1d88582a7c780ded3acc332b4486700d54bec4ad9eb4662d71b5ac7f34933f62b8aa10fc3af5698141e863a91868e6016942731fd184c33cc8b322410a867975d0b8a4c8171dac26fde922717585df8246e500ccf8e0ffc2647cf91257bbaca3d44e98c9d12ac7883e4a7cff5cfce0bd2ddd08ea93e55ca5c68ad736a43cf4f88ad9b2a0207aae49476026d17953ad182415ec7d5af810d20d069bfdc8f123a3081ac7bea8d1add2720cbba088b3a6f58bc1edc77e352f1e6e2557446aafcf10260a1e0506f083d3952d87da205cbd36b2cb8625630fc13be40013fdafbdb0640f9c2348204ff6be6e84
[1667212998.269285][14571:14571] CHIP:DMG: buffer_ptr = 187651867800624
[1667212998.269339][14571:14571] CHIP:DMG: }
[1667212998.269392][14571:14571] CHIP:DMG:
[1667212998.269469][14571:14571] CHIP:DMG: Decrypted Payload (424 bytes) =
[1667212998.269528][14571:14571] CHIP:DMG: {
[1667212998.269583][14571:14571] CHIP:DMG: data = 153602153501370024010125020101240302182401042402022604a51720013507240000240107240200240301260469b601003405181818153501370024010125020101240302182401052402022506a2a43507240001240107240200240301260469b60100340518181815350137002401012502010124030218240106240202250668ea35072400002401043402340334043405181818153501370024010125020101240302182401072402022506e2223507240001240107240200240301260469b6010034051818181535013700240101250201012403021824010924020225067eea350724000024010434023403340434051818181535013700240101250201012403021824011324020226062e2911003507240001240107240201240301260469b601003605152400012401011818181818153501370024010125020101240302182401142402022506a0ea35072400002401043402340334043405181818153501370024010125020101240302182401162402022606748006003507240000240107240201240301260469b60100360515240001240101181818181818290424ff0118
[1667212998.269658][14571:14571] CHIP:DMG: }
[1667212998.269710][14571:14571] CHIP:DMG:
[1667212998.270178][14571:14571] CHIP:DMG: ReportDataMessage =
[1667212998.270245][14571:14571] CHIP:DMG: {
[1667212998.270299][14571:14571] CHIP:DMG: EventReportIBs =
[1667212998.270384][14571:14571] CHIP:DMG: [
[1667212998.270447][14571:14571] CHIP:DMG: EventReportIB =
[1667212998.270588][14571:14571] CHIP:DMG: {
[1667212998.270656][14571:14571] CHIP:DMG: EventDataIB =
[1667212998.270744][14571:14571] CHIP:DMG: {
[1667212998.270822][14571:14571] CHIP:DMG: EventPath =
[1667212998.270910][14571:14571] CHIP:DMG: {
[1667212998.270998][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212998.271090][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212998.271344][14571:14571] CHIP:DMG: Event = 0x2,
[1667212998.271434][14571:14571] CHIP:DMG: },
[1667212998.271526][14571:14571] CHIP:DMG:
[1667212998.271610][14571:14571] CHIP:DMG: EventNumber = 0x4,
[1667212998.271697][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212998.271785][14571:14571] CHIP:DMG: SystemTimestamp = 0x12017a5,
[1667212998.271869][14571:14571] CHIP:DMG: EventData =
[1667212998.271954][14571:14571] CHIP:DMG: {
[1667212998.272043][14571:14571] CHIP:DMG: 0x0 = 0,
[1667212998.272134][14571:14571] CHIP:DMG: 0x1 = 7,
[1667212998.272224][14571:14571] CHIP:DMG: 0x2 = 0,
[1667212998.272315][14571:14571] CHIP:DMG: 0x3 = 1,
[1667212998.272406][14571:14571] CHIP:DMG: 0x4 = 112233,
[1667212998.272496][14571:14571] CHIP:DMG: 0x5 = NULL
[1667212998.272585][14571:14571] CHIP:DMG: },
[1667212998.272668][14571:14571] CHIP:DMG: },
[1667212998.272766][14571:14571] CHIP:DMG:
[1667212998.272840][14571:14571] CHIP:DMG: },
[1667212998.273203][14571:14571] CHIP:DMG:
[1667212998.273374][14571:14571] CHIP:DMG: EventReportIB =
[1667212998.273468][14571:14571] CHIP:DMG: {
[1667212998.273543][14571:14571] CHIP:DMG: EventDataIB =
[1667212998.273789][14571:14571] CHIP:DMG: {
[1667212998.273870][14571:14571] CHIP:DMG: EventPath =
[1667212998.273958][14571:14571] CHIP:DMG: {
[1667212998.274046][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212998.274137][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212998.274227][14571:14571] CHIP:DMG: Event = 0x2,
[1667212998.274312][14571:14571] CHIP:DMG: },
[1667212998.274402][14571:14571] CHIP:DMG:
[1667212998.274628][14571:14571] CHIP:DMG: EventNumber = 0x5,
[1667212998.274720][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212998.274806][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0xa4a2,
[1667212998.274955][14571:14571] CHIP:DMG: EventData =
[1667212998.275044][14571:14571] CHIP:DMG: {
[1667212998.275133][14571:14571] CHIP:DMG: 0x0 = 1,
[1667212998.275225][14571:14571] CHIP:DMG: 0x1 = 7,
[1667212998.275316][14571:14571] CHIP:DMG: 0x2 = 0,
[1667212998.275489][14571:14571] CHIP:DMG: 0x3 = 1,
[1667212998.275585][14571:14571] CHIP:DMG: 0x4 = 112233,
[1667212998.275676][14571:14571] CHIP:DMG: 0x5 = NULL
[1667212998.275766][14571:14571] CHIP:DMG: },
[1667212998.275924][14571:14571] CHIP:DMG: },
[1667212998.276021][14571:14571] CHIP:DMG:
[1667212998.276095][14571:14571] CHIP:DMG: },
[1667212998.276209][14571:14571] CHIP:DMG:
[1667212998.276280][14571:14571] CHIP:DMG: EventReportIB =
[1667212998.276380][14571:14571] CHIP:DMG: {
[1667212998.276457][14571:14571] CHIP:DMG: EventDataIB =
[1667212998.276538][14571:14571] CHIP:DMG: {
[1667212998.276618][14571:14571] CHIP:DMG: EventPath =
[1667212998.276703][14571:14571] CHIP:DMG: {
[1667212998.276790][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212998.276879][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212998.276968][14571:14571] CHIP:DMG: Event = 0x2,
[1667212998.277052][14571:14571] CHIP:DMG: },
[1667212998.277141][14571:14571] CHIP:DMG:
[1667212998.277224][14571:14571] CHIP:DMG: EventNumber = 0x6,
[1667212998.277310][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212998.277396][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0xea68,
[1667212998.277479][14571:14571] CHIP:DMG: EventData =
[1667212998.277563][14571:14571] CHIP:DMG: {
[1667212998.277650][14571:14571] CHIP:DMG: 0x0 = 0,
[1667212998.277741][14571:14571] CHIP:DMG: 0x1 = 4,
[1667212998.277831][14571:14571] CHIP:DMG: 0x2 = NULL
[1667212998.277921][14571:14571] CHIP:DMG: 0x3 = NULL
[1667212998.278012][14571:14571] CHIP:DMG: 0x4 = NULL
[1667212998.278102][14571:14571] CHIP:DMG: 0x5 = NULL
[1667212998.278190][14571:14571] CHIP:DMG: },
[1667212998.278270][14571:14571] CHIP:DMG: },
[1667212998.278367][14571:14571] CHIP:DMG:
[1667212998.278441][14571:14571] CHIP:DMG: },
[1667212998.278590][14571:14571] CHIP:DMG:
[1667212998.278654][14571:14571] CHIP:DMG: EventReportIB =
[1667212998.278752][14571:14571] CHIP:DMG: {
[1667212998.278826][14571:14571] CHIP:DMG: EventDataIB =
[1667212998.278908][14571:14571] CHIP:DMG: {
[1667212998.278986][14571:14571] CHIP:DMG: EventPath =
[1667212998.279071][14571:14571] CHIP:DMG: {
[1667212998.279157][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212998.279247][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212998.279337][14571:14571] CHIP:DMG: Event = 0x2,
[1667212998.279421][14571:14571] CHIP:DMG: },
[1667212998.279510][14571:14571] CHIP:DMG:
[1667212998.279595][14571:14571] CHIP:DMG: EventNumber = 0x7,
[1667212998.279681][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212998.279765][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x22e2,
[1667212998.279847][14571:14571] CHIP:DMG: EventData =
[1667212998.279931][14571:14571] CHIP:DMG: {
[1667212998.280019][14571:14571] CHIP:DMG: 0x0 = 1,
[1667212998.280110][14571:14571] CHIP:DMG: 0x1 = 7,
[1667212998.280201][14571:14571] CHIP:DMG: 0x2 = 0,
[1667212998.280291][14571:14571] CHIP:DMG: 0x3 = 1,
[1667212998.280383][14571:14571] CHIP:DMG: 0x4 = 112233,
[1667212998.280473][14571:14571] CHIP:DMG: 0x5 = NULL
[1667212998.280561][14571:14571] CHIP:DMG: },
[1667212998.280643][14571:14571] CHIP:DMG: },
[1667212998.280741][14571:14571] CHIP:DMG:
[1667212998.280815][14571:14571] CHIP:DMG: },
[1667212998.280930][14571:14571] CHIP:DMG:
[1667212998.281000][14571:14571] CHIP:DMG: EventReportIB =
[1667212998.281098][14571:14571] CHIP:DMG: {
[1667212998.281171][14571:14571] CHIP:DMG: EventDataIB =
[1667212998.281252][14571:14571] CHIP:DMG: {
[1667212998.281331][14571:14571] CHIP:DMG: EventPath =
[1667212998.281415][14571:14571] CHIP:DMG: {
[1667212998.281502][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212998.281592][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212998.281772][14571:14571] CHIP:DMG: Event = 0x2,
[1667212998.281859][14571:14571] CHIP:DMG: },
[1667212998.281949][14571:14571] CHIP:DMG:
[1667212998.282033][14571:14571] CHIP:DMG: EventNumber = 0x9,
[1667212998.282118][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212998.282203][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0xea7e,
[1667212998.282284][14571:14571] CHIP:DMG: EventData =
[1667212998.282369][14571:14571] CHIP:DMG: {
[1667212998.282482][14571:14571] CHIP:DMG: 0x0 = 0,
[1667212998.282582][14571:14571] CHIP:DMG: 0x1 = 4,
[1667212998.282672][14571:14571] CHIP:DMG: 0x2 = NULL
[1667212998.282764][14571:14571] CHIP:DMG: 0x3 = NULL
[1667212998.282854][14571:14571] CHIP:DMG: 0x4 = NULL
[1667212998.282943][14571:14571] CHIP:DMG: 0x5 = NULL
[1667212998.283030][14571:14571] CHIP:DMG: },
[1667212998.283111][14571:14571] CHIP:DMG: },
[1667212998.283209][14571:14571] CHIP:DMG:
[1667212998.283280][14571:14571] CHIP:DMG: },
[1667212998.283398][14571:14571] CHIP:DMG:
[1667212998.283461][14571:14571] CHIP:DMG: EventReportIB =
[1667212998.283565][14571:14571] CHIP:DMG: {
[1667212998.283638][14571:14571] CHIP:DMG: EventDataIB =
[1667212998.283719][14571:14571] CHIP:DMG: {
[1667212998.283798][14571:14571] CHIP:DMG: EventPath =
[1667212998.283883][14571:14571] CHIP:DMG: {
[1667212998.283969][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212998.284058][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212998.284147][14571:14571] CHIP:DMG: Event = 0x2,
[1667212998.284231][14571:14571] CHIP:DMG: },
[1667212998.284319][14571:14571] CHIP:DMG:
[1667212998.284402][14571:14571] CHIP:DMG: EventNumber = 0x13,
[1667212998.284488][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212998.284573][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x11292e,
[1667212998.284655][14571:14571] CHIP:DMG: EventData =
[1667212998.284738][14571:14571] CHIP:DMG: {
[1667212998.284826][14571:14571] CHIP:DMG: 0x0 = 1,
[1667212998.284916][14571:14571] CHIP:DMG: 0x1 = 7,
[1667212998.285008][14571:14571] CHIP:DMG: 0x2 = 1,
[1667212998.285098][14571:14571] CHIP:DMG: 0x3 = 1,
[1667212998.285188][14571:14571] CHIP:DMG: 0x4 = 112233,
[1667212998.285274][14571:14571] CHIP:DMG: 0x5 = [
[1667212998.285362][14571:14571] CHIP:DMG:
[1667212998.285456][14571:14571] CHIP:DMG: {
[1667212998.285552][14571:14571] CHIP:DMG: 0x0 = 1,
[1667212998.285652][14571:14571] CHIP:DMG: 0x1 = 1,
[1667212998.285748][14571:14571] CHIP:DMG: },
[1667212998.285841][14571:14571] CHIP:DMG: ],
[1667212998.285929][14571:14571] CHIP:DMG: },
[1667212998.286009][14571:14571] CHIP:DMG: },
[1667212998.286109][14571:14571] CHIP:DMG:
[1667212998.286181][14571:14571] CHIP:DMG: },
[1667212998.286299][14571:14571] CHIP:DMG:
[1667212998.286362][14571:14571] CHIP:DMG: EventReportIB =
[1667212998.286488][14571:14571] CHIP:DMG: {
[1667212998.286559][14571:14571] CHIP:DMG: EventDataIB =
[1667212998.286642][14571:14571] CHIP:DMG: {
[1667212998.286720][14571:14571] CHIP:DMG: EventPath =
[1667212998.286806][14571:14571] CHIP:DMG: {
[1667212998.286893][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212998.286983][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212998.287071][14571:14571] CHIP:DMG: Event = 0x2,
[1667212998.287155][14571:14571] CHIP:DMG: },
[1667212998.287244][14571:14571] CHIP:DMG:
[1667212998.287327][14571:14571] CHIP:DMG: EventNumber = 0x14,
[1667212998.287413][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212998.287498][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0xeaa0,
[1667212998.287579][14571:14571] CHIP:DMG: EventData =
[1667212998.287663][14571:14571] CHIP:DMG: {
[1667212998.287750][14571:14571] CHIP:DMG: 0x0 = 0,
[1667212998.287840][14571:14571] CHIP:DMG: 0x1 = 4,
[1667212998.288362][14571:14571] CHIP:DMG: 0x2 = NULL
[1667212998.288452][14571:14571] CHIP:DMG: 0x3 = NULL
[1667212998.288544][14571:14571] CHIP:DMG: 0x4 = NULL
[1667212998.288634][14571:14571] CHIP:DMG: 0x5 = NULL
[1667212998.288723][14571:14571] CHIP:DMG: },
[1667212998.288804][14571:14571] CHIP:DMG: },
[1667212998.288902][14571:14571] CHIP:DMG:
[1667212998.288975][14571:14571] CHIP:DMG: },
[1667212998.289094][14571:14571] CHIP:DMG:
[1667212998.289165][14571:14571] CHIP:DMG: EventReportIB =
[1667212998.289270][14571:14571] CHIP:DMG: {
[1667212998.289343][14571:14571] CHIP:DMG: EventDataIB =
[1667212998.289425][14571:14571] CHIP:DMG: {
[1667212998.289503][14571:14571] CHIP:DMG: EventPath =
[1667212998.289662][14571:14571] CHIP:DMG: {
[1667212998.289753][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667212998.289842][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667212998.289930][14571:14571] CHIP:DMG: Event = 0x2,
[1667212998.290013][14571:14571] CHIP:DMG: },
[1667212998.290104][14571:14571] CHIP:DMG:
[1667212998.290189][14571:14571] CHIP:DMG: EventNumber = 0x16,
[1667212998.290275][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667212998.290360][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x68074,
[1667212998.290441][14571:14571] CHIP:DMG: EventData =
[1667212998.290606][14571:14571] CHIP:DMG: {
[1667212998.290697][14571:14571] CHIP:DMG: 0x0 = 0,
[1667212998.290789][14571:14571] CHIP:DMG: 0x1 = 7,
[1667212998.290879][14571:14571] CHIP:DMG: 0x2 = 1,
[1667212998.290972][14571:14571] CHIP:DMG: 0x3 = 1,
[1667212998.291064][14571:14571] CHIP:DMG: 0x4 = 112233,
[1667212998.291154][14571:14571] CHIP:DMG: 0x5 = [
[1667212998.291242][14571:14571] CHIP:DMG:
[1667212998.291336][14571:14571] CHIP:DMG: {
[1667212998.291433][14571:14571] CHIP:DMG: 0x0 = 1,
[1667212998.291532][14571:14571] CHIP:DMG: 0x1 = 1,
[1667212998.291629][14571:14571] CHIP:DMG: },
[1667212998.291723][14571:14571] CHIP:DMG: ],
[1667212998.291812][14571:14571] CHIP:DMG: },
[1667212998.291892][14571:14571] CHIP:DMG: },
[1667212998.291992][14571:14571] CHIP:DMG:
[1667212998.292066][14571:14571] CHIP:DMG: },
disabled: true
- label: "DUT sends the Unlock Door command to the TH with Invalid PINCode"
PICS: DRLK.C.C01.Tx
verification: |
./chip-tool doorlock unlock-door 1 1 --timedInteractionTimeoutMs 1000 --PINCode 12345678
Verify "DUT receives Unlock Door command response" on the TH(Lock-app) Log:
[1667215267.105416][14571:14571] CHIP:DMG: Handing timed invoke to IM engine: handler 0xaaab1aebee00 exchange 36245r
[1667215267.105521][14571:14571] CHIP:DMG: InvokeRequestMessage =
[1667215267.105585][14571:14571] CHIP:DMG: {
[1667215267.105643][14571:14571] CHIP:DMG: suppressResponse = false,
[1667215267.105711][14571:14571] CHIP:DMG: timedRequest = true,
[1667215267.105773][14571:14571] CHIP:DMG: InvokeRequests =
[1667215267.105851][14571:14571] CHIP:DMG: [
[1667215267.105914][14571:14571] CHIP:DMG: CommandDataIB =
[1667215267.105992][14571:14571] CHIP:DMG: {
[1667215267.106062][14571:14571] CHIP:DMG: CommandPathIB =
[1667215267.106150][14571:14571] CHIP:DMG: {
[1667215267.106239][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667215267.106328][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667215267.106420][14571:14571] CHIP:DMG: CommandId = 0x1,
[1667215267.106563][14571:14571] CHIP:DMG: },
[1667215267.106651][14571:14571] CHIP:DMG:
[1667215267.106724][14571:14571] CHIP:DMG: CommandFields =
[1667215267.106795][14571:14571] CHIP:DMG: {
[1667215267.106874][14571:14571] CHIP:DMG: 0x0 = [
[1667215267.106969][14571:14571] CHIP:DMG: 0x31, 0x32, 0x33, 0x34, 0x35, 0x36, 0x37, 0x38,
[1667215267.107064][14571:14571] CHIP:DMG: ] (8 bytes)
[1667215267.107155][14571:14571] CHIP:DMG: },
[1667215267.107238][14571:14571] CHIP:DMG: },
[1667215267.107316][14571:14571] CHIP:DMG:
[1667215267.107377][14571:14571] CHIP:DMG: ],
[1667215267.107454][14571:14571] CHIP:DMG:
[1667215267.107661][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667215267.107725][14571:14571] CHIP:DMG: },
[1667215267.107874][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=o
[1667215267.107957][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215267.108027][14571:14571] CHIP:DMG: Received command for Endpoint=1 Cluster=0x0000_0101 Command=0x0000_0001
[1667215267.108107][14571:14571] CHIP:ZCL: Received command: UnlockDoor
[1667215267.108207][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=1]
[1667215267.108267][14571:14571] CHIP:ZCL: Found occupied user [endpoint=1,adjustedIndex=0,name="xxx",credentialsCount=1,uniqueId=1934,type=0,credentialRule=0,createdBy=1,lastModifiedBy=1]
[1667215267.108332][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetCredential [endpoint=1,credentialIndex=1,credentialType=1]
[1667215267.108387][14571:14571] CHIP:ZCL: Found occupied credential [endpoint=1,index=1,type=1,dataSize=6,createdBy=1,modifiedBy=1]
[1667215267.108444][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=2]
[1667215267.108498][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=1]
[1667215267.108552][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=3]
[1667215267.108604][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=2]
[1667215267.108657][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=4]
[1667215267.108709][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=3]
[1667215267.108762][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=5]
[1667215267.108814][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=4]
[1667215267.108867][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=6]
[1667215267.108918][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=5]
[1667215267.108971][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=7]
[1667215267.109023][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=6]
[1667215267.109075][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=8]
[1667215267.109127][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=7]
[1667215267.109184][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=9]
[1667215267.109236][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=8]
[1667215267.109290][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=10]
[1667215267.109343][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=9]
[1667215267.109411][14571:14571] CHIP:ZCL: Lock App: specified PIN code was not found in the database, ignoring command to set lock state to "Unlocked" [endpointId=1]
[1667215267.109509][14571:14571] CHIP:DMG: Command handler moving to [ Preparing]
[1667215267.109584][14571:14571] CHIP:DMG: Command handler moving to [AddingComm]
[1667215267.109650][14571:14571] CHIP:DMG: Command handler moving to [AddedComma]
[1667215267.110004][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 2
[1667215267.110370][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 1
[1667215267.110517][14571:14571] CHIP:EVL: LogEvent event number: 0x0000000000000017 priority: 2, endpoint id: 0x1 cluster id: 0x0000_0101 event id: 0x3 Sys timestamp: 0x00000000015EA0DF
[1667215267.110636][14571:14571] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1667215267.111150][14571:14571] CHIP:EM: <<< [E:36245r M:261136447 (Ack:106346658)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:09 (IM:InvokeCommandResponse)
[1667215267.111247][14571:14571] CHIP:IN: (S) Sending msg 261136447 on secure session with LSID: 42496
[1667215267.111913][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:38328 | 261136447 | [Interaction Model (1) / InvokeCommandResponse (0x09) / Session = 60832 / Exchange = 36245]
[1667215267.112011][14571:14571] CHIP:DMG: Header Flags =
[1667215267.112069][14571:14571] CHIP:DMG: {
[1667215267.112156][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667215267.112212][14571:14571] CHIP:DMG: {
[1667215267.112273][14571:14571] CHIP:DMG: AckMsg = 106346658
[1667215267.112329][14571:14571] CHIP:DMG: NeedsAck = true
[1667215267.112382][14571:14571] CHIP:DMG: }
[1667215267.112454][14571:14571] CHIP:DMG: }
[1667215267.112509][14571:14571] CHIP:DMG:
[1667215267.112578][14571:14571] CHIP:DMG: Encrypted Payload (68 bytes) =
[1667215267.112633][14571:14571] CHIP:DMG: {
[1667215267.112687][14571:14571] CHIP:DMG: data = 00a0ed003fa0900fbf387771b4a3b12d07474edd7655f6bcafc1b4717d2c347729609406630451b06c06d214eda38f9d282ca793f637ad4fedc7049643610beca8622b46
[1667215267.112747][14571:14571] CHIP:DMG: buffer_ptr = 187651867797616
[1667215267.112801][14571:14571] CHIP:DMG: }
[1667215267.112853][14571:14571] CHIP:DMG:
[1667215267.112928][14571:14571] CHIP:DMG: Decrypted Payload (34 bytes) =
[1667215267.112984][14571:14571] CHIP:DMG: {
[1667215267.113037][14571:14571] CHIP:DMG: data = 15280036011535013700240001250101012402011835012400011818181824ff0118
[1667215267.113093][14571:14571] CHIP:DMG: }
[1667215267.113144][14571:14571] CHIP:DMG:
[1667215267.113275][14571:14571] CHIP:DMG: InvokeResponseMessage =
[1667215267.113337][14571:14571] CHIP:DMG: {
[1667215267.113396][14571:14571] CHIP:DMG: suppressResponse = false,
[1667215267.113458][14571:14571] CHIP:DMG: InvokeResponseIBs =
[1667215267.113535][14571:14571] CHIP:DMG: [
[1667215267.113605][14571:14571] CHIP:DMG: InvokeResponseIB =
[1667215267.113693][14571:14571] CHIP:DMG: {
[1667215267.113764][14571:14571] CHIP:DMG: CommandStatusIB =
[1667215267.113845][14571:14571] CHIP:DMG: {
[1667215267.113929][14571:14571] CHIP:DMG: CommandPathIB =
[1667215267.114014][14571:14571] CHIP:DMG: {
[1667215267.114108][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667215267.114199][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667215267.114288][14571:14571] CHIP:DMG: CommandId = 0x1,
[1667215267.114382][14571:14571] CHIP:DMG: },
[1667215267.114530][14571:14571] CHIP:DMG:
[1667215267.114619][14571:14571] CHIP:DMG: StatusIB =
[1667215267.114712][14571:14571] CHIP:DMG: {
[1667215267.114804][14571:14571] CHIP:DMG: status = 0x01 (FAILURE),
[1667215267.114888][14571:14571] CHIP:DMG: },
[1667215267.114973][14571:14571] CHIP:DMG:
disabled: true
- label:
"TH will initiate LockOperationError event by causing Lock error using
invalid PIN"
PICS: DRLK.C.E03
verification: |
./chip-tool doorlock read-event lock-operation-error 1 1
Verify "DUT receives LockOperationError event response" on the TH(Lock-app) Log:
[1667215309.086203][14571:14571] CHIP:EM: Handling via exchange: 32821r, Delegate: 0xaaaae921d988
[1667215309.086300][14571:14571] CHIP:IM: Received Read request
[1667215309.086530][14571:14571] CHIP:DMG: ReadRequestMessage =
[1667215309.086599][14571:14571] CHIP:DMG: {
[1667215309.086653][14571:14571] CHIP:DMG: EventPathIBs =
[1667215309.086718][14571:14571] CHIP:DMG: [
[1667215309.086779][14571:14571] CHIP:DMG: EventPath =
[1667215309.086864][14571:14571] CHIP:DMG: {
[1667215309.086936][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215309.087016][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215309.087097][14571:14571] CHIP:DMG: Event = 0x3,
[1667215309.087180][14571:14571] CHIP:DMG: },
[1667215309.087257][14571:14571] CHIP:DMG:
[1667215309.087318][14571:14571] CHIP:DMG: ],
[1667215309.087388][14571:14571] CHIP:DMG:
[1667215309.087451][14571:14571] CHIP:DMG: isFabricFiltered = true,
[1667215309.087517][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667215309.087577][14571:14571] CHIP:DMG: },
[1667215309.087734][14571:14571] CHIP:DMG: IM RH moving to [GeneratingReports]
[1667215309.087947][14571:14571] CHIP:DMG: Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0
[1667215309.088037][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215309.088115][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215309.089673][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215309.089742][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215309.089897][14571:14571] CHIP:DMG: Fetched 1 events
[1667215309.089966][14571:14571] CHIP:DMG: <RE> Sending report (payload has 75 bytes)...
[1667215309.090564][14571:14571] CHIP:EM: <<< [E:32821r M:164504730 (Ack:106105423)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:05 (IM:ReportData)
[1667215309.090670][14571:14571] CHIP:IN: (S) Sending msg 164504730 on secure session with LSID: 42497
[1667215309.091357][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:48090 | 164504730 | [Interaction Model (1) / Report Data (0x05) / Session = 44278 / Exchange = 32821]
[1667215309.091453][14571:14571] CHIP:DMG: Header Flags =
[1667215309.091511][14571:14571] CHIP:DMG: {
[1667215309.091596][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667215309.091652][14571:14571] CHIP:DMG: {
[1667215309.091712][14571:14571] CHIP:DMG: AckMsg = 106105423
[1667215309.091769][14571:14571] CHIP:DMG: NeedsAck = true
[1667215309.091822][14571:14571] CHIP:DMG: }
[1667215309.091894][14571:14571] CHIP:DMG: }
[1667215309.091949][14571:14571] CHIP:DMG:
[1667215309.092018][14571:14571] CHIP:DMG: Encrypted Payload (109 bytes) =
[1667215309.092074][14571:14571] CHIP:DMG: {
[1667215309.092128][14571:14571] CHIP:DMG: data = 00f6ac009a24ce099bf4d1d908cacfd6383bc919db87a589176cb5f55f70af236c1ee3423bddc87f2b0421e46a7e0da143e119f8c05bbc8bc3295a3c7dd9bf4bd7e7e3f9461f80fa69109faef6b1e36d5434d727b3b808c03f478b60c936def5354f2034a1854c9f32054eb67d
[1667215309.092191][14571:14571] CHIP:DMG: buffer_ptr = 187651867797632
[1667215309.092244][14571:14571] CHIP:DMG: }
[1667215309.092296][14571:14571] CHIP:DMG:
[1667215309.092371][14571:14571] CHIP:DMG: Decrypted Payload (75 bytes) =
[1667215309.092428][14571:14571] CHIP:DMG: {
[1667215309.092482][14571:14571] CHIP:DMG: data = 153602153501370024010125020101240303182401172402022604dfa05e013507240001240107240201240300240401260569b60100360615240001240100181818181818290424ff0118
[1667215309.092539][14571:14571] CHIP:DMG: }
[1667215309.092591][14571:14571] CHIP:DMG:
[1667215309.092906][14571:14571] CHIP:DMG: ReportDataMessage =
[1667215309.092975][14571:14571] CHIP:DMG: {
[1667215309.093029][14571:14571] CHIP:DMG: EventReportIBs =
[1667215309.093119][14571:14571] CHIP:DMG: [
[1667215309.093181][14571:14571] CHIP:DMG: EventReportIB =
[1667215309.093285][14571:14571] CHIP:DMG: {
[1667215309.093366][14571:14571] CHIP:DMG: EventDataIB =
[1667215309.093454][14571:14571] CHIP:DMG: {
[1667215309.093539][14571:14571] CHIP:DMG: EventPath =
[1667215309.093616][14571:14571] CHIP:DMG: {
[1667215309.093709][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215309.093802][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215309.093891][14571:14571] CHIP:DMG: Event = 0x3,
[1667215309.093982][14571:14571] CHIP:DMG: },
[1667215309.094079][14571:14571] CHIP:DMG:
[1667215309.094168][14571:14571] CHIP:DMG: EventNumber = 0x17,
[1667215309.094262][14571:14571] CHIP:DMG: PriorityLevel = 0x2,
[1667215309.094355][14571:14571] CHIP:DMG: SystemTimestamp = 0x15ea0df,
[1667215309.094438][14571:14571] CHIP:DMG: EventData =
[1667215309.094565][14571:14571] CHIP:DMG: {
[1667215309.094661][14571:14571] CHIP:DMG: 0x0 = 1,
[1667215309.094754][14571:14571] CHIP:DMG: 0x1 = 7,
[1667215309.094853][14571:14571] CHIP:DMG: 0x2 = 1,
[1667215309.094943][14571:14571] CHIP:DMG: 0x3 = 0,
[1667215309.095191][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215309.095293][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215309.095386][14571:14571] CHIP:DMG: 0x6 = [
[1667215309.095482][14571:14571] CHIP:DMG:
[1667215309.095586][14571:14571] CHIP:DMG: {
[1667215309.095674][14571:14571] CHIP:DMG: 0x0 = 1,
[1667215309.095799][14571:14571] CHIP:DMG: 0x1 = 0,
[1667215309.095908][14571:14571] CHIP:DMG: },
[1667215309.096010][14571:14571] CHIP:DMG: ],
[1667215309.096097][14571:14571] CHIP:DMG: },
[1667215309.096177][14571:14571] CHIP:DMG: },
[1667215309.096284][14571:14571] CHIP:DMG:
[1667215309.096355][14571:14571] CHIP:DMG: },
disabled: true
- label: "DUT sends Set User command to TH"
PICS: DRLK.C.F08 && DRLK.C.C1a.Tx
verification: |
./chip-tool doorlock set-user 0 3 xxx 6452 1 0 0 1 1 --timedInteractionTimeoutMs 1000
Verify "DUT receives Set User command response" on the TH(Lock-app) Log:
[1667215343.626313][14571:14571] CHIP:DMG: Handing timed invoke to IM engine: handler 0xaaab1aea1e50 exchange 62569r
[1667215343.626425][14571:14571] CHIP:DMG: InvokeRequestMessage =
[1667215343.626536][14571:14571] CHIP:DMG: {
[1667215343.626597][14571:14571] CHIP:DMG: suppressResponse = false,
[1667215343.626665][14571:14571] CHIP:DMG: timedRequest = true,
[1667215343.626726][14571:14571] CHIP:DMG: InvokeRequests =
[1667215343.626813][14571:14571] CHIP:DMG: [
[1667215343.626876][14571:14571] CHIP:DMG: CommandDataIB =
[1667215343.626961][14571:14571] CHIP:DMG: {
[1667215343.627033][14571:14571] CHIP:DMG: CommandPathIB =
[1667215343.627120][14571:14571] CHIP:DMG: {
[1667215343.627210][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667215343.627297][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667215343.627381][14571:14571] CHIP:DMG: CommandId = 0x1a,
[1667215343.627460][14571:14571] CHIP:DMG: },
[1667215343.627550][14571:14571] CHIP:DMG:
[1667215343.627623][14571:14571] CHIP:DMG: CommandFields =
[1667215343.627709][14571:14571] CHIP:DMG: {
[1667215343.627800][14571:14571] CHIP:DMG: 0x0 = 0,
[1667215343.627893][14571:14571] CHIP:DMG: 0x1 = 3,
[1667215343.627983][14571:14571] CHIP:DMG: 0x2 = "xxx" (3 chars),
[1667215343.628071][14571:14571] CHIP:DMG: 0x3 = 6452,
[1667215343.628157][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215343.628242][14571:14571] CHIP:DMG: 0x5 = 0,
[1667215343.628327][14571:14571] CHIP:DMG: 0x6 = 0,
[1667215343.628410][14571:14571] CHIP:DMG: },
[1667215343.628493][14571:14571] CHIP:DMG: },
[1667215343.628584][14571:14571] CHIP:DMG:
[1667215343.628646][14571:14571] CHIP:DMG: ],
[1667215343.628730][14571:14571] CHIP:DMG:
[1667215343.628792][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667215343.628851][14571:14571] CHIP:DMG: },
[1667215343.629010][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=a
[1667215343.629090][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215343.629160][14571:14571] CHIP:DMG: Received command for Endpoint=1 Cluster=0x0000_0101 Command=0x0000_001A
[1667215343.629273][14571:14571] CHIP:ZCL: [SetUser] Incoming command [endpointId=1,userIndex=3]
[1667215343.629371][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::GetUser [endpoint=1,userIndex=3]
[1667215343.629429][14571:14571] CHIP:ZCL: Found unoccupied user [endpoint=1,adjustedIndex=2]
[1667215343.629487][14571:14571] CHIP:ZCL: Lock App: LockEndpoint::SetUser [endpoint=1,userIndex=3,creator=1,modifier=1,userName="xxx",uniqueId=1934,userStatus=1,userType=0,credentialRule=0,credentials=(nil),totalCredentials=0]
[1667215343.629553][14571:14571] CHIP:ZCL: Successfully set the user [mEndpointId=1,index=3,adjustedIndex=2]
[1667215343.629608][14571:14571] CHIP:ZCL: [createUser] User created [endpointId=1,creatorFabricId=1,userIndex=3,userName="xxx",userUniqueId=0x1934,userStatus=1,userType=0,credentialRule=0,totalCredentials=0]
[1667215343.629955][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 2
[1667215343.630156][14571:14571] CHIP:EVL: Copy Event to next buffer with priority 1
[1667215343.630266][14571:14571] CHIP:EVL: LogEvent event number: 0x0000000000000018 priority: 1, endpoint id: 0x1 cluster id: 0x0000_0101 event id: 0x4 Sys timestamp: 0x00000000015FCBC7
[1667215343.630337][14571:14571] CHIP:ZCL: [RemoteLockUserChange] Sent lock user change event [endpointId=1,eventNumber=24,dataType=2,operation=0,nodeId=112233,fabricIndex=1]
[1667215343.630424][14571:14571] CHIP:DMG: Command handler moving to [ Preparing]
[1667215343.630541][14571:14571] CHIP:DMG: Command handler moving to [AddingComm]
[1667215343.630610][14571:14571] CHIP:DMG: Command handler moving to [AddedComma]
[1667215343.630722][14571:14571] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1667215343.631234][14571:14571] CHIP:EM: <<< [E:62569r M:236898903 (Ack:33683114)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:09 (IM:InvokeCommandResponse)
[1667215343.631332][14571:14571] CHIP:IN: (S) Sending msg 236898903 on secure session with LSID: 42498
[1667215343.631985][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:47391 | 236898903 | [Interaction Model (1) / InvokeCommandResponse (0x09) / Session = 1652 / Exchange = 62569]
[1667215343.632081][14571:14571] CHIP:DMG: Header Flags =
[1667215343.632138][14571:14571] CHIP:DMG: {
[1667215343.632223][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667215343.632280][14571:14571] CHIP:DMG: {
[1667215343.632340][14571:14571] CHIP:DMG: AckMsg = 33683114
[1667215343.632395][14571:14571] CHIP:DMG: NeedsAck = true
[1667215343.632449][14571:14571] CHIP:DMG: }
[1667215343.632519][14571:14571] CHIP:DMG: }
[1667215343.632575][14571:14571] CHIP:DMG:
[1667215343.632644][14571:14571] CHIP:DMG: Encrypted Payload (68 bytes) =
[1667215343.632698][14571:14571] CHIP:DMG: {
[1667215343.632752][14571:14571] CHIP:DMG: data = 0074060057ca1e0ef633c3d81bcd21e8b41f1b6236f8d3a3d6508aa4efa6d6b0bf5a61b6a5645308a5d884cb42bfa2adddae75f427ce0be23cad981c074f53094a76c515
[1667215343.632811][14571:14571] CHIP:DMG: buffer_ptr = 187651867797088
[1667215343.633058][14571:14571] CHIP:DMG: }
[1667215343.633112][14571:14571] CHIP:DMG:
[1667215343.633192][14571:14571] CHIP:DMG: Decrypted Payload (34 bytes) =
[1667215343.633249][14571:14571] CHIP:DMG: {
[1667215343.633304][14571:14571] CHIP:DMG: data = 152800360115350137002400012501010124021a1835012400001818181824ff0118
[1667215343.633360][14571:14571] CHIP:DMG: }
[1667215343.633412][14571:14571] CHIP:DMG:
[1667215343.633543][14571:14571] CHIP:DMG: InvokeResponseMessage =
[1667215343.634057][14571:14571] CHIP:DMG: {
[1667215343.634124][14571:14571] CHIP:DMG: suppressResponse = false,
[1667215343.634188][14571:14571] CHIP:DMG: InvokeResponseIBs =
[1667215343.634413][14571:14571] CHIP:DMG: [
[1667215343.634525][14571:14571] CHIP:DMG: InvokeResponseIB =
[1667215343.634618][14571:14571] CHIP:DMG: {
[1667215343.634690][14571:14571] CHIP:DMG: CommandStatusIB =
[1667215343.634777][14571:14571] CHIP:DMG: {
[1667215343.634861][14571:14571] CHIP:DMG: CommandPathIB =
[1667215343.634947][14571:14571] CHIP:DMG: {
[1667215343.635041][14571:14571] CHIP:DMG: EndpointId = 0x1,
[1667215343.635139][14571:14571] CHIP:DMG: ClusterId = 0x101,
[1667215343.635235][14571:14571] CHIP:DMG: CommandId = 0x1a,
[1667215343.635318][14571:14571] CHIP:DMG: },
[1667215343.635420][14571:14571] CHIP:DMG:
[1667215343.635498][14571:14571] CHIP:DMG: StatusIB =
[1667215343.635583][14571:14571] CHIP:DMG: {
[1667215343.635678][14571:14571] CHIP:DMG: status = 0x00 (SUCCESS),
[1667215343.635761][14571:14571] CHIP:DMG: },
[1667215343.635853][14571:14571] CHIP:DMG:
[1667215343.635930][14571:14571] CHIP:DMG: },
[1667215343.636023][14571:14571] CHIP:DMG:
[1667215343.636330][14571:14571] CHIP:DMG: },
disabled: true
- label:
"TH initiates LockUserChange event by adding the UserIndex using Set
User command"
PICS: DRLK.C.E04
verification: |
./chip-tool doorlock read-event lock-user-change 1 1
Verify "DUT receives read-event lock-user-change response" on the TH(Lock-app) Log:
[1667215381.519551][14571:14571] CHIP:EM: Handling via exchange: 17082r, Delegate: 0xaaaae921d988
[1667215381.519649][14571:14571] CHIP:IM: Received Read request
[1667215381.519824][14571:14571] CHIP:DMG: ReadRequestMessage =
[1667215381.519891][14571:14571] CHIP:DMG: {
[1667215381.519944][14571:14571] CHIP:DMG: EventPathIBs =
[1667215381.520009][14571:14571] CHIP:DMG: [
[1667215381.520071][14571:14571] CHIP:DMG: EventPath =
[1667215381.520154][14571:14571] CHIP:DMG: {
[1667215381.520226][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.520310][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.520389][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.520471][14571:14571] CHIP:DMG: },
[1667215381.520548][14571:14571] CHIP:DMG:
[1667215381.520609][14571:14571] CHIP:DMG: ],
[1667215381.520680][14571:14571] CHIP:DMG:
[1667215381.520742][14571:14571] CHIP:DMG: isFabricFiltered = true,
[1667215381.520807][14571:14571] CHIP:DMG: InteractionModelRevision = 1
[1667215381.520866][14571:14571] CHIP:DMG: },
[1667215381.521021][14571:14571] CHIP:DMG: IM RH moving to [GeneratingReports]
[1667215381.521248][14571:14571] CHIP:DMG: Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0
[1667215381.521337][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.521416][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.521970][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.522040][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.522292][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.522359][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.522576][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.522646][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.522828][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.522894][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.523069][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.523136][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.523313][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.523379][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.523554][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.523619][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.523795][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.523861][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.524036][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.524102][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.524283][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.524349][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.524862][14571:14571] CHIP:DMG: AccessControl: checking f=1 a=c s=0x000000000001B669 t= c=0x0000_0101 e=1 p=v
[1667215381.524928][14571:14571] CHIP:DMG: AccessControl: allowed
[1667215381.525059][14571:14571] CHIP:DMG: Fetched 11 events
[1667215381.525125][14571:14571] CHIP:DMG: <RE> Sending report (payload has 631 bytes)...
[1667215381.525872][14571:14571] CHIP:EM: <<< [E:17082r M:265557999 (Ack:110761060)] (S) Msg TX to 1:000000000001B669 [673E] --- Type 0001:05 (IM:ReportData)
[1667215381.525973][14571:14571] CHIP:IN: (S) Sending msg 265557999 on secure session with LSID: 42499
[1667215381.527172][14571:14571] CHIP:DMG: >> to UDP:[fe80::e65f:1ff:fe0e:be37%eth0]:49574 | 265557999 | [Interaction Model (1) / Report Data (0x05) / Session = 8112 / Exchange = 17082]
[1667215381.527272][14571:14571] CHIP:DMG: Header Flags =
[1667215381.527330][14571:14571] CHIP:DMG: {
[1667215381.527416][14571:14571] CHIP:DMG: Exchange (0x06) =
[1667215381.527473][14571:14571] CHIP:DMG: {
[1667215381.527533][14571:14571] CHIP:DMG: AckMsg = 110761060
[1667215381.527645][14571:14571] CHIP:DMG: NeedsAck = true
[1667215381.527700][14571:14571] CHIP:DMG: }
[1667215381.527774][14571:14571] CHIP:DMG: }
[1667215381.527830][14571:14571] CHIP:DMG:
[1667215381.527904][14571:14571] CHIP:DMG: Encrypted Payload (665 bytes) =
[1667215381.527961][14571:14571] CHIP:DMG: {
[1667215381.528016][14571:14571] CHIP:DMG: data = 00b01f00ef17d40fba2ce9d50e1fe1bf3262948a9e36f61bd023f244e7bb0679def72d108f27ac12cc1afbb32f4cdd8a0eaaa839b4c1d2553072651d5bf478d1afccd25a3a5fec7f155124f75d2c4e4347376b68014548b08c066271090bcca34169120f7046ecd6ef78dc6ede11daf9f87957061c5365a177b910e7df7aa7aa661f1b8545a8bca606bdd12df647cf21e1f77afc733cda7fa4c730384f09e98ecda70fa1c52efc4853843d936378098ada61127e632bee94332fe27cb8f861e8c1af2dcc289bba0cf713fec5cbf8706a0955c9c8e17239a8be3ccf2a5822f71f98542efb94afddffb74220356bd6fbf7b527af7b632a1bc135b547a8972d389a3a0959c26c897b04e6e2767a950b21957b2d2d16ca7cc9f0d2f455f6030cee10e3e6a06de7fdfecf8dc5fcd9f6fd09fac36012eef5673579613dafb6227b14ea398ac6225ec84d30b6f29a4ee96787ef010e26e1c28dcdf30d19497d9eb13bceabd0378ea5ac68be632806f09a862a53fbfc731c55e92328fbb5b0d0dda9344a62bdc6819393f5a9dc5bd6ba3ea2691d9865c3fa8f6bc94b06af3d82ce1aae1aa97c28ccf410b23be54d19a526ed4184e26ffeff73c6ea7b5bd12cf192359534698817426f830c7b9bb0793a6fec35a0e11e2c3a339df0110e9fb9521467dea9a3c906cfb0f1f3c6de34b93073cb6cb75a34ee2b3c28e6502d44cc909162c6d7e9798046d2e156041615a089e490f0d8790e36ab01c8316224dcbbd5cb858b1dbc51f1f8eccce64fb493edf9c00298719036dac0de698eb8486831538d15eb0f2b9f0d4965485d89530d31e9a2cef92db2a74cec8faddbf1ccf8abd78e1b31a2b23c63c604594125da85e02292f83dc1da279d35d419bae4ee27bf1b6facfdb98aaa5a17938d60cb19046e56bd26c6f76e1f75f54620345756
[1667215381.528139][14571:14571] CHIP:DMG: buffer_ptr = 187651867797088
[1667215381.528195][14571:14571] CHIP:DMG: }
[1667215381.528248][14571:14571] CHIP:DMG:
[1667215381.528327][14571:14571] CHIP:DMG: Decrypted Payload (631 bytes) =
[1667215381.528384][14571:14571] CHIP:DMG: {
[1667215381.528439][14571:14571] CHIP:DMG: data = 1536021535013700240101250201012403041824010824020126049d4d22013507240002240100240207240301240401260569b601002406011818181535013700240101250201012403041824010a24020125069fac3507240003240100240207240301240401260569b601002406011818181535013700240101250201012403041824010b2402012606e86301003507240003240101240207240301240401260569b601002406011818181535013700240101250201012403041824010c2402012506f0b13507240004240100240207240301240401260569b601002406011818181535013700240101250201012403041824010d2402012606c22e02003507240004240101240207240301240401260569b601002406011818181535013700240101250201012403041824010e240201260665e902003507240002240100240207240302240401260569b601002406021818181535013700240101250201012403041824010f2402012606a74e01003507240002240101240207240302240401260569b60100240602181818153501370024010125020101240304182401102402012606e5d803003507240002240101240207240301240401260569b60100240601181818153501370024010125020101240304182401112402012606050a03003507240002240100240207240301240401260569b60100240601181818153501370024010125020101240304182401122402012506e9fc3507240006240100240207240301240401260569b6010024060118181815350137002401012502010124030418240118240201260612752c003507240002240100240207240303240401260569b6010024060318181818290424ff0118
[1667215381.528554][14571:14571] CHIP:DMG: }
[1667215381.528607][14571:14571] CHIP:DMG:
[1667215381.529222][14571:14571] CHIP:DMG: ReportDataMessage =
[1667215381.529357][14571:14571] CHIP:DMG: {
[1667215381.529414][14571:14571] CHIP:DMG: EventReportIBs =
[1667215381.529501][14571:14571] CHIP:DMG: [
[1667215381.529563][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.529663][14571:14571] CHIP:DMG: {
[1667215381.529734][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.529822][14571:14571] CHIP:DMG: {
[1667215381.529906][14571:14571] CHIP:DMG: EventPath =
[1667215381.529992][14571:14571] CHIP:DMG: {
[1667215381.530079][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.530177][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.530269][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.530353][14571:14571] CHIP:DMG: },
[1667215381.530448][14571:14571] CHIP:DMG:
[1667215381.530683][14571:14571] CHIP:DMG: EventNumber = 0x8,
[1667215381.530769][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.530855][14571:14571] CHIP:DMG: SystemTimestamp = 0x1224d9d,
[1667215381.530937][14571:14571] CHIP:DMG: EventData =
[1667215381.531028][14571:14571] CHIP:DMG: {
[1667215381.531125][14571:14571] CHIP:DMG: 0x0 = 2,
[1667215381.531222][14571:14571] CHIP:DMG: 0x1 = 0,
[1667215381.531320][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.531411][14571:14571] CHIP:DMG: 0x3 = 1,
[1667215381.531503][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.531593][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.531692][14571:14571] CHIP:DMG: 0x6 = 1,
[1667215381.531780][14571:14571] CHIP:DMG: },
[1667215381.531864][14571:14571] CHIP:DMG: },
[1667215381.531971][14571:14571] CHIP:DMG:
[1667215381.532042][14571:14571] CHIP:DMG: },
[1667215381.532165][14571:14571] CHIP:DMG:
[1667215381.532233][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.532329][14571:14571] CHIP:DMG: {
[1667215381.532409][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.532495][14571:14571] CHIP:DMG: {
[1667215381.532573][14571:14571] CHIP:DMG: EventPath =
[1667215381.532657][14571:14571] CHIP:DMG: {
[1667215381.532753][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.532851][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.532940][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.533023][14571:14571] CHIP:DMG: },
[1667215381.533120][14571:14571] CHIP:DMG:
[1667215381.533202][14571:14571] CHIP:DMG: EventNumber = 0xa,
[1667215381.533287][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.533379][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0xac9f,
[1667215381.533461][14571:14571] CHIP:DMG: EventData =
[1667215381.533545][14571:14571] CHIP:DMG: {
[1667215381.533639][14571:14571] CHIP:DMG: 0x0 = 3,
[1667215381.533730][14571:14571] CHIP:DMG: 0x1 = 0,
[1667215381.533829][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.533920][14571:14571] CHIP:DMG: 0x3 = 1,
[1667215381.534018][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.534119][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.534214][14571:14571] CHIP:DMG: 0x6 = 1,
[1667215381.534301][14571:14571] CHIP:DMG: },
[1667215381.534389][14571:14571] CHIP:DMG: },
[1667215381.534520][14571:14571] CHIP:DMG:
[1667215381.534588][14571:14571] CHIP:DMG: },
[1667215381.534702][14571:14571] CHIP:DMG:
[1667215381.534770][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.534866][14571:14571] CHIP:DMG: {
[1667215381.534936][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.535024][14571:14571] CHIP:DMG: {
[1667215381.535107][14571:14571] CHIP:DMG: EventPath =
[1667215381.535198][14571:14571] CHIP:DMG: {
[1667215381.535286][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.535386][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.535475][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.535566][14571:14571] CHIP:DMG: },
[1667215381.535654][14571:14571] CHIP:DMG:
[1667215381.535744][14571:14571] CHIP:DMG: EventNumber = 0xb,
[1667215381.535829][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.535914][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x163e8,
[1667215381.536002][14571:14571] CHIP:DMG: EventData =
[1667215381.536092][14571:14571] CHIP:DMG: {
[1667215381.536179][14571:14571] CHIP:DMG: 0x0 = 3,
[1667215381.536270][14571:14571] CHIP:DMG: 0x1 = 1,
[1667215381.536361][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.536452][14571:14571] CHIP:DMG: 0x3 = 1,
[1667215381.536544][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.536642][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.536819][14571:14571] CHIP:DMG: 0x6 = 1,
[1667215381.536910][14571:14571] CHIP:DMG: },
[1667215381.536999][14571:14571] CHIP:DMG: },
[1667215381.537097][14571:14571] CHIP:DMG:
[1667215381.537168][14571:14571] CHIP:DMG: },
[1667215381.537285][14571:14571] CHIP:DMG:
[1667215381.537353][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.537450][14571:14571] CHIP:DMG: {
[1667215381.537529][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.537609][14571:14571] CHIP:DMG: {
[1667215381.537992][14571:14571] CHIP:DMG: EventPath =
[1667215381.538084][14571:14571] CHIP:DMG: {
[1667215381.538221][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.538322][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.538422][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.538559][14571:14571] CHIP:DMG: },
[1667215381.538648][14571:14571] CHIP:DMG:
[1667215381.538737][14571:14571] CHIP:DMG: EventNumber = 0xc,
[1667215381.538832][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.538917][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0xb1f0,
[1667215381.538998][14571:14571] CHIP:DMG: EventData =
[1667215381.539082][14571:14571] CHIP:DMG: {
[1667215381.539177][14571:14571] CHIP:DMG: 0x0 = 4,
[1667215381.539277][14571:14571] CHIP:DMG: 0x1 = 0,
[1667215381.539367][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.539465][14571:14571] CHIP:DMG: 0x3 = 1,
[1667215381.539556][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.539647][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.539744][14571:14571] CHIP:DMG: 0x6 = 1,
[1667215381.539833][14571:14571] CHIP:DMG: },
[1667215381.539912][14571:14571] CHIP:DMG: },
[1667215381.540008][14571:14571] CHIP:DMG:
[1667215381.540078][14571:14571] CHIP:DMG: },
[1667215381.540198][14571:14571] CHIP:DMG:
[1667215381.540261][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.540367][14571:14571] CHIP:DMG: {
[1667215381.540440][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.540521][14571:14571] CHIP:DMG: {
[1667215381.540599][14571:14571] CHIP:DMG: EventPath =
[1667215381.540692][14571:14571] CHIP:DMG: {
[1667215381.540785][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.540875][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.540972][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.541066][14571:14571] CHIP:DMG: },
[1667215381.541155][14571:14571] CHIP:DMG:
[1667215381.541245][14571:14571] CHIP:DMG: EventNumber = 0xd,
[1667215381.541329][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.541413][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x22ec2,
[1667215381.541493][14571:14571] CHIP:DMG: EventData =
[1667215381.541576][14571:14571] CHIP:DMG: {
[1667215381.541671][14571:14571] CHIP:DMG: 0x0 = 4,
[1667215381.541762][14571:14571] CHIP:DMG: 0x1 = 1,
[1667215381.541861][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.541961][14571:14571] CHIP:DMG: 0x3 = 1,
[1667215381.542052][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.542143][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.542233][14571:14571] CHIP:DMG: 0x6 = 1,
[1667215381.542321][14571:14571] CHIP:DMG: },
[1667215381.542401][14571:14571] CHIP:DMG: },
[1667215381.542524][14571:14571] CHIP:DMG:
[1667215381.542591][14571:14571] CHIP:DMG: },
[1667215381.542704][14571:14571] CHIP:DMG:
[1667215381.542767][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.542861][14571:14571] CHIP:DMG: {
[1667215381.542932][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.543013][14571:14571] CHIP:DMG: {
[1667215381.543090][14571:14571] CHIP:DMG: EventPath =
[1667215381.543173][14571:14571] CHIP:DMG: {
[1667215381.543260][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.543357][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.543445][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.543529][14571:14571] CHIP:DMG: },
[1667215381.543624][14571:14571] CHIP:DMG:
[1667215381.543706][14571:14571] CHIP:DMG: EventNumber = 0xe,
[1667215381.543791][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.543875][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x2e965,
[1667215381.543965][14571:14571] CHIP:DMG: EventData =
[1667215381.544048][14571:14571] CHIP:DMG: {
[1667215381.544143][14571:14571] CHIP:DMG: 0x0 = 2,
[1667215381.544233][14571:14571] CHIP:DMG: 0x1 = 0,
[1667215381.544331][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.544421][14571:14571] CHIP:DMG: 0x3 = 2,
[1667215381.544511][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.544599][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.544690][14571:14571] CHIP:DMG: 0x6 = 2,
[1667215381.544777][14571:14571] CHIP:DMG: },
[1667215381.544856][14571:14571] CHIP:DMG: },
[1667215381.544961][14571:14571] CHIP:DMG:
[1667215381.545031][14571:14571] CHIP:DMG: },
[1667215381.545146][14571:14571] CHIP:DMG:
[1667215381.545208][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.545302][14571:14571] CHIP:DMG: {
[1667215381.545373][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.545460][14571:14571] CHIP:DMG: {
[1667215381.545543][14571:14571] CHIP:DMG: EventPath =
[1667215381.545627][14571:14571] CHIP:DMG: {
[1667215381.545711][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.545808][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.545903][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.545987][14571:14571] CHIP:DMG: },
[1667215381.546074][14571:14571] CHIP:DMG:
[1667215381.546157][14571:14571] CHIP:DMG: EventNumber = 0xf,
[1667215381.546242][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.546326][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x14ea7,
[1667215381.546408][14571:14571] CHIP:DMG: EventData =
[1667215381.546529][14571:14571] CHIP:DMG: {
[1667215381.546627][14571:14571] CHIP:DMG: 0x0 = 2,
[1667215381.546719][14571:14571] CHIP:DMG: 0x1 = 1,
[1667215381.546816][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.546906][14571:14571] CHIP:DMG: 0x3 = 2,
[1667215381.547005][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.547095][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.547192][14571:14571] CHIP:DMG: 0x6 = 2,
[1667215381.547288][14571:14571] CHIP:DMG: },
[1667215381.547375][14571:14571] CHIP:DMG: },
[1667215381.547481][14571:14571] CHIP:DMG:
[1667215381.547551][14571:14571] CHIP:DMG: },
[1667215381.547664][14571:14571] CHIP:DMG:
[1667215381.547726][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.547822][14571:14571] CHIP:DMG: {
[1667215381.547893][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.547979][14571:14571] CHIP:DMG: {
[1667215381.548063][14571:14571] CHIP:DMG: EventPath =
[1667215381.548154][14571:14571] CHIP:DMG: {
[1667215381.548248][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.548703][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.548810][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.548898][14571:14571] CHIP:DMG: },
[1667215381.548988][14571:14571] CHIP:DMG:
[1667215381.549077][14571:14571] CHIP:DMG: EventNumber = 0x10,
[1667215381.549161][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.549245][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x3d8e5,
[1667215381.549328][14571:14571] CHIP:DMG: EventData =
[1667215381.549411][14571:14571] CHIP:DMG: {
[1667215381.549498][14571:14571] CHIP:DMG: 0x0 = 2,
[1667215381.549588][14571:14571] CHIP:DMG: 0x1 = 1,
[1667215381.549678][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.549776][14571:14571] CHIP:DMG: 0x3 = 1,
[1667215381.549874][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.549963][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.550060][14571:14571] CHIP:DMG: 0x6 = 1,
[1667215381.550156][14571:14571] CHIP:DMG: },
[1667215381.550236][14571:14571] CHIP:DMG: },
[1667215381.550338][14571:14571] CHIP:DMG:
[1667215381.550409][14571:14571] CHIP:DMG: },
[1667215381.550551][14571:14571] CHIP:DMG:
[1667215381.550730][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.550840][14571:14571] CHIP:DMG: {
[1667215381.550912][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.550993][14571:14571] CHIP:DMG: {
[1667215381.551071][14571:14571] CHIP:DMG: EventPath =
[1667215381.551155][14571:14571] CHIP:DMG: {
[1667215381.551248][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.551336][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.551490][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.551590][14571:14571] CHIP:DMG: },
[1667215381.551688][14571:14571] CHIP:DMG:
[1667215381.551777][14571:14571] CHIP:DMG: EventNumber = 0x11,
[1667215381.551862][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.551948][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x30a05,
[1667215381.552029][14571:14571] CHIP:DMG: EventData =
[1667215381.552113][14571:14571] CHIP:DMG: {
[1667215381.552209][14571:14571] CHIP:DMG: 0x0 = 2,
[1667215381.552299][14571:14571] CHIP:DMG: 0x1 = 0,
[1667215381.552397][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.552533][14571:14571] CHIP:DMG: 0x3 = 1,
[1667215381.552626][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.552717][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.552807][14571:14571] CHIP:DMG: 0x6 = 1,
[1667215381.552893][14571:14571] CHIP:DMG: },
[1667215381.552973][14571:14571] CHIP:DMG: },
[1667215381.553076][14571:14571] CHIP:DMG:
[1667215381.553146][14571:14571] CHIP:DMG: },
[1667215381.553263][14571:14571] CHIP:DMG:
[1667215381.553325][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.553421][14571:14571] CHIP:DMG: {
[1667215381.553493][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.553579][14571:14571] CHIP:DMG: {
[1667215381.553657][14571:14571] CHIP:DMG: EventPath =
[1667215381.553742][14571:14571] CHIP:DMG: {
[1667215381.553835][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.553923][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.554011][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.554093][14571:14571] CHIP:DMG: },
[1667215381.554189][14571:14571] CHIP:DMG:
[1667215381.554270][14571:14571] CHIP:DMG: EventNumber = 0x12,
[1667215381.554355][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.554439][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0xfce9,
[1667215381.554555][14571:14571] CHIP:DMG: EventData =
[1667215381.554640][14571:14571] CHIP:DMG: {
[1667215381.554733][14571:14571] CHIP:DMG: 0x0 = 6,
[1667215381.554833][14571:14571] CHIP:DMG: 0x1 = 0,
[1667215381.554923][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.555022][14571:14571] CHIP:DMG: 0x3 = 1,
[1667215381.555120][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.555217][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.555307][14571:14571] CHIP:DMG: 0x6 = 1,
[1667215381.555394][14571:14571] CHIP:DMG: },
[1667215381.555481][14571:14571] CHIP:DMG: },
[1667215381.555577][14571:14571] CHIP:DMG:
[1667215381.555647][14571:14571] CHIP:DMG: },
[1667215381.555763][14571:14571] CHIP:DMG:
[1667215381.555830][14571:14571] CHIP:DMG: EventReportIB =
[1667215381.555927][14571:14571] CHIP:DMG: {
[1667215381.556007][14571:14571] CHIP:DMG: EventDataIB =
[1667215381.556087][14571:14571] CHIP:DMG: {
[1667215381.556172][14571:14571] CHIP:DMG: EventPath =
[1667215381.556265][14571:14571] CHIP:DMG: {
[1667215381.556357][14571:14571] CHIP:DMG: Endpoint = 0x1,
[1667215381.556446][14571:14571] CHIP:DMG: Cluster = 0x101,
[1667215381.556535][14571:14571] CHIP:DMG: Event = 0x4,
[1667215381.556619][14571:14571] CHIP:DMG: },
[1667215381.556708][14571:14571] CHIP:DMG:
[1667215381.556790][14571:14571] CHIP:DMG: EventNumber = 0x18,
[1667215381.556875][14571:14571] CHIP:DMG: PriorityLevel = 0x1,
[1667215381.556959][14571:14571] CHIP:DMG: DeltaSystemTimestamp = 0x2c7512,
[1667215381.557040][14571:14571] CHIP:DMG: EventData =
[1667215381.557124][14571:14571] CHIP:DMG: {
[1667215381.557209][14571:14571] CHIP:DMG: 0x0 = 2,
[1667215381.557308][14571:14571] CHIP:DMG: 0x1 = 0,
[1667215381.557397][14571:14571] CHIP:DMG: 0x2 = 7,
[1667215381.557486][14571:14571] CHIP:DMG: 0x3 = 3,
[1667215381.557588][14571:14571] CHIP:DMG: 0x4 = 1,
[1667215381.557757][14571:14571] CHIP:DMG: 0x5 = 112233,
[1667215381.557850][14571:14571] CHIP:DMG: 0x6 = 3,
[1667215381.557939][14571:14571] CHIP:DMG: },
[1667215381.558026][14571:14571] CHIP:DMG: },
[1667215381.558121][14571:14571] CHIP:DMG:
[1667215381.558191][14571:14571] CHIP:DMG: },
disabled: true