Skip to content

Instantly share code, notes, and snippets.

@ShaneQi

ShaneQi/light_turn_on_matter_server.log

Created April 26, 2023 06:48
Show Gist options
  • Save ShaneQi/816bbcbc745c790950667914f22654e0 to your computer and use it in GitHub Desktop.
Save ShaneQi/816bbcbc745c790950667914f22654e0 to your computer and use it in GitHub Desktop.
Download ZIP
The matter-server log of simply turning on a light.
Raw
light_turn_on_matter_server.log
2023-04-26 06:07:27 NUC matter_server.server.client_handler[1] DEBUG [140070004330256] Received: {
"message_id": "759d235ee19c4fac94a820c9c2ac8fa5",
"command": "device_command",
"args": {
"node_id": 25,
"endpoint_id": 1,
"cluster_id": 6,
"command_name": "On",
"payload": {},
"response_type": null,
"timed_request_timeout_ms": null,
"interaction_timeout_ms": null
}
}
2023-04-26 06:07:27 NUC matter_server.server.client_handler[1] DEBUG [140070004330256] Received CommandMessage(message_id='759d235ee19c4fac94a820c9c2ac8fa5', command='device_command', args={'node_id': 25, 'endpoint_id': 1, 'cluster_id': 6, 'command_name': 'On', 'payload': {}, 'response_type': None, 'timed_request_timeout_ms': None, 'interaction_timeout_ms': None})
2023-04-26 06:07:27 NUC matter_server.server.client_handler[1] DEBUG [140070004330256] Handling command device_command
2023-04-26 06:07:27 NUC chip.CSM[1] DEBUG FindOrEstablishSession: PeerId = [1:0000000000000019]
2023-04-26 06:07:27 NUC chip.CSM[1] DEBUG FindOrEstablishSession: No existing OperationalSessionSetup instance found
2023-04-26 06:07:27 NUC chip.CTL[1] INFO Found an existing secure session to [1:0000000000000019]!
2023-04-26 06:07:27 NUC chip.CTL[1] DEBUG OperationalSessionSetup[1:0000000000000019]: State change 1 --> 5
2023-04-26 06:07:27 NUC chip.DMG[1] DEBUG ICR moving to [AddingComm]
2023-04-26 06:07:27 NUC chip.DMG[1] DEBUG ICR moving to [AddedComma]
2023-04-26 06:07:27 NUC chip.EM[1] INFO <<< [E:45293i M:119769567] (S) Msg TX to 1:0000000000000019 [C0BF] --- Type 0001:08 (IM:InvokeCommandRequest)
2023-04-26 06:07:27 NUC chip.IN[1] INFO (S) Sending msg 119769567 on secure session with LSID: 64986
2023-04-26 06:07:27 NUC chip.DMG[1] DEBUG ICR moving to [CommandSen]
2023-04-26 06:07:28 NUC chip.DMG[1] INFO Refresh LivenessCheckTime for 121500 milliseconds with SubscriptionId = 0x40d33b2d Peer = 01:0000000000000006
2023-04-26 06:07:28 NUC chip.EM[1] INFO <<< [E:47335r M:26694659 (Ack:262657602)] (S) Msg TX to 1:0000000000000006 [C0BF] --- Type 0001:01 (IM:StatusResponse)
2023-04-26 06:07:28 NUC chip.IN[1] INFO (S) Sending msg 26694659 on secure session with LSID: 64937
2023-04-26 06:07:28 NUC chip.EM[1] INFO >>> [E:47335r M:262657603 (Ack:26694659)] (S) Msg RX from 1:0000000000000006 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:28 NUC chip.EM[1] DEBUG Found matching exchange: 47335r, Delegate: (nil)
2023-04-26 06:07:28 NUC chip.EM[1] DEBUG Rxd Ack; Removing MessageCounter:26694659 from Retrans Table on exchange 47335r
2023-04-26 06:07:28 NUC chip.EM[1] INFO >>> [E:45293i M:90613339 (Ack:119769567)] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0001:09 (IM:InvokeCommandResponse)
2023-04-26 06:07:28 NUC chip.EM[1] DEBUG Found matching exchange: 45293i, Delegate: 0x7f64a01d0cc8
2023-04-26 06:07:28 NUC chip.EM[1] DEBUG Rxd Ack; Removing MessageCounter:119769567 from Retrans Table on exchange 45293i
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG ICR moving to [ResponseRe]
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG InvokeResponseMessage =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG suppressResponse = false,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG InvokeResponseIBs =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG [
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG InvokeResponseIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG CommandStatusIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG CommandPathIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG EndpointId = 0x1,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG ClusterId = 0x6,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG CommandId = 0x1,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG StatusIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG status = 0x00 (SUCCESS),
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG ],
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG InteractionModelRevision = 1
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] INFO Received Command Response Status for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0001 Status=0x0
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG ICR moving to [AwaitingDe]
2023-04-26 06:07:28 NUC chip.EM[1] INFO <<< [E:45293i M:119769568 (Ack:90613339)] (S) Msg TX to 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:28 NUC chip.IN[1] INFO (S) Sending msg 119769568 on secure session with LSID: 64986
2023-04-26 06:07:28 NUC chip.EM[1] DEBUG Flushed pending ack for MessageCounter:90613339 on exchange 45293i
2023-04-26 06:07:28 NUC chip.EM[1] INFO >>> [E:2792r M:90613340] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0001:05 (IM:ReportData)
2023-04-26 06:07:28 NUC chip.EM[1] DEBUG Handling via exchange: 2792r, Delegate: 0x7f64afff97c8
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG ReportDataMessage =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG SubscriptionId = 0xc3b93ff4,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeReportIBs =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG [
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeReportIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeDataIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG DataVersion = 0xb666234b,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributePathIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Endpoint = 0x1,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Cluster = 0x5,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Attribute = 0x0000_0003,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Data = false,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeReportIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeDataIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG DataVersion = 0x58e3f7d5,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributePathIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Endpoint = 0x1,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Cluster = 0x6,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Attribute = 0x0000_0000,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Data = true,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeReportIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeDataIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG DataVersion = 0x58e3f7d5,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributePathIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Endpoint = 0x1,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Cluster = 0x6,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Attribute = 0x0000_4000,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Data = true,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeReportIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeDataIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG DataVersion = 0x58e3f7d5,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributePathIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Endpoint = 0x1,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Cluster = 0x6,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Attribute = 0x0000_4002,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Data = 0,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeReportIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributeDataIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG DataVersion = 0x1e4ce88d,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG AttributePathIB =
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Endpoint = 0x1,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Cluster = 0x8,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Attribute = 0x0000_0000,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG Data = 1,
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG ],
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG InteractionModelRevision = 1
2023-04-26 06:07:28 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:28 NUC matter_server.server.device_controller.25[1] DEBUG Attribute updated: TypedAttributePath(ClusterType=<class 'chip.clusters.Objects.LevelControl'>, AttributeType=<class 'chip.clusters.Objects.LevelControl.Attributes.CurrentLevel'>, AttributeName='currentLevel', Path=AttributePath(EndpointId=1, ClusterId=8, AttributeId=0)) - new value: 1
2023-04-26 06:07:28 NUC matter_server.server.device_controller.25[1] DEBUG Attribute updated: TypedAttributePath(ClusterType=<class 'chip.clusters.Objects.OnOff'>, AttributeType=<class 'chip.clusters.Objects.OnOff.Attributes.GlobalSceneControl'>, AttributeName='globalSceneControl', Path=AttributePath(EndpointId=1, ClusterId=6, AttributeId=16384)) - new value: True
2023-04-26 06:07:28 NUC matter_server.server.device_controller.25[1] DEBUG Attribute updated: TypedAttributePath(ClusterType=<class 'chip.clusters.Objects.OnOff'>, AttributeType=<class 'chip.clusters.Objects.OnOff.Attributes.OffWaitTime'>, AttributeName='offWaitTime', Path=AttributePath(EndpointId=1, ClusterId=6, AttributeId=16386)) - new value: 0
2023-04-26 06:07:28 NUC matter_server.server.device_controller.25[1] DEBUG Attribute updated: TypedAttributePath(ClusterType=<class 'chip.clusters.Objects.Scenes'>, AttributeType=<class 'chip.clusters.Objects.Scenes.Attributes.SceneValid'>, AttributeName='sceneValid', Path=AttributePath(EndpointId=1, ClusterId=5, AttributeId=3)) - new value: False
2023-04-26 06:07:28 NUC matter_server.server.device_controller.25[1] DEBUG Attribute updated: TypedAttributePath(ClusterType=<class 'chip.clusters.Objects.OnOff'>, AttributeType=<class 'chip.clusters.Objects.OnOff.Attributes.OnOff'>, AttributeName='onOff', Path=AttributePath(EndpointId=1, ClusterId=6, AttributeId=0)) - new value: True
2023-04-26 06:07:28 NUC chip.DMG[1] INFO Refresh LivenessCheckTime for 121500 milliseconds with SubscriptionId = 0xc3b93ff4 Peer = 01:0000000000000019
2023-04-26 06:07:28 NUC chip.EM[1] INFO <<< [E:2792r M:119769569 (Ack:90613340)] (S) Msg TX to 1:0000000000000019 [C0BF] --- Type 0001:01 (IM:StatusResponse)
2023-04-26 06:07:28 NUC chip.IN[1] INFO (S) Sending msg 119769569 on secure session with LSID: 64986
2023-04-26 06:07:28 NUC chip.IN[1] DEBUG Received a duplicate message with MessageCounter:90613339 on exchange 45293i
2023-04-26 06:07:28 NUC chip.EM[1] INFO >>> [E:45293i M:90613339 (Ack:119769567)] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0001:09 (IM:InvokeCommandResponse)
2023-04-26 06:07:28 NUC chip.EM[1] DEBUG Generating StandaloneAck via exchange: 45293i
2023-04-26 06:07:28 NUC chip.EM[1] DEBUG Forcing tx of solitary ack for duplicate MessageCounter:90613339 on exchange 45293i
2023-04-26 06:07:28 NUC chip.EM[1] INFO <<< [E:45293i M:119769570 (Ack:90613339)] (S) Msg TX to 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:28 NUC chip.IN[1] INFO (S) Sending msg 119769570 on secure session with LSID: 64986
2023-04-26 06:07:29 NUC chip.IN[1] DEBUG Received a duplicate message with MessageCounter:90613340 on exchange 2792r
2023-04-26 06:07:29 NUC chip.EM[1] INFO >>> [E:2792r M:90613340] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0001:05 (IM:ReportData)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Found matching exchange: 2792r, Delegate: (nil)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Forcing tx of solitary ack for duplicate MessageCounter:90613340 on exchange 2792r
2023-04-26 06:07:29 NUC chip.EM[1] INFO <<< [E:2792r M:119769571 (Ack:90613340)] (S) Msg TX to 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:29 NUC chip.IN[1] INFO (S) Sending msg 119769571 on secure session with LSID: 64986
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Retransmitting MessageCounter:119769569 on exchange 2792r Send Cnt 1
2023-04-26 06:07:29 NUC chip.IN[1] INFO (S) Sending msg 119769569 on secure session with LSID: 64986
2023-04-26 06:07:29 NUC chip.IN[1] DEBUG Received a duplicate message with MessageCounter:90613339 on exchange 45293i
2023-04-26 06:07:29 NUC chip.EM[1] INFO >>> [E:45293i M:90613339 (Ack:119769567)] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0001:09 (IM:InvokeCommandResponse)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Generating StandaloneAck via exchange: 45293i
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Forcing tx of solitary ack for duplicate MessageCounter:90613339 on exchange 45293i
2023-04-26 06:07:29 NUC chip.EM[1] INFO <<< [E:45293i M:119769572 (Ack:90613339)] (S) Msg TX to 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:29 NUC chip.IN[1] INFO (S) Sending msg 119769572 on secure session with LSID: 64986
2023-04-26 06:07:29 NUC chip.IN[1] DEBUG Received a duplicate message with MessageCounter:90613340 on exchange 2792r
2023-04-26 06:07:29 NUC chip.EM[1] INFO >>> [E:2792r M:90613340] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0001:05 (IM:ReportData)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Found matching exchange: 2792r, Delegate: (nil)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Forcing tx of solitary ack for duplicate MessageCounter:90613340 on exchange 2792r
2023-04-26 06:07:29 NUC chip.EM[1] INFO <<< [E:2792r M:119769573 (Ack:90613340)] (S) Msg TX to 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:29 NUC chip.IN[1] INFO (S) Sending msg 119769573 on secure session with LSID: 64986
2023-04-26 06:07:29 NUC chip.EM[1] INFO >>> [E:2792r M:90613341 (Ack:119769569)] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Found matching exchange: 2792r, Delegate: (nil)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Rxd Ack; Removing MessageCounter:119769569 from Retrans Table on exchange 2792r
2023-04-26 06:07:29 NUC chip.EM[1] INFO >>> [E:2795r M:90613342] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0001:05 (IM:ReportData)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Handling via exchange: 2795r, Delegate: 0x7f64afff97c8
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG ReportDataMessage =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG SubscriptionId = 0xc3b93ff4,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributeReportIBs =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG [
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributeReportIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributeDataIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG DataVersion = 0xb66623ed,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributePathIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Endpoint = 0x1,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Cluster = 0x5,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Attribute = 0x0000_0003,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Data = false,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributeReportIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributeDataIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG DataVersion = 0x1e4cea0d,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributePathIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Endpoint = 0x1,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Cluster = 0x8,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Attribute = 0x0000_0000,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Data = 163,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributeReportIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributeDataIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG DataVersion = 0x1e4cea0d,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributePathIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Endpoint = 0x1,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Cluster = 0x8,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Attribute = 0x0000_0001,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Data = 0,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributeReportIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributeDataIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG DataVersion = 0x1e4cea0d,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG AttributePathIB =
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG {
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Endpoint = 0x1,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Cluster = 0x8,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Attribute = 0x0000_0011,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG Data = 163,
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG },
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG ],
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG InteractionModelRevision = 1
2023-04-26 06:07:29 NUC chip.DMG[1] DEBUG }
2023-04-26 06:07:29 NUC matter_server.server.device_controller.25[1] DEBUG Attribute updated: TypedAttributePath(ClusterType=<class 'chip.clusters.Objects.LevelControl'>, AttributeType=<class 'chip.clusters.Objects.LevelControl.Attributes.RemainingTime'>, AttributeName='remainingTime', Path=AttributePath(EndpointId=1, ClusterId=8, AttributeId=1)) - new value: 0
2023-04-26 06:07:29 NUC matter_server.server.device_controller.25[1] DEBUG Attribute updated: TypedAttributePath(ClusterType=<class 'chip.clusters.Objects.Scenes'>, AttributeType=<class 'chip.clusters.Objects.Scenes.Attributes.SceneValid'>, AttributeName='sceneValid', Path=AttributePath(EndpointId=1, ClusterId=5, AttributeId=3)) - new value: False
2023-04-26 06:07:29 NUC matter_server.server.device_controller.25[1] DEBUG Attribute updated: TypedAttributePath(ClusterType=<class 'chip.clusters.Objects.LevelControl'>, AttributeType=<class 'chip.clusters.Objects.LevelControl.Attributes.CurrentLevel'>, AttributeName='currentLevel', Path=AttributePath(EndpointId=1, ClusterId=8, AttributeId=0)) - new value: 163
2023-04-26 06:07:29 NUC matter_server.server.device_controller.25[1] DEBUG Attribute updated: TypedAttributePath(ClusterType=<class 'chip.clusters.Objects.LevelControl'>, AttributeType=<class 'chip.clusters.Objects.LevelControl.Attributes.OnLevel'>, AttributeName='onLevel', Path=AttributePath(EndpointId=1, ClusterId=8, AttributeId=17)) - new value: 163
2023-04-26 06:07:29 NUC chip.DMG[1] INFO Refresh LivenessCheckTime for 121500 milliseconds with SubscriptionId = 0xc3b93ff4 Peer = 01:0000000000000019
2023-04-26 06:07:29 NUC chip.EM[1] INFO <<< [E:2795r M:119769574 (Ack:90613342)] (S) Msg TX to 1:0000000000000019 [C0BF] --- Type 0001:01 (IM:StatusResponse)
2023-04-26 06:07:29 NUC chip.IN[1] INFO (S) Sending msg 119769574 on secure session with LSID: 64986
2023-04-26 06:07:29 NUC chip.EM[1] INFO >>> [E:2792r M:90613343 (Ack:119769569)] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:29 NUC chip.EM[1] INFO >>> [E:2792r M:90613344 (Ack:119769569)] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:29 NUC chip.IN[1] DEBUG Received a duplicate message with MessageCounter:90613342 on exchange 2795r
2023-04-26 06:07:29 NUC chip.EM[1] INFO >>> [E:2795r M:90613342] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0001:05 (IM:ReportData)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Found matching exchange: 2795r, Delegate: (nil)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Forcing tx of solitary ack for duplicate MessageCounter:90613342 on exchange 2795r
2023-04-26 06:07:29 NUC chip.EM[1] INFO <<< [E:2795r M:119769575 (Ack:90613342)] (S) Msg TX to 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:29 NUC chip.IN[1] INFO (S) Sending msg 119769575 on secure session with LSID: 64986
2023-04-26 06:07:29 NUC chip.EM[1] INFO >>> [E:2795r M:90613345 (Ack:119769574)] (S) Msg RX from 1:0000000000000019 [C0BF] --- Type 0000:10 (SecureChannel:StandaloneAck)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Found matching exchange: 2795r, Delegate: (nil)
2023-04-26 06:07:29 NUC chip.EM[1] DEBUG Rxd Ack; Removing MessageCounter:119769574 from Retrans Table on exchange 2795r
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment