I: 716633 [EM]>>> [E:18312r S:0 M:211988935] (U) Msg RX from 0:E42BC403CE05DFAB [0000] to 0000000000000000 --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196)
I: 716647 [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x2000ad00
I: 716655 [EM]<<< [E:18312r S:0 M:68006857 (Ack:211988935)] (U) Msg TX from 0000000000000000 to 0:E42BC403CE05DFAB [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 716674 [SC]Received Sigma1 msg
I: 716680 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x0000000012344321
I: 716693 [EM]<<< [E:18312r S:0 M:68006858 (Ack:211988935)] (U) Msg TX from 0000000000000000 to 0:E42BC403CE05DFAB [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:1050)
I: 716714 [EM]??1 [E:18312r S:0 M:68006858] (U) Msg Retransmission to 0:0000000000000000 scheduled for 1831ms from now [State:Active II:500 AI:300 AT:4000]
I: 716728 [SC]Sent Sigma2 msg
I: 716983 [EM]>>> [E:18312r S:0 M:211988936 (Ack:68006858)] (U) Msg RX from 0:E42BC403CE05DFAB [0000] to 0000000000000000 --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:774)
I: 716999 [EM]<<< [E:18312r S:0 M:68006859 (Ack:211988936)] (U) Msg TX from 0000000000000000 to 0:E42BC403CE05DFAB [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 717018 [SC]Received Sigma3 msg
I: 717207 [EM]<<< [E:18312r S:0 M:68006860 (Ack:211988936)] (U) Msg TX from 0000000000000000 to 0:E42BC403CE05DFAB [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
I: 717227 [EM]??1 [E:18312r S:0 M:68006860] (U) Msg Retransmission to 0:0000000000000000 scheduled for 1831ms from now [State:Active II:500 AI:300 AT:4000]
I: 717242 [SC]SecureSession[0x200086e0, LSID:54731]: State change 'kEstablishing' --> 'kActive'
D: 717250 [IN]SecureSession[0x200086e0]: Activated - Type:2 LSID:54731
D: 717257 [IN]New secure session activated for device <000000000001B669, 1>, LSID:54731 PSID:25990!
I: 717265 [IN]CASE Session established to peer: <000000000001B669, 1>
D: 717272 [IN]SecureSession[0x200087b8]: Allocated Type:2 LSID:54733
E: 717278 [DL]Long dispatch time: 249 ms, for event type 2
I: 717296 [EM]>>> [E:18312r S:0 M:211988937 (Ack:68006860)] (U) Msg RX from 0:E42BC403CE05DFAB [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 717317 [EM]>>> [E:18313r S:54731 M:4587509] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:02 (IM:ReadRequest) (B:63)
D: 717330 [IM]Received Read request
I: 717349 [EM]<<< [E:18313r S:54731 M:144494045 (Ack:4587509)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:1196)
I: 717368 [EM]??1 [E:18313r S:54731 M:144494045] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1886ms from now [State:Active II:500 AI:300 AT:4000]
I: 718099 [EM]>>> [E:18313r S:54731 M:4587510 (Ack:144494045)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:01 (IM:StatusResponse) (B:42)
I: 718114 [IM]Received status response, status is 0x00
I: 718132 [EM]<<< [E:18313r S:54731 M:144494046 (Ack:4587510)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:1208)
I: 718152 [EM]??1 [E:18313r S:54731 M:144494046] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1881ms from now [State:Active II:500 AI:300 AT:4000]
I: 718793 [EM]>>> [E:18313r S:54731 M:4587511 (Ack:144494046)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:01 (IM:StatusResponse) (B:42)
I: 718808 [IM]Received status response, status is 0x00
I: 718822 [EM]<<< [E:18313r S:54731 M:144494047 (Ack:4587511)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:1198)
I: 718842 [EM]??1 [E:18313r S:54731 M:144494047] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1851ms from now [State:Active II:500 AI:300 AT:4000]
I: 719442 [EM]>>> [E:18313r S:54731 M:4587512 (Ack:144494047)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:01 (IM:StatusResponse) (B:42)
I: 719457 [IM]Received status response, status is 0x00
I: 719470 [EM]<<< [E:18313r S:54731 M:144494048 (Ack:4587512)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:1193)
I: 719490 [EM]??1 [E:18313r S:54731 M:144494048] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1901ms from now [State:Active II:500 AI:300 AT:4000]
I: 720218 [EM]>>> [E:18313r S:54731 M:4587513 (Ack:144494048)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:01 (IM:StatusResponse) (B:42)
I: 720233 [IM]Received status response, status is 0x00
I: 720241 [EM]<<< [E:18313r S:54731 M:144494049 (Ack:4587513)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:462)
I: 720260 [EM]??1 [E:18313r S:54731 M:144494049] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1889ms from now [State:Active II:500 AI:300 AT:4000]
I: 720859 [EM]>>> [E:18313r S:54731 M:4587514 (Ack:144494049)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 720882 [EM]>>> [E:18314r S:54731 M:4587515] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 720895 [IM]Received Read request
I: 720900 [EM]<<< [E:18314r S:54731 M:144494050 (Ack:4587515)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:77)
I: 720919 [EM]??1 [E:18314r S:54731 M:144494050] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1848ms from now [State:Active II:500 AI:300 AT:4000]
I: 721005 [EM]>>> [E:18314r S:54731 M:4587516 (Ack:144494050)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 721023 [EM]>>> [E:18315r S:54731 M:4587517] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:0A (IM:TimedRequest) (B:39)
I: 721036 [EM]<<< [E:18315r S:54731 M:144494051 (Ack:4587517)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:01 (IM:StatusResponse) (B:42)
I: 721056 [EM]??1 [E:18315r S:54731 M:144494051] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1906ms from now [State:Active II:500 AI:300 AT:4000]
I: 721125 [EM]>>> [E:18315r S:54731 M:4587518 (Ack:144494051)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:08 (IM:InvokeCommandRequest) (B:66)
I: 721141 [ZCL]Received command to open basic commissioning window
D: 721147 [IN]SecureSession[0x20008890]: Allocated Type:1 LSID:54734
I: 721154 [DIS]Updating services using commissioning mode 1
D: 721159 [DL]Using Thread extended MAC for hostname.
I: 721164 [DIS]Advertise operational node C652644619EF8962-0000000012344321
D: 721170 [DL]Using Thread extended MAC for hostname.
I: 721175 [DIS]Advertise commission parameter vendorID=4992 productID=17 discriminator=2028/07 cm=1 cp=0
I: 721185 [DL]advertising srp service: CE0204F4074FF12B._matterc._udp
I: 721191 [DL]removing srp service: A8B9E1358598B091._matterc._udp
I: 721197 [ZCL]Commissioning window is now open
I: 721202 [EM]<<< [E:18315r S:54731 M:144494052 (Ack:4587518)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
I: 721221 [EM]??1 [E:18315r S:54731 M:144494052] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1908ms from now [State:Active II:500 AI:300 AT:4000]
I: 721325 [EM]>>> [E:18315r S:54731 M:4587519 (Ack:144494052)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 731969 [EM]>>> [E:18316r S:54731 M:4587520] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:06 (IM:WriteRequest) (B:61)
D: 731983 [IM]Received Write request
I: 732050 [DL]********************Attribute change on endpoint 0, clusterId: 40, attributeId: 5, type: 66, size: 33, value: 0********************
I: 732063 [EM]<<< [E:18316r S:54731 M:144494053 (Ack:4587520)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:07 (IM:WriteResponse) (B:62)
I: 732082 [EM]??1 [E:18316r S:54731 M:144494053] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1890ms from now [State:Active II:500 AI:300 AT:4000]
I: 732156 [EM]>>> [E:18316r S:54731 M:4587521 (Ack:144494053)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 732176 [EM]>>> [E:18317r S:54731 M:4587522] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 732189 [IM]Received Read request
I: 732194 [EM]<<< [E:18317r S:54731 M:144494054 (Ack:4587522)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:70)
I: 732213 [EM]??1 [E:18317r S:54731 M:144494054] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1849ms from now [State:Active II:500 AI:300 AT:4000]
I: 732292 [EM]>>> [E:18317r S:54731 M:4587523 (Ack:144494054)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 733669 [EM]>>> [E:18318r S:0 M:211988938] (U) Msg RX from 0:E3C9D144E13E019A [0000] to 0000000000000000 --- Type 0000:20 (SecureChannel:PBKDFParamRequest) (B:98)
I: 733684 [EM]<<< [E:18318r S:0 M:68006861 (Ack:211988938)] (U) Msg TX from 0000000000000000 to 0:E3C9D144E13E019A [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 733704 [EM]<<< [E:18318r S:0 M:68006862 (Ack:211988938)] (U) Msg TX from 0000000000000000 to 0:E3C9D144E13E019A [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (B:158)
I: 733725 [EM]??1 [E:18318r S:0 M:68006862] (U) Msg Retransmission to 0:0000000000000000 scheduled for 1838ms from now [State:Active II:500 AI:300 AT:4000]
I: 733740 [SVR]Commissioning session establishment step started
I: 733868 [EM]>>> [E:18318r S:0 M:211988939 (Ack:68006862)] (U) Msg RX from 0:E3C9D144E13E019A [0000] to 0000000000000000 --- Type 0000:22 (SecureChannel:PASE_Pake1) (B:96)
I: 733883 [EM]<<< [E:18318r S:0 M:68006863 (Ack:211988939)] (U) Msg TX from 0000000000000000 to 0:E3C9D144E13E019A [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 733910 [EM]<<< [E:18318r S:0 M:68006864 (Ack:211988939)] (U) Msg TX from 0000000000000000 to 0:E3C9D144E13E019A [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:23 (SecureChannel:PASE_Pake2) (B:131)
I: 733930 [EM]??1 [E:18318r S:0 M:68006864] (U) Msg Retransmission to 0:0000000000000000 scheduled for 1833ms from now [State:Active II:500 AI:300 AT:4000]
I: 734037 [EM]>>> [E:18318r S:0 M:211988940 (Ack:68006864)] (U) Msg RX from 0:E3C9D144E13E019A [0000] to 0000000000000000 --- Type 0000:24 (SecureChannel:PASE_Pake3) (B:63)
I: 734053 [EM]<<< [E:18318r S:0 M:68006865 (Ack:211988940)] (U) Msg TX from 0000000000000000 to 0:E3C9D144E13E019A [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 734073 [EM]<<< [E:18318r S:0 M:68006866 (Ack:211988940)] (U) Msg TX from 0000000000000000 to 0:E3C9D144E13E019A [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
I: 734093 [EM]??1 [E:18318r S:0 M:68006866] (U) Msg Retransmission to 0:0000000000000000 scheduled for 1904ms from now [State:Active II:500 AI:300 AT:4000]
I: 734108 [SC]SecureSession[0x20008890, LSID:54734]: State change 'kEstablishing' --> 'kActive'
D: 734117 [IN]SecureSession[0x20008890]: Activated - Type:1 LSID:54734
D: 734123 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:54734 PSID:25991!
I: 734132 [SVR]Commissioning completed session establishment step
I: 734138 [DIS]Updating services using commissioning mode 0
D: 734143 [DL]Using Thread extended MAC for hostname.
I: 734148 [DIS]Advertise operational node C652644619EF8962-0000000012344321
I: 734154 [DL]removing srp service: A8B9E1358598B091._matterc._udp
I: 734160 [DL]removing srp service: CE0204F4074FF12B._matterc._udp
I: 734166 [SVR]Device completed Rendezvous process
I: 734196 [EM]>>> [E:18319r S:54734 M:130803257] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:134)
D: 734210 [IM]Received Read request
I: 734229 [EM]<<< [E:18319r S:54734 M:98819112 (Ack:130803257)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:769)
I: 734248 [EM]??1 [E:18319r S:54734 M:98819112] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1849ms from now [State:Active II:500 AI:300 AT:4000]
I: 734267 [EM]>>> [E:18318r S:0 M:211988941 (Ack:68006866)] (U) Msg RX from 0:E3C9D144E13E019A [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 734751 [EM]>>> [E:18320r S:54734 M:130803258] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:02 (IM:ReadRequest) (B:103)
D: 734766 [IM]Received Read request
E: 734770 [DMG]Read request on unknown cluster - no data version available
E: 734777 [DMG]Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0002err = 5c3
E: 734788 [DMG]Read request on unknown cluster - no data version available
E: 734795 [DMG]Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0001err = 5c3
E: 734807 [DMG]Read request on unknown cluster - no data version available
E: 734813 [DMG]Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0000err = 5c3
E: 734825 [DMG]Read request on unknown cluster - no data version available
E: 734831 [DMG]Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0007err = 5c3
E: 734843 [DMG]Read request on unknown cluster - no data version available
E: 734850 [DMG]Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0006err = 5c3
I: 734862 [EM]<<< [E:18320r S:54734 M:98819113 (Ack:130803258)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:185)
I: 734881 [EM]??1 [E:18320r S:54734 M:98819113] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1848ms from now [State:Active II:500 AI:300 AT:4000]
I: 734900 [EM]>>> [E:18319r S:54734 M:130803259 (Ack:98819112)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 735075 [EM]>>> [E:18321r S:54734 M:130803260] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:65)
I: 735089 [FS]GeneralCommissioning: Received ArmFailSafe (60s)
I: 735096 [DL]********************Attribute change on endpoint 0, clusterId: 48, attributeId: 0, type: 39, size: 8, value: 3********************
I: 735110 [EM]<<< [E:18321r S:54734 M:98819114 (Ack:130803260)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
I: 735130 [EM]??1 [E:18321r S:54734 M:98819114] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1873ms from now [State:Active II:500 AI:300 AT:4000]
I: 735145 [EM]>>> [E:18320r S:54734 M:130803261 (Ack:98819113)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 735232 [EM]>>> [E:18322r S:54734 M:130803262] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:70)
I: 735269 [DL]********************Attribute change on endpoint 0, clusterId: 48, attributeId: 0, type: 39, size: 8, value: 4********************
I: 735283 [EM]<<< [E:18322r S:54734 M:98819115 (Ack:130803262)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
I: 735302 [EM]??1 [E:18322r S:54734 M:98819115] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1855ms from now [State:Active II:500 AI:300 AT:4000]
I: 735317 [EM]>>> [E:18321r S:54734 M:130803263 (Ack:98819114)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 735413 [EM]>>> [E:18323r S:54734 M:130803264] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:72)
I: 735431 [TS]Last Known Good Time: 2025-12-04T12:50:32
I: 735436 [TS]New proposed Last Known Good Time: 2025-12-04T12:59:14
I: 735449 [TS]Updating Last Known Good Time to 2025-12-04T12:59:14
I: 735454 [-]*** Time Synchronization: 818168354085261 ***
I: 735460 [EM]<<< [E:18323r S:54734 M:98819116 (Ack:130803264)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
I: 735480 [EM]??1 [E:18323r S:54734 M:98819116] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1867ms from now [State:Active II:500 AI:300 AT:4000]
I: 735495 [EM]>>> [E:18322r S:54734 M:130803265 (Ack:98819115)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 735578 [EM]>>> [E:18324r S:54734 M:130803266] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
I: 735594 [ZCL]OpCreds: Certificate Chain request received for PAI
I: 735601 [EM]<<< [E:18324r S:54734 M:98819117 (Ack:130803266)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:533)
I: 735621 [EM]??1 [E:18324r S:54734 M:98819117] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1891ms from now [State:Active II:500 AI:300 AT:4000]
I: 735636 [EM]>>> [E:18323r S:54734 M:130803267 (Ack:98819116)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 735757 [EM]>>> [E:18325r S:54734 M:130803268] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
I: 735772 [ZCL]OpCreds: Certificate Chain request received for DAC
I: 735780 [EM]<<< [E:18325r S:54734 M:98819118 (Ack:130803268)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:559)
I: 735800 [EM]??1 [E:18325r S:54734 M:98819118] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1908ms from now [State:Active II:500 AI:300 AT:4000]
I: 735816 [EM]>>> [E:18324r S:54734 M:130803269 (Ack:98819117)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 735947 [EM]>>> [E:18326r S:54734 M:130803270] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:94)
I: 735963 [ZCL]OpCreds: Received an AttestationRequest command
I: 735969 [EM]<<< [E:18326r S:54734 M:98819119 (Ack:130803270)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 735990 [ZCL]OpCreds: AttestationRequest successful.
I: 735996 [EM]<<< [E:18326r S:54734 M:98819120 (Ack:130803270)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:419)
I: 736016 [EM]??1 [E:18326r S:54734 M:98819120] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1858ms from now [State:Active II:500 AI:300 AT:4000]
I: 736032 [EM]>>> [E:18325r S:54734 M:130803271 (Ack:98819118)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 736295 [EM]>>> [E:18327r S:54734 M:130803272] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:94)
I: 736310 [ZCL]OpCreds: Received a CSRRequest command
I: 736317 [EM]<<< [E:18327r S:54734 M:98819121 (Ack:130803272)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 736340 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded
I: 736347 [ZCL]OpCreds: CSRRequest successful.
I: 736352 [EM]<<< [E:18327r S:54734 M:98819122 (Ack:130803272)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:397)
I: 736372 [EM]??1 [E:18327r S:54734 M:98819122] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1861ms from now [State:Active II:500 AI:300 AT:4000]
I: 736388 [EM]>>> [E:18326r S:54734 M:130803273 (Ack:98819120)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 736585 [EM]>>> [E:18328r S:54734 M:130803274] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:293)
I: 736602 [ZCL]OpCreds: Received an AddTrustedRootCertificate command
I: 736608 [EM]<<< [E:18328r S:54734 M:98819123 (Ack:130803274)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 736630 [ZCL]OpCreds: AddTrustedRootCertificate successful.
I: 736636 [EM]<<< [E:18328r S:54734 M:98819124 (Ack:130803274)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
I: 736656 [EM]??1 [E:18328r S:54734 M:98819124] (S) Msg Retransmission to 0:FFFFFFFB00000000 scheduled for 1863ms from now [State:Active II:500 AI:300 AT:4000]
I: 736671 [EM]>>> [E:18327r S:54734 M:130803275 (Ack:98819122)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 736811 [EM]>>> [E:18329r S:54734 M:130803276] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:08 (IM:InvokeCommandRequest) (B:566)
I: 736828 [ZCL]OpCreds: Received an AddNOC command
I: 736833 [EM]<<< [E:18329r S:54734 M:98819125 (Ack:130803276)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 736853 [FP]Validating NOC chain
I: 736861 [FP]NOC chain validation successful
I: 736865 [FP]Added new fabric at index: 0x2
I: 736869 [FP]Assigned compressed fabric ID: 0x8EB1D0ED236AD16A, node ID: 0x0000000012344321
I: 736877 [TS]Last Known Good Time: 2025-12-04T12:59:14
I: 736882 [TS]New proposed Last Known Good Time: 2021-01-01T00:00:00
I: 736888 [TS]Retaining current Last Known Good Time
I: 737154 [ZCL]OpCreds: ACL entry created for Fabric index 0x2 CASE Admin Subject 0x0000000000000002
D: 737163 [DL]Using Thread extended MAC for hostname.
I: 737168 [DIS]Advertise operational node C652644619EF8962-0000000012344321
D: 737174 [DL]Using Thread extended MAC for hostname.
I: 737179 [DIS]Advertise operational node 8EB1D0ED236AD16A-0000000012344321
I: 737186 [DL]advertising srp service: 8EB1D0ED236AD16A-0000000012344321._matter._tcp
I: 737194 [ZCL]OpCreds: successfully created fabric index 0x2 via AddNOC
I: 737201 [EM]<<< [E:18329r S:54734 M:98819126 (Ack:130803276)] (S) Msg TX from 0000000000000000 to 2:FFFFFFFB00000000 [D16A] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
I: 737221 [EM]??1 [E:18329r S:54734 M:98819126] (S) Msg Retransmission to 2:FFFFFFFB00000000 scheduled for 1832ms from now [State:Active II:500 AI:300 AT:4000]
I: 737236 [EM]>>> [E:18328r S:54734 M:130803277 (Ack:98819124)] (S) Msg RX from 2:FFFFFFFB00000000 [D16A] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 737337 [EM]>>> [E:18329r S:54734 M:130803278 (Ack:98819126)] (S) Msg RX from 2:FFFFFFFB00000000 [D16A] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 738571 [EM]>>> [E:18330r S:0 M:211988942] (U) Msg RX from 0:67CA104DFD9C2318 [0000] to 0000000000000000 --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196)
I: 738585 [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x2000ad00
I: 738593 [EM]<<< [E:18330r S:0 M:68006867 (Ack:211988942)] (U) Msg TX from 0000000000000000 to 0:67CA104DFD9C2318 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 738612 [SC]Received Sigma1 msg
I: 738621 [SC]CASE matched destination ID: fabricIndex 2, NodeID 0x0000000012344321
I: 738634 [EM]<<< [E:18330r S:0 M:68006868 (Ack:211988942)] (U) Msg TX from 0000000000000000 to 0:67CA104DFD9C2318 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:754)
I: 738654 [EM]??1 [E:18330r S:0 M:68006868] (U) Msg Retransmission to 0:0000000000000000 scheduled for 1890ms from now [State:Active II:500 AI:300 AT:4000]
I: 738669 [SC]Sent Sigma2 msg
I: 738883 [EM]>>> [E:18330r S:0 M:211988943 (Ack:68006868)] (U) Msg RX from 0:67CA104DFD9C2318 [0000] to 0000000000000000 --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:595)
I: 738899 [EM]<<< [E:18330r S:0 M:68006869 (Ack:211988943)] (U) Msg TX from 0000000000000000 to 0:67CA104DFD9C2318 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 738918 [SC]Received Sigma3 msg
I: 739147 [EM]<<< [E:18330r S:0 M:68006870 (Ack:211988943)] (U) Msg TX from 0000000000000000 to 0:67CA104DFD9C2318 [0000] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
I: 739167 [EM]??1 [E:18330r S:0 M:68006870] (U) Msg Retransmission to 0:0000000000000000 scheduled for 1871ms from now [State:Active II:500 AI:300 AT:4000]
I: 739182 [SC]SecureSession[0x200087b8, LSID:54733]: State change 'kEstablishing' --> 'kActive'
D: 739190 [IN]SecureSession[0x200087b8]: Activated - Type:2 LSID:54733
D: 739197 [IN]New secure session activated for device <0000000000000002, 2>, LSID:54733 PSID:25992!
I: 739205 [IN]CASE Session established to peer: <0000000000000002, 2>
D: 739212 [IN]SecureSession[0x20008968]: Allocated Type:2 LSID:54735
E: 739218 [DL]Long dispatch time: 289 ms, for event type 2
I: 739284 [EM]>>> [E:18331r S:54733 M:71305564] (S) Msg RX from 2:0000000000000002 [D16A] to 0000000012344321 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
I: 739298 [FS]GeneralCommissioning: Received CommissioningComplete
I: 739430 [FP]Metadata for Fabric 0x2 persisted to storage.
I: 739980 [TS]Committing Last Known Good Time to storage: 2025-12-04T12:59:14
I: 740026 [ZCL]OpCreds: Fabric index 0x2 was committed to storage. Compressed Fabric Id 0x8EB1D0ED236AD16A, FabricId 0000000000000002, NodeId 0000000012344321, VendorId 0xFFF1
I: 740103 [FS]GeneralCommissioning: Successfully committed pending fabric data
I: 740110 [FS]Fail-safe cleanly disarmed
I: 740114 [DL]********************Attribute change on endpoint 0, clusterId: 48, attributeId: 0, type: 39, size: 8, value: 0********************
I: 740127 [EM]<<< [E:18331r S:54733 M:122147938 (Ack:71305564)] (S) Msg TX from 0000000012344321 to 2:0000000000000002 [D16A] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
I: 740147 [EM]??1 [E:18331r S:54733 M:122147938] (S) Msg Retransmission to 2:0000000000000002 scheduled for 1859ms from now [State:Active II:500 AI:300 AT:4000]
I: 740162 [SVR]Commissioning completed successfully
I: 740166 [DIS]Updating services using commissioning mode 0
D: 740172 [DL]Using Thread extended MAC for hostname.
I: 740176 [DIS]Advertise operational node C652644619EF8962-0000000012344321
D: 740183 [DL]Using Thread extended MAC for hostname.
I: 740188 [DIS]Advertise operational node 8EB1D0ED236AD16A-0000000012344321
I: 740195 [DL]removing srp service: A8B9E1358598B091._matterc._udp
I: 740201 [DL]removing srp service: CE0204F4074FF12B._matterc._udp
D: 740207 [IN]Expiring all PASE sessions
D: 740210 [IN]SecureSession[0x20008890]: MarkForEviction Type:1 LSID:54734
I: 740217 [SC]SecureSession[0x20008890, LSID:54734]: State change 'kActive' --> 'kPendingEviction'
D: 740226 [IN]SecureSession[0x20008890]: Released - Type:1 LSID:54734
I: 740232 [SWU]Device commissioned, schedule a default provider query
I: 740238 [DL]********************Attribute change on endpoint 1, clusterId: 47, attributeId: 11, type: 35, size: 4, value: 2988********************
I: 740253 [DL]********************Attribute change on endpoint 1, clusterId: 47, attributeId: 12, type: 32, size: 1, value: 198********************
I: 740270 [EM]>>> [E:18330r S:0 M:211988944 (Ack:68006870)] (U) Msg RX from 0:67CA104DFD9C2318 [0000] to 0000000000000000 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
I: 740286 [EM]>>> [E:18331r S:54733 M:71305565 (Ack:122147938)] (S) Msg RX from 2:0000000000000002 [D16A] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 740302 [EM]>>> [E:18332r S:54731 M:4587524] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 740315 [IM]Received Read request
I: 740320 [EM]<<< [E:18332r S:54731 M:144494055 (Ack:4587524)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:159)
I: 740339 [EM]??1 [E:18332r S:54731 M:144494055] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1838ms from now [State:Active II:500 AI:300 AT:4000]
I: 740440 [EM]>>> [E:18332r S:54731 M:4587525 (Ack:144494055)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 740461 [EM]>>> [E:18333r S:54733 M:71305566] (S) Msg RX from 2:0000000000000002 [D16A] to 0000000012344321 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 740474 [IM]Received Read request
I: 740479 [EM]<<< [E:18333r S:54733 M:122147939 (Ack:71305566)] (S) Msg TX from 0000000012344321 to 2:0000000000000002 [D16A] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:159)
I: 740498 [EM]??1 [E:18333r S:54733 M:122147939] (S) Msg Retransmission to 2:0000000000000002 scheduled for 1906ms from now [State:Active II:500 AI:300 AT:4000]
I: 740595 [EM]>>> [E:18333r S:54733 M:71305567 (Ack:122147939)] (S) Msg RX from 2:0000000000000002 [D16A] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 740618 [EM]>>> [E:18334r S:54733 M:71305568] (S) Msg RX from 2:0000000000000002 [D16A] to 0000000012344321 --- Type 0001:02 (IM:ReadRequest) (B:51)
D: 740631 [IM]Received Read request
I: 740636 [EM]<<< [E:18334r S:54733 M:122147940 (Ack:71305568)] (S) Msg TX from 0000000012344321 to 2:0000000000000002 [D16A] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:05 (IM:ReportData) (B:70)
I: 740655 [EM]??1 [E:18334r S:54733 M:122147940] (S) Msg Retransmission to 2:0000000000000002 scheduled for 1834ms from now [State:Active II:500 AI:300 AT:4000]
I: 740731 [EM]>>> [E:18334r S:54733 M:71305569 (Ack:122147940)] (S) Msg RX from 2:0000000000000002 [D16A] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 740754 [EM]>>> [E:18335r S:54731 M:4587526] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0001:08 (IM:InvokeCommandRequest) (B:62)
I: 740768 [ZCL]OpCreds: Received a RemoveFabric Command for FabricIndex 0x2
I: 740775 [EM]<<< [E:18335r S:54731 M:144494056 (Ack:4587526)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
I: 741151 [FP]Fabric (0x2) deleted.
I: 741154 [ZCL]OpCreds: Fabric index 0x2 was removed
I: 741159 [DIS]Updating services using commissioning mode 0
D: 741164 [DL]Using Thread extended MAC for hostname.
I: 741169 [DIS]Advertise operational node C652644619EF8962-0000000012344321
I: 741175 [DL]removing srp service: A8B9E1358598B091._matterc._udp
I: 741181 [DL]removing srp service: CE0204F4074FF12B._matterc._udp
I: 741187 [DL]removing srp service: 8EB1D0ED236AD16A-0000000012344321._matter._tcp
I: 741623 [ZCL]OpCreds: RemoveFabric successful
D: 741628 [IN]Expiring all sessions for fabric 0x2!!
D: 741632 [IN]SecureSession[0x200087b8]: MarkForEviction Type:2 LSID:54733
I: 741639 [SC]SecureSession[0x200087b8, LSID:54733]: State change 'kActive' --> 'kPendingEviction'
D: 741648 [IN]SecureSession[0x200087b8]: Released - Type:2 LSID:54733
I: 741654 [EM]<<< [E:18335r S:54731 M:144494057 (Ack:4587526)] (S) Msg TX from 0000000012344321 to 1:000000000001B669 [8962] [UDP:[fd11:22::f18:2886:ad16:49d6]:60198] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70)
I: 741674 [EM]??1 [E:18335r S:54731 M:144494057] (S) Msg Retransmission to 1:000000000001B669 scheduled for 1911ms from now [State:Active II:500 AI:300 AT:4000]
I: 741853 [EM]>>> [E:18335r S:54731 M:4587527 (Ack:144494057)] (S) Msg RX from 1:000000000001B669 [8962] to 0000000012344321 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)


