D: 351 [IN]TransportMgr initialized
D: 358 [DL]Using Thread extended MAC for hostname.
I: 365 [ZCL]Using ZAP configuration...
I: 375 [DMG]AccessControlCluster: initializing
I: 379 [ZCL]Initiating Admin Commissioning cluster.
D: 384 [DL]Boot reason: 1
E: 386 [ZCL]DeviceInfoProvider is not registered
E: 390 [ZCL]Failed to read calendar type with error: 0x86
I: emberAfWindowCoveringClusterInitCallback, endpoint:1
I: WindowCoveringManager::Init, endpoint:1
I: 405 [DL]OpenThread SED interval is 200ms
D: 409 [IN]SecureSession[0x20003a88]: Allocated Type:1 LSID:22447
D: 415 [DL]CHIPoBLE advertising set to on
I: 418 [DIS]Updating services using commissioning mode 1
D: 423 [DL]Using Thread extended MAC for hostname.
I: 428 [DIS]Advertise commission parameter vendorID=4945 productID=65531 discriminator=3111/12 cm=1
E: 437 [DIS]Failed to advertise commissionable node: 3
E: 442 [DIS]Failed to finalize service update: 1c
I: 446 [DIS]Updating services using commissioning mode 1
D: 451 [DL]Using Thread extended MAC for hostname.
I: 456 [DIS]Advertise commission parameter vendorID=4945 productID=65531 discriminator=3111/12 cm=1
E: 465 [DIS]Failed to advertise commissionable node: 3
E: 470 [DIS]Failed to finalize service update: 1c
I: 474 [DIS]Delaying proxy of operational discovery: missing delegate
I: 480 [IN]CASE Server enabling CASE session setups
D: 485 [IN]SecureSession[0x20003b40]: Allocated Type:2 LSID:22448
I: 491 [SVR]Joining Multicast groups
I: 494 [SVR]Server Listening...
I: 497 [DL]Device Configuration:
I: 501 [DL]  Serial Number: (not set)
I: 504 [DL]  Vendor Id: 4945 (0x1351)
I: 507 [DL]  Product Id: 65531 (0xFFFB)
I: 511 [DL]  Product Name: HooRii Window Covering
I: 515 [DL]  Hardware Version: 1
I: 518 [DL]  Setup Pin Code (0 for UNKNOWN/ERROR): 46928638
I: 524 [DL]  Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3111 (0xC27)
I: 530 [DL]  Manufacturing Date: 2024-01-10
I: 534 [DL]  Device Type: 65535 (0xFFFF)
I: 538 [SVR]SetupQRCode: [MT:ILQU6J0X17A7E71Q.00]
I: 542 [SVR]Copy/paste the below URL in a browser to see the QR Code:
I: 548 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3AILQU6J0X17A7E71Q.00
I: 557 [SVR]Manual pairing code: [30486228645]
D: 561 [DL]Device name set to: WindowCovering
I: CommissioningWindow is OPEN
I: 568 [SVR]Closing pairing window
D: 572 [IN]SecureSession[0x20003a88]: Released - Type:1 LSID:22447
I: 577 [DIS]Updating services using commissioning mode 0
D: 583 [DL]Using Thread extended MAC for hostname.
I: 587 [DIS]Advertise commission parameter vendorID=4945 productID=65531 discriminator=3111/12 cm=0
E: 596 [DIS]Failed to advertise extended commissionable node: 3
D: 602 [DIS]Scheduling extended discovery timeout in 900s
E: 607 [DIS]Failed to finalize service update: 1c
D: 611 [DL]CHIPoBLE advertising set to off
I: 615 [DL]OpenThread SED interval is 2000ms
I: CLOSE CommissioningWindow
I: weak func hoorii_matter_app_init
I: endpoint:1 supported identify!
I: matter init ret:0
I: watch dog init complete

I: cnt:0 , feed
I: cnt:1 , feed
I: at server recv:AT+MT_START

I: 3692 [DL]OpenThread SED interval is 200ms
D: 3697 [IN]SecureSession[0x20003a88]: Allocated Type:1 LSID:22449
D: 3702 [DL]CHIPoBLE advertising set to on
I: 3706 [DIS]Updating services using commissioning mode 1
D: 3711 [DL]Using Thread extended MAC for hostname.
I: 3716 [DIS]Advertise commission parameter vendorID=4945 productID=65531 discriminator=3111/12 cm=1
E: 3725 [DIS]Failed to advertise commissionable node: 3
E: 3730 [DIS]Failed to finalize service update: 1c
I: 3735 [DL]CHIP task running
I: 3739 [DL]CHIPoBLE advertising started
I: 3743 [DIS]Updating services using commissioning mode 1
D: 3748 [DL]Using Thread extended MAC for hostname.
I: 3753 [DIS]Advertise commission parameter vendorID=4945 productID=65531 discriminator=3111/12 cm=1
E: 3762 [DIS]Failed to advertise commissionable node: 3
E: 3767 [DIS]Failed to finalize service update: 1c
I: kCHIPoBLEAdvertisingChange

I: RESPOND:+MT_START:OK

I: REQUIRE_CREDENTIAL

I: COMMISSION_OPENING

I: cnt:2 , feed
I: cnt:3 , feed
I: 11367 [DL]BLE connection established (ConnId: 0x00)
I: 11372 [DL]Current number of connections: 1/1
I: 11377 [DL]CHIPoBLE advertising stopped
I: kCHIPoBLEAdvertisingChange

W: Ignoring data for unknown channel ID 0x003a
D: 12509 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 12516 [BLE]local and remote recv window sizes = 5
I: 12521 [BLE]selected BTP version 4
I: 12524 [BLE]using BTP fragment sizes rx 244 / tx 244.
D: 12629 [DL]ConnId: 0x00, New CCCD value: 0x0002
D: 12634 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6)
D: 12641 [IN]BLE EndPoint 0x2000f7c8 Connection Complete
I: 12646 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 247)
I: cnt:4 , feed
D: 12720 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 12727 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 12735 [EM]>>> [E:64330r S:0 M:93597794] (U) Msg RX from 0:285105DF6035A5C1 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I: 12748 [EM]<<< [E:64330r S:0 M:24782504] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I: 12759 [IN](U) Sending msg 24782504 to IP address 'BLE'
D: 12765 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 156)
D: 12899 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 12907 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 12915 [EM]>>> [E:64330r S:0 M:93597795] (U) Msg RX from 0:285105DF6035A5C1 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I: 14832 [EM]<<< [E:64330r S:0 M:24782505] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I: 14843 [IN](U) Sending msg 24782505 to IP address 'BLE'
D: 14848 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 132)
E: 14856 [DL]Long dispatch time: 1949 ms, for event type 16388
D: 14940 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 14947 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 14955 [EM]>>> [E:64330r S:0 M:93597796] (U) Msg RX from 0:285105DF6035A5C1 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I: 14967 [EM]<<< [E:64330r S:0 M:24782506] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 14978 [IN](U) Sending msg 24782506 to IP address 'BLE'
D: 14983 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 35)
I: 14992 [SC]SecureSession[0x20003a88]: Moving from state 'kEstablishing' --> 'kActive'
D: 15000 [IN]SecureSession[0x20003a88]: Activated - Type:1 LSID:22449
D: 15006 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:22449 PSID:38955!
I: 15015 [SVR]Commissioning completed session establishment step
I: 15021 [DIS]Updating services using commissioning mode 0
D: 15026 [DL]Using Thread extended MAC for hostname.
I: 15031 [DIS]Advertise commission parameter vendorID=4945 productID=65531 discriminator=3111/12 cm=0
E: 15040 [DIS]Failed to advertise extended commissionable node: 3
D: 15046 [DIS]Scheduling extended discovery timeout in 900s
E: 15051 [DIS]Failed to finalize service update: 1c
D: 15055 [DL]CHIPoBLE advertising set to off
I: 15061 [SVR]Device completed Rendezvous process
E: 15065 [DL]Long dispatch time: 118 ms, for event type 16388
D: 15070 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 15078 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 15086 [EM]>>> [E:64331r S:22449 M:64206864] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 15097 [IM]Received Read request
I: 15102 [EM]<<< [E:64331r S:22449 M:181697616] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 15112 [IN](S) Sending msg 181697616 on secure session with LSID: 22449
D: 15119 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 99)
D: 15240 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 15247 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 15255 [EM]>>> [E:64332r S:22449 M:64206865] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 15266 [IM]Received Read request
I: 15273 [EM]<<< [E:64332r S:22449 M:181697617] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 15284 [IN](S) Sending msg 181697617 on secure session with LSID: 22449
D: 15290 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 15359 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 15367 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 15479 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 15487 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 7)
D: 15600 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 15607 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 15615 [EM]>>> [E:64333r S:22449 M:64206866] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 15626 [IM]Received Read request
I: 15631 [EM]<<< [E:64333r S:22449 M:181697618] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 15641 [IN](S) Sending msg 181697618 on secure session with LSID: 22449
D: 15648 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
I: cnt:5 , feed
D: 15720 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 15727 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 15735 [EM]>>> [E:64334r S:22449 M:64206867] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 15746 [IM]Received Read request
I: 15751 [EM]<<< [E:64334r S:22449 M:181697619] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 15761 [IN](S) Sending msg 181697619 on secure session with LSID: 22449
D: 15768 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 15900 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 15907 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 15915 [EM]>>> [E:64335r S:22449 M:64206868] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 15926 [IM]Received Read request
I: 15931 [EM]<<< [E:64335r S:22449 M:181697620] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 15941 [IN](S) Sending msg 181697620 on secure session with LSID: 22449
D: 15948 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 16079 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 16087 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 16095 [EM]>>> [E:64336r S:22449 M:64206869] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 16106 [IM]Received Read request
I: 16115 [EM]<<< [E:64336r S:22449 M:181697621] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 16126 [IN](S) Sending msg 181697621 on secure session with LSID: 22449
D: 16132 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 16319 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 16327 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 21)
D: 16500 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 16507 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 16515 [EM]>>> [E:64337r S:22449 M:64206870] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 16527 [FS]GeneralCommissioning: Received ArmFailSafe (60s)
I: 16534 [EM]<<< [E:64337r S:22449 M:181697622] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 16545 [IN](S) Sending msg 181697622 on secure session with LSID: 22449
D: 16552 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 16679 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 16687 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 16695 [EM]>>> [E:64338r S:22449 M:64206871] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 16709 [EM]<<< [E:64338r S:22449 M:181697623] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 16721 [IN](S) Sending msg 181697623 on secure session with LSID: 22449
D: 16728 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 16859 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 16867 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 16875 [EM]>>> [E:64339r S:22449 M:64206872] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 16887 [ZCL]OpCreds: Certificate Chain request received for PAI
I: 16896 [EM]<<< [E:64339r S:22449 M:181697624] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 16908 [IN](S) Sending msg 181697624 on secure session with LSID: 22449
D: 16914 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 17039 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 17047 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 17159 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 17167 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 58)
D: 17339 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 17347 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 17355 [EM]>>> [E:64340r S:22449 M:64206873] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 17367 [ZCL]OpCreds: Certificate Chain request received for DAC
I: 17376 [EM]<<< [E:64340r S:22449 M:181697625] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 17388 [IN](S) Sending msg 181697625 on secure session with LSID: 22449
D: 17394 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 17519 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 17527 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 17639 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 17647 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 94)
D: 17759 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 17767 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 17775 [EM]>>> [E:64341r S:22449 M:64206874] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 17787 [ZCL]OpCreds: Received an AttestationRequest command
I: 17824 [ZCL]OpCreds: AttestationRequest successful.
I: 17831 [EM]<<< [E:64341r S:22449 M:181697626] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 17843 [IN](S) Sending msg 181697626 on secure session with LSID: 22449
D: 17849 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 17999 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 18006 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 172)
D: 18179 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 18187 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 18195 [EM]>>> [E:64342r S:22449 M:64206875] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 18207 [FS]GeneralCommissioning: Received ArmFailSafe (60s)
I: 18214 [EM]<<< [E:64342r S:22449 M:181697627] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 18225 [IN](S) Sending msg 181697627 on secure session with LSID: 22449
D: 18232 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 18359 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 18420 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 18428 [EM]>>> [E:64343r S:22449 M:64206876] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 18440 [ZCL]OpCreds: Received a CSRRequest command
I: 18634 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded
I: 18671 [ZCL]OpCreds: CSRRequest successful.
I: 18677 [EM]<<< [E:64343r S:22449 M:181697628] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 18689 [IN](S) Sending msg 181697628 on secure session with LSID: 22449
D: 18695 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
E: 18703 [DL]Long dispatch time: 284 ms, for event type 16388
I: cnt:6 , feed
D: 18779 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 18786 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 138)
D: 18899 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 18907 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 19079 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 19088 [EM]>>> [E:64344r S:22449 M:64206877] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 19100 [ZCL]OpCreds: Received an AddTrustedRootCertificate command
I: 19163 [ZCL]OpCreds: AddTrustedRootCertificate successful.
I: 19169 [EM]<<< [E:64344r S:22449 M:181697629] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 19181 [IN](S) Sending msg 181697629 on secure session with LSID: 22449
D: 19187 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 68)
E: 19195 [DL]Long dispatch time: 116 ms, for event type 16388
D: 19319 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 19327 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 19439 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 19449 [EM]>>> [E:64345r S:22449 M:64206878] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 19461 [ZCL]OpCreds: Received an AddNOC command
I: 19468 [FP]Validating NOC chain
I: 19528 [FP]NOC chain validation successful
I: 19532 [FP]Added new fabric at index: 0x1
I: 19536 [FP]Assigned compressed fabric ID: 0x89EF8A86B66B8E75, node ID: 0x00000000C1B23F0F
I: 19544 [TS]Last Known Good Time: 2024-01-09T07:05:02
I: 19549 [TS]New proposed Last Known Good Time: 2024-01-18T09:15:36
I: 19555 [TS]Updating pending Last Known Good Time to 2024-01-18T09:15:36
I: 19579 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x00000000E4176285
D: 19588 [DL]Using Thread extended MAC for hostname.
I: 19593 [DIS]Advertise operational node 89EF8A86B66B8E75-00000000C1B23F0F
I: 19600 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC
I: 19607 [EM]<<< [E:64345r S:22449 M:181697630] (S) Msg TX to 1:FFFFFFFB00000000 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 19619 [IN](S) Sending msg 181697630 on secure session with LSID: 22449
D: 19625 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
E: 19633 [DL]Long dispatch time: 194 ms, for event type 16388
D: 19739 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 19747 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 19755 [EM]>>> [E:64346r S:22449 M:64206879] (S) Msg RX from 1:FFFFFFFB00000000 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 19773 [EM]<<< [E:64346r S:22449 M:181697631] (S) Msg TX to 1:FFFFFFFB00000000 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 19784 [IN](S) Sending msg 181697631 on secure session with LSID: 22449
D: 19791 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 19919 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 19927 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 19935 [EM]>>> [E:64347r S:22449 M:64206880] (S) Msg RX from 1:FFFFFFFB00000000 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 19947 [FS]GeneralCommissioning: Received ArmFailSafe (101s)
I: 19954 [EM]<<< [E:64347r S:22449 M:181697632] (S) Msg TX to 1:FFFFFFFB00000000 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 19965 [IN](S) Sending msg 181697632 on secure session with LSID: 22449
D: 19972 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 20219 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 20227 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 20235 [EM]>>> [E:64348r S:22449 M:64206881] (S) Msg RX from 1:FFFFFFFB00000000 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
D: 20272 [DL]OpenThread State Changed (Flags: 0x1007c100)
D: 20278 [DL]   Network Name: MyHome1498887646
D: 20283 [DL]   PAN Id: 0x9649
D: 20286 [DL]   Extended PAN Id: 0x9888BD0351EF488B
D: 20292 [DL]   Channel: 25
D: 20294 [DL]   Mesh Prefix: fdde:ad00:beef::/64
D: 20301 [DL]OpenThread State Changed (Flags: 0x0100103d)
D: 20307 [DL]   Device Role: DETACHED
D: 20311 [DL]   Thread Unicast Addresses:
D: 20315 [DL]        fdde:ad00:beef::ff:fe00:9869/64 valid rloc
D: 20321 [DL]        fdde:ad00:beef:0:b697:933c:a4bc:a0df/64 valid
D: 20327 [DL]        fe80::5cc5:fd22:b7db:7744/64 valid preferred
D: 20889 [DL]OpenThread State Changed (Flags: 0x10000040)
I: cnt:7 , feed
I: 21909 [DL]SRP Client was started, detected server: fd18:4e30:752d:be29:0000:00ff:fe00:fc10
D: 21918 [DL]OpenThread State Changed (Flags: 0x301032b7)
D: 21923 [DL]   Device Role: CHILD
D: 21926 [DL]   Partition Id: 0x5FD3ABBB
D: 21930 [DL]   Thread Unicast Addresses:
D: 21934 [DL]        fd33:b4b1:659a:0:3c91:967c:9373:126a/64 valid preferred
D: 21940 [DL]        fd18:4e30:752d:be29:0:ff:fe00:9001/64 valid rloc
D: 21947 [DL]        fd18:4e30:752d:be29:b697:933c:a4bc:a0df/64 valid
D: 21953 [DL]        fe80::5cc5:fd22:b7db:7744/64 valid preferred
D: 21960 [DL]OpenThread State Changed (Flags: 0x00000001)
D: 21965 [DL]   Thread Unicast Addresses:
D: 21969 [DL]        fd33:b4b1:659a:0:3c91:967c:9373:126a/64 valid preferred
D: 21975 [DL]        fd18:4e30:752d:be29:0:ff:fe00:9001/64 valid rloc
D: 21982 [DL]        fd18:4e30:752d:be29:b697:933c:a4bc:a0df/64 valid
D: 21988 [DL]        fe80::5cc5:fd22:b7db:7744/64 valid preferred
I: 21996 [EM]<<< [E:64348r S:22449 M:181697633] (S) Msg TX to 1:FFFFFFFB00000000 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 22007 [IN](S) Sending msg 181697633 on secure session with LSID: 22449
D: 22014 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 70)
D: 22023 [DL]Using Thread extended MAC for hostname.
I: 22028 [DIS]Advertise operational node 89EF8A86B66B8E75-00000000C1B23F0F
I: 22070 [SVR]Operational advertising enabled
D: 22199 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 22270 [DL]SRP update succeeded
I: 22273 [DIS]Setting operational delegate post init
I: 22281 [SWU]Stopping the watchdog timer
I: 22285 [SWU]Starting the periodic query timer, timeout: 86400 seconds
E: 22291 [SVR]Server initialization complete
I: 22295 [DIS]Updating services using commissioning mode 0
D: 22300 [DL]Using Thread extended MAC for hostname.
I: 22305 [DIS]Advertise operational node 89EF8A86B66B8E75-00000000C1B23F0F
I: 22312 [DL]advertising srp service: 89EF8A86B66B8E75-00000000C1B23F0F._matter._tcp
D: 22320 [DL]Using Thread extended MAC for hostname.
I: 22324 [DIS]Advertise commission parameter vendorID=4945 productID=65531 discriminator=3111/12 cm=0
I: 22334 [DL]advertising srp service: 397CD89884CB99A5._matterc._udp
D: 23971 [DL]SRP update succeeded
I: 24162 [EM]>>> [E:64349r S:0 M:93597797] (U) Msg RX from 0:13969523F74BDA0D [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I: 24173 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20005390
I: 24181 [IN]CASE Server disabling CASE session setups
I: 24186 [EM]<<< [E:64349r S:0 M:24782507 (Ack:93597797)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 24198 [IN](U) Sending msg 24782507 to IP address 'UDP:[fd62:12bb:6fb8:6000:18ba:26d3:36ba:9239]:57794'
I: 24209 [SC]Received Sigma1 msg
I: 24216 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x00000000C1B23F0F
I: 24442 [EM]<<< [E:64349r S:0 M:24782508 (Ack:93597797)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I: 24455 [IN](U) Sending msg 24782508 to IP address 'UDP:[fd62:12bb:6fb8:6000:18ba:26d3:36ba:9239]:57794'
I: 24466 [SC]Sent Sigma2 msg
D: 24598 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: cnt:8 , feed
I: 24826 [EM]>>> [E:64349r S:0 M:93597798 (Ack:24782508)] (U) Msg RX from 0:13969523F74BDA0D [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I: 24839 [EM]<<< [E:64349r S:0 M:24782509 (Ack:93597798)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 24852 [IN](U) Sending msg 24782509 to IP address 'UDP:[fd62:12bb:6fb8:6000:18ba:26d3:36ba:9239]:57794'
I: 24862 [SC]Received Sigma3 msg
E: 24869 [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
E: 24992 [DL]Long dispatch time: 113 ms, for event type 2
I: 25028 [EM]<<< [E:64349r S:0 M:24782510 (Ack:93597798)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 25040 [IN](U) Sending msg 24782510 to IP address 'UDP:[fd62:12bb:6fb8:6000:18ba:26d3:36ba:9239]:57794'
I: 25052 [SC]SecureSession[0x20003b40]: Moving from state 'kEstablishing' --> 'kActive'
D: 25061 [IN]SecureSession[0x20003b40]: Activated - Type:2 LSID:22448
D: 25067 [IN]New secure session activated for device <00000000E4176285, 1>, LSID:22448 PSID:38956!
I: 25076 [IN]CASE Session established to peer: <00000000E4176285, 1>
I: 25082 [IN]CASE Server enabling CASE session setups
D: 25087 [IN]SecureSession[0x20003bf8]: Allocated Type:2 LSID:22450
I: 25239 [EM]>>> [E:64350r S:22448 M:181402710] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 25252 [FS]GeneralCommissioning: Received CommissioningComplete
I: 25278 [FP]Metadata for Fabric 0x1 persisted to storage.
I: 25468 [TS]Committing Last Known Good Time to storage: 2024-01-18T09:15:36
I: 25479 [ZCL]OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0x89EF8A86B66B8E75, FabricId 00000000BBE78ADC, NodeId 00000000C1B23F0F, VendorId 0x1349
I: 25499 [FS]GeneralCommissioning: Successfully commited pending fabric data
I: 25506 [FS]Fail-safe cleanly disarmed
I: 25511 [EM]<<< [E:64350r S:22448 M:111330476 (Ack:181402710)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 25524 [IN](S) Sending msg 111330476 on secure session with LSID: 22448
I: 25532 [SWU]Device commissioned, schedule a default provider query
I: 25538 [SVR]Commissioning completed successfully
I: 25543 [DIS]Updating services using commissioning mode 0
D: 25549 [DL]Using Thread extended MAC for hostname.
I: 25553 [DIS]Advertise operational node 89EF8A86B66B8E75-00000000C1B23F0F
D: 25561 [DL]Using Thread extended MAC for hostname.
I: 25566 [DIS]Advertise commission parameter vendorID=4945 productID=65531 discriminator=3111/12 cm=0
D: 25575 [IN]Expiring all PASE sessions
D: 25579 [IN]SecureSession[0x20003a88]: MarkForEviction Type:1 LSID:22449
I: 25585 [SC]SecureSession[0x20003a88]: Moving from state 'kActive' --> 'kPendingEviction'
D: 25593 [IN]SecureSession[0x20003a88]: Released - Type:1 LSID:22449
D: 25599 [IN]Clearing BLE pending packets.
I: 25603 [BLE]Releasing end point's BLE connection back to application.
I: 25609 [DL]Closing BLE GATT connection (ConnId 00)
I: CONNECTED_BR

I: 25618 [EM]>>> [E:64349r S:0 M:93597799 (Ack:24782510)] (U) Msg RX from 0:13969523F74BDA0D [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 25631 [DL]BLE GAP connection terminated (reason 0x13)
I: 25637 [DL]Current number of connections: 0/1
I: set ot txpower:8
I: 25686 [EM]>>> [E:64350r S:22448 M:181402711 (Ack:111330476)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 25718 [EM]>>> [E:64351r S:22448 M:181402712] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 25729 [IM]Received Read request
I: 25734 [EM]<<< [E:64351r S:22448 M:111330477 (Ack:181402712)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 25746 [IN](S) Sending msg 111330477 on secure session with LSID: 22448
I: 25898 [EM]>>> [E:64351r S:22448 M:181402713 (Ack:111330477)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 25926 [EM]>>> [E:64352r S:22448 M:181402714] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 25936 [IM]Received Read request
I: 25941 [EM]<<< [E:64352r S:22448 M:111330478 (Ack:181402714)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 25953 [IN](S) Sending msg 111330478 on secure session with LSID: 22448
I: 26114 [EM]>>> [E:64352r S:22448 M:181402715 (Ack:111330478)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 26143 [EM]>>> [E:64353r S:22448 M:181402716] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 26154 [IM]Received Read request
I: 26159 [EM]<<< [E:64353r S:22448 M:111330479 (Ack:181402716)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 26171 [IN](S) Sending msg 111330479 on secure session with LSID: 22448
I: 26333 [EM]>>> [E:64353r S:22448 M:181402717 (Ack:111330479)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 26350 [EM]>>> [E:64354r S:22448 M:181402718] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 26361 [IM]Received Read request
I: 26366 [EM]<<< [E:64354r S:22448 M:111330480 (Ack:181402718)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 26378 [IN](S) Sending msg 111330480 on secure session with LSID: 22448
I: 26546 [EM]>>> [E:64354r S:22448 M:181402719 (Ack:111330480)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 26577 [EM]>>> [E:64355r S:22448 M:181402720] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 26588 [IM]Received Read request
I: 26593 [EM]<<< [E:64355r S:22448 M:111330481 (Ack:181402720)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 26605 [IN](S) Sending msg 111330481 on secure session with LSID: 22448
I: 26760 [EM]>>> [E:64355r S:22448 M:181402721 (Ack:111330481)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 26802 [EM]>>> [E:64356r S:22448 M:181402722] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 26813 [IM]Received Read request
I: 26819 [EM]<<< [E:64356r S:22448 M:111330482 (Ack:181402722)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 26831 [IN](S) Sending msg 111330482 on secure session with LSID: 22448
I: 26997 [EM]>>> [E:64356r S:22448 M:181402723 (Ack:111330482)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 27024 [EM]>>> [E:64357r S:22448 M:181402724] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 27035 [IM]Received Read request
I: 27040 [EM]<<< [E:64357r S:22448 M:111330483 (Ack:181402724)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 27052 [IN](S) Sending msg 111330483 on secure session with LSID: 22448
I: 27211 [EM]>>> [E:64357r S:22448 M:181402725 (Ack:111330483)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 27244 [EM]>>> [E:64358r S:22448 M:181402726] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 27255 [IM]Received Read request
I: 27260 [EM]<<< [E:64358r S:22448 M:111330484 (Ack:181402726)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 27272 [IN](S) Sending msg 111330484 on secure session with LSID: 22448
I: 27442 [EM]>>> [E:64358r S:22448 M:181402727 (Ack:111330484)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 27470 [EM]>>> [E:64359r S:22448 M:181402728] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 27481 [IM]Received Read request
I: 27486 [EM]<<< [E:64359r S:22448 M:111330485 (Ack:181402728)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 27498 [IN](S) Sending msg 111330485 on secure session with LSID: 22448
I: cnt:9 , feed
I: 28255 [EM]>>> [E:64359r S:22448 M:181402729 (Ack:111330485)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 28272 [EM]>>> [E:64360r S:22448 M:181402730] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 28283 [IM]Received Read request
I: 28288 [EM]<<< [E:64360r S:22448 M:111330486 (Ack:181402730)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 28300 [IN](S) Sending msg 111330486 on secure session with LSID: 22448
I: 28671 [EM]>>> [E:64360r S:22448 M:181402731 (Ack:111330486)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 28688 [EM]>>> [E:64361r S:22448 M:181402732] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 28699 [IM]Received Read request
I: 28707 [EM]<<< [E:64361r S:22448 M:111330487 (Ack:181402732)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 28719 [IN](S) Sending msg 111330487 on secure session with LSID: 22448
I: 29285 [EM]>>> [E:64361r S:22448 M:181402733 (Ack:111330487)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 29302 [EM]>>> [E:64362r S:22448 M:181402734] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 29313 [IM]Received Read request
I: 29321 [EM]<<< [E:64362r S:22448 M:111330488 (Ack:181402734)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 29333 [IN](S) Sending msg 111330488 on secure session with LSID: 22448
I: 29905 [EM]>>> [E:64362r S:22448 M:181402735 (Ack:111330488)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 29922 [EM]>>> [E:64363r S:22448 M:181402736] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 29933 [IM]Received Read request
I: 29940 [EM]<<< [E:64363r S:22448 M:111330489 (Ack:181402736)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 29952 [IN](S) Sending msg 111330489 on secure session with LSID: 22448
I: 30319 [EM]>>> [E:64363r S:22448 M:181402737 (Ack:111330489)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 30356 [EM]>>> [E:64364r S:22448 M:181402738] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 30367 [IM]Received Read request
I: 30372 [EM]<<< [E:64364r S:22448 M:111330490 (Ack:181402738)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 30384 [IN](S) Sending msg 111330490 on secure session with LSID: 22448
I: 30534 [EM]>>> [E:64364r S:22448 M:181402739 (Ack:111330490)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 30563 [EM]>>> [E:64365r S:22448 M:181402740] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 30576 [IM]Received Read request
I: 30584 [EM]<<< [E:64365r S:22448 M:111330491 (Ack:181402740)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 30596 [IN](S) Sending msg 111330491 on secure session with LSID: 22448
I: 30605 [EM]>>> [E:64366r S:22448 M:181402741] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 30616 [IM]Received Read request
I: 30624 [EM]<<< [E:64366r S:22448 M:111330492 (Ack:181402741)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 30638 [IN](S) Sending msg 111330492 on secure session with LSID: 22448
I: cnt:10 , feed
I: 30977 [EM]>>> [E:64365r S:22448 M:181402742 (Ack:111330491)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 31005 [EM]>>> [E:64366r S:22448 M:181402743 (Ack:111330492)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 31033 [EM]>>> [E:64367r S:22448 M:181402744] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 31044 [IM]Received Read request
I: 31050 [EM]<<< [E:64367r S:22448 M:111330493 (Ack:181402744)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 31061 [IN](S) Sending msg 111330493 on secure session with LSID: 22448
I: 31618 [EM]>>> [E:64367r S:22448 M:181402745 (Ack:111330493)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 31647 [EM]>>> [E:64368r S:22448 M:181402746] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 31659 [FS]GeneralCommissioning: Received ArmFailSafe (30s)
I: 31665 [EM]<<< [E:64368r S:22448 M:111330494 (Ack:181402746)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 31678 [IN](S) Sending msg 111330494 on secure session with LSID: 22448
I: 32034 [EM]>>> [E:64368r S:22448 M:181402747 (Ack:111330494)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 32184 [EM]>>> [E:64369r S:22448 M:181402748] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 32196 [ZCL]OpCreds: Received a CSRRequest command
I: 32202 [EM]<<< [E:64369r S:22448 M:111330495 (Ack:181402748)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 32215 [IN](S) Sending msg 111330495 on secure session with LSID: 22448
I: 32414 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded
I: 32451 [ZCL]OpCreds: CSRRequest successful.
I: 32457 [EM]<<< [E:64369r S:22448 M:111330496 (Ack:181402748)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 32470 [IN](S) Sending msg 111330496 on secure session with LSID: 22448
I: 32986 [EM]>>> [E:64369r S:22448 M:181402749 (Ack:111330496)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 33047 [EM]>>> [E:64370r S:22448 M:181402750] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 33059 [ZCL]OpCreds: Received an AddTrustedRootCertificate command
I: 33066 [EM]<<< [E:64370r S:22448 M:111330497 (Ack:181402750)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 33079 [IN](S) Sending msg 111330497 on secure session with LSID: 22448
I: 33146 [ZCL]OpCreds: AddTrustedRootCertificate successful.
I: 33152 [EM]<<< [E:64370r S:22448 M:111330498 (Ack:181402750)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 33165 [IN](S) Sending msg 111330498 on secure session with LSID: 22448
I: 33648 [EM]>>> [E:64370r S:22448 M:181402751 (Ack:111330498)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: cnt:11 , feed
I: 33766 [EM]>>> [E:64371r S:22448 M:181402752] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 33778 [ZCL]OpCreds: Received an AddNOC command
I: 33783 [EM]<<< [E:64371r S:22448 M:111330499 (Ack:181402752)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 33797 [IN](S) Sending msg 111330499 on secure session with LSID: 22448
I: 33808 [FP]Validating NOC chain
I: 33869 [FP]NOC chain validation successful
I: 33873 [FP]Added new fabric at index: 0x2
I: 33877 [FP]Assigned compressed fabric ID: 0x1DBAE84B9EF77C11, node ID: 0x000000001D28240F
I: 33885 [TS]Last Known Good Time: 2024-01-18T09:15:36
I: 33890 [TS]New proposed Last Known Good Time: 2024-01-18T09:15:50
I: 33896 [TS]Updating pending Last Known Good Time to 2024-01-18T09:15:50
I: 33923 [ZCL]OpCreds: ACL entry created for Fabric index 0x2 CASE Admin Subject 0x000000009E550284
D: 33931 [DL]Using Thread extended MAC for hostname.
I: 33936 [DIS]Advertise operational node 89EF8A86B66B8E75-00000000C1B23F0F
D: 33943 [DL]Using Thread extended MAC for hostname.
I: 33948 [DIS]Advertise operational node 1DBAE84B9EF77C11-000000001D28240F
I: 33955 [DL]advertising srp service: 1DBAE84B9EF77C11-000000001D28240F._matter._tcp
I: 33964 [ZCL]OpCreds: successfully created fabric index 0x2 via AddNOC
I: 33973 [EM]<<< [E:64371r S:22448 M:111330500 (Ack:181402752)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 33987 [IN](S) Sending msg 111330500 on secure session with LSID: 22448
I: 34360 [EM]>>> [E:64371r S:22448 M:181402753 (Ack:111330500)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
D: 35297 [DL]SRP update succeeded
I: 35739 [EM]>>> [E:64372r S:0 M:93597800] (U) Msg RX from 0:362E417CB5F9A337 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I: 35751 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20005390
I: 35758 [IN]CASE Server disabling CASE session setups
I: 35763 [EM]<<< [E:64372r S:0 M:24782511 (Ack:93597800)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 35776 [IN](U) Sending msg 24782511 to IP address 'UDP:[fd62:12bb:6fb8:6000:18ba:26d3:36ba:9239]:57794'
I: 35786 [SC]Received Sigma1 msg
I: 35797 [SC]CASE matched destination ID: fabricIndex 2, NodeID 0x000000001D28240F
I: 36020 [EM]<<< [E:64372r S:0 M:24782512 (Ack:93597800)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I: 36032 [IN](U) Sending msg 24782512 to IP address 'UDP:[fd62:12bb:6fb8:6000:18ba:26d3:36ba:9239]:57794'
I: 36043 [SC]Sent Sigma2 msg
I: 36405 [EM]>>> [E:64372r S:0 M:93597801 (Ack:24782512)] (U) Msg RX from 0:362E417CB5F9A337 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I: 36418 [EM]<<< [E:64372r S:0 M:24782513 (Ack:93597801)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 36431 [IN](U) Sending msg 24782513 to IP address 'UDP:[fd62:12bb:6fb8:6000:18ba:26d3:36ba:9239]:57794'
I: 36441 [SC]Received Sigma3 msg
E: 36448 [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
E: 36570 [DL]Long dispatch time: 112 ms, for event type 2
I: 36592 [EM]<<< [E:64372r S:0 M:24782514 (Ack:93597801)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 36605 [IN](U) Sending msg 24782514 to IP address 'UDP:[fd62:12bb:6fb8:6000:18ba:26d3:36ba:9239]:57794'
I: 36617 [SC]SecureSession[0x20003bf8]: Moving from state 'kEstablishing' --> 'kActive'
D: 36625 [IN]SecureSession[0x20003bf8]: Activated - Type:2 LSID:22450
D: 36632 [IN]New secure session activated for device <000000009E550284, 2>, LSID:22450 PSID:38957!
I: 36641 [IN]CASE Session established to peer: <000000009E550284, 2>
I: 36647 [IN]CASE Server enabling CASE session setups
D: 36652 [IN]SecureSession[0x20003a88]: Allocated Type:2 LSID:22451
I: cnt:12 , feed
I: 37419 [EM]>>> [E:64373r S:22450 M:55192764] (S) Msg RX from 2:000000009E550284 [7C11] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 37432 [FS]GeneralCommissioning: Received CommissioningComplete
I: 37461 [FP]Metadata for Fabric 0x2 persisted to storage.
I: 37493 [TS]Committing Last Known Good Time to storage: 2024-01-18T09:15:50
I: 37503 [ZCL]OpCreds: Fabric index 0x2 was committed to storage. Compressed Fabric Id 0x1DBAE84B9EF77C11, FabricId 00000000A38EB414, NodeId 000000001D28240F, VendorId 0x1384
I: 37520 [FS]GeneralCommissioning: Successfully commited pending fabric data
I: 37526 [FS]Fail-safe cleanly disarmed
I: 37531 [EM]<<< [E:64373r S:22450 M:7416349 (Ack:55192764)] (S) Msg TX to 2:000000009E550284 [7C11] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 37544 [IN](S) Sending msg 7416349 on secure session with LSID: 22450
I: 37552 [SWU]Device commissioned, schedule a default provider query
I: 37558 [SVR]Commissioning completed successfully
I: 37562 [DIS]Updating services using commissioning mode 0
D: 37568 [DL]Using Thread extended MAC for hostname.
I: 37573 [DIS]Advertise operational node 89EF8A86B66B8E75-00000000C1B23F0F
D: 37580 [DL]Using Thread extended MAC for hostname.
I: 37585 [DIS]Advertise operational node 1DBAE84B9EF77C11-000000001D28240F
D: 37592 [DL]Using Thread extended MAC for hostname.
I: 37597 [DIS]Advertise commission parameter vendorID=4945 productID=65531 discriminator=3111/12 cm=0
D: 37606 [IN]Expiring all PASE sessions
I: 37611 [EM]>>> [E:64372r S:0 M:93597802 (Ack:24782514)] (U) Msg RX from 0:362E417CB5F9A337 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: CONNECTED_BR

I: set ot txpower:8
I: 37686 [EM]>>> [E:64373r S:22450 M:55192765 (Ack:7416349)] (S) Msg RX from 2:000000009E550284 [7C11] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 37702 [EM]>>> [E:64374r S:22450 M:55192766] (S) Msg RX from 2:000000009E550284 [7C11] --- Type 0001:02 (IM:ReadRequest)
D: 37713 [IM]Received Read request
I: 37718 [EM]<<< [E:64374r S:22450 M:7416350 (Ack:55192766)] (S) Msg TX to 2:000000009E550284 [7C11] --- Type 0001:05 (IM:ReportData)
I: 37730 [IN](S) Sending msg 7416350 on secure session with LSID: 22450
I: 38098 [EM]>>> [E:64374r S:22450 M:55192767 (Ack:7416350)] (S) Msg RX from 2:000000009E550284 [7C11] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: cnt:13 , feed
I: 40503 [EM]>>> [E:64375r S:22448 M:181402754] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 40513 [IM]Received Read request
I: 40519 [EM]<<< [E:64375r S:22448 M:111330501 (Ack:181402754)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 40530 [IN](S) Sending msg 111330501 on secure session with LSID: 22448
I: 40906 [EM]>>> [E:64375r S:22448 M:181402755 (Ack:111330501)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 40923 [EM]>>> [E:64376r S:22448 M:181402756] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 40934 [IM]Received Read request
I: 40942 [EM]<<< [E:64376r S:22448 M:111330502 (Ack:181402756)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 40954 [IN](S) Sending msg 111330502 on secure session with LSID: 22448
I: 41321 [EM]>>> [E:64376r S:22448 M:181402757 (Ack:111330502)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 41349 [EM]>>> [E:64377r S:22448 M:181402758] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 41360 [IM]Received Read request
I: 41366 [EM]<<< [E:64377r S:22448 M:111330503 (Ack:181402758)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 41378 [IN](S) Sending msg 111330503 on secure session with LSID: 22448
I: 41935 [EM]>>> [E:64377r S:22448 M:181402759 (Ack:111330503)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: cnt:14 , feed
I: 44556 [EM]>>> [E:51893r S:0 M:209373590] (U) Msg RX from 0:C3B43384C0811179 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I: 44568 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20005390
I: 44575 [IN]CASE Server disabling CASE session setups
I: 44580 [EM]<<< [E:51893r S:0 M:24782515 (Ack:209373590)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 44593 [IN](U) Sending msg 24782515 to IP address 'UDP:[fd33:b4b1:659a:0:bdd9:3dc4:fe94:9861]:56231'
I: 44603 [SC]Received Sigma1 msg
I: 44611 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x00000000C1B23F0F
I: 44837 [EM]<<< [E:51893r S:0 M:24782516 (Ack:209373590)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I: 44849 [IN](U) Sending msg 24782516 to IP address 'UDP:[fd33:b4b1:659a:0:bdd9:3dc4:fe94:9861]:56231'
I: 44860 [SC]Sent Sigma2 msg
I: 45015 [EM]>>> [E:51893r S:0 M:209373591 (Ack:24782516)] (U) Msg RX from 0:C3B43384C0811179 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I: 45028 [EM]<<< [E:51893r S:0 M:24782517 (Ack:209373591)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 45041 [IN](U) Sending msg 24782517 to IP address 'UDP:[fd33:b4b1:659a:0:bdd9:3dc4:fe94:9861]:56231'
I: 45051 [SC]Received Sigma3 msg
E: 45059 [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
E: 45180 [DL]Long dispatch time: 112 ms, for event type 2
I: 45197 [EM]<<< [E:51893r S:0 M:24782518 (Ack:209373591)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 45210 [IN](U) Sending msg 24782518 to IP address 'UDP:[fd33:b4b1:659a:0:bdd9:3dc4:fe94:9861]:56231'
I: 45223 [SC]SecureSession[0x20003a88]: Moving from state 'kEstablishing' --> 'kActive'
D: 45231 [IN]SecureSession[0x20003a88]: Activated - Type:2 LSID:22451
D: 45238 [IN]New secure session activated for device <00000000E4176285, 1>, LSID:22451 PSID:46568!
I: 45247 [IN]CASE Session established to peer: <00000000E4176285, 1>
I: 45253 [IN]CASE Server enabling CASE session setups
D: 45259 [IN]SecureSession[0x20003cb0]: Allocated Type:2 LSID:22452
I: 45429 [EM]>>> [E:51894r S:22451 M:91673433] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 45441 [IM]Received Read request
I: 45448 [EM]<<< [E:51894r S:22451 M:116795782 (Ack:91673433)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 45460 [IN](S) Sending msg 116795782 on secure session with LSID: 22451
I: 45481 [EM]>>> [E:51895r S:22451 M:91673434] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:03 (IM:SubscribeRequest)
D: 45493 [IM]Received Subscribe request
I: *******************************
I: agreedMaxInterval:120
I: *******************************
I: 45507 [DMG]Final negotiated min/max parameters: Min = 0s, Max = 120s
E: 45518 [DMG]Error retrieving data from clusterId: 0x0000_001D, err = b
I: 45531 [EM]<<< [E:51895r S:22451 M:116795783 (Ack:91673434)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 45542 [IN](S) Sending msg 116795783 on secure session with LSID: 22451
I: 45551 [EM]>>> [E:51893r S:0 M:209373592 (Ack:24782518)] (U) Msg RX from 0:C3B43384C0811179 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 45618 [EM]>>> [E:51894r S:22451 M:91673435 (Ack:116795782)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 45669 [EM]>>> [E:51896r S:22451 M:91673436] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:06 (IM:WriteRequest)
D: 45682 [IM]Received Write request
I: cnt:15 , feed
I: 45741 [EM]<<< [E:51896r S:22451 M:116795784 (Ack:91673436)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:07 (IM:WriteResponse)
I: 45755 [IN](S) Sending msg 116795784 on secure session with LSID: 22451
I: 45804 [EM]>>> [E:51895r S:22451 M:91673437 (Ack:116795783)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 45818 [IM]Received status response, status is 0x00
E: 45828 [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b
I: 45840 [EM]<<< [E:51895r S:22451 M:116795785 (Ack:91673437)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 45851 [IN](S) Sending msg 116795785 on secure session with LSID: 22451
I: 45885 [EM]>>> [E:51897r S:22451 M:91673438] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 45896 [IM]Received Read request
I: 45902 [EM]<<< [E:51897r S:22451 M:116795786 (Ack:91673438)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 45915 [IN](S) Sending msg 116795786 on secure session with LSID: 22451
I: 45926 [EM]>>> [E:51896r S:22451 M:91673439 (Ack:116795784)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 46122 [EM]>>> [E:51895r S:22451 M:91673440 (Ack:116795785)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 46136 [IM]Received status response, status is 0x00
E: 46145 [DMG]Error retrieving data from clusterId: 0x0000_002A, err = b
I: 46158 [EM]<<< [E:51895r S:22451 M:116795787 (Ack:91673440)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 46171 [IN](S) Sending msg 116795787 on secure session with LSID: 22451
I: 46181 [EM]>>> [E:51897r S:22451 M:91673441 (Ack:116795786)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 46334 [EM]>>> [E:51895r S:22451 M:91673442 (Ack:116795787)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 46347 [IM]Received status response, status is 0x00
E: 46354 [DMG]Error retrieving data from clusterId: 0x0000_002C, err = b
I: 46366 [EM]<<< [E:51895r S:22451 M:116795788 (Ack:91673442)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 46377 [IN](S) Sending msg 116795788 on secure session with LSID: 22451
I: 46558 [EM]>>> [E:51895r S:22451 M:91673443 (Ack:116795788)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 46570 [IM]Received status response, status is 0x00
E: 46578 [DMG]Error retrieving data from clusterId: 0x0000_0030, err = b
I: 46589 [EM]<<< [E:51895r S:22451 M:116795789 (Ack:91673443)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 46601 [IN](S) Sending msg 116795789 on secure session with LSID: 22451
I: 46845 [EM]>>> [E:51895r S:22451 M:91673444 (Ack:116795789)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 46858 [IM]Received status response, status is 0x00
E: 46868 [DMG]Error retrieving data from clusterId: 0x0000_0031, err = b
I: 46879 [EM]<<< [E:51895r S:22451 M:116795790 (Ack:91673444)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 46891 [IN](S) Sending msg 116795790 on secure session with LSID: 22451
I: 47062 [EM]>>> [E:51895r S:22451 M:91673445 (Ack:116795790)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 47075 [IM]Received status response, status is 0x00
E: 47083 [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b
I: 47095 [EM]<<< [E:51895r S:22451 M:116795791 (Ack:91673445)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 47106 [IN](S) Sending msg 116795791 on secure session with LSID: 22451
I: 47245 [EM]>>> [E:51895r S:22451 M:91673446 (Ack:116795791)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 47258 [IM]Received status response, status is 0x00
E: 47269 [DL]_WriteThreadNetworkDiagnosticAttributeToTlv failed: Error CHIP:0x0000000B
E: 47276 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
I: 47288 [EM]<<< [E:51895r S:22451 M:116795792 (Ack:91673446)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 47300 [IN](S) Sending msg 116795792 on secure session with LSID: 22451
I: 47447 [EM]>>> [E:51895r S:22451 M:91673447 (Ack:116795792)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 47459 [IM]Received status response, status is 0x00
E: 47471 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b
I: 47483 [EM]<<< [E:51895r S:22451 M:116795793 (Ack:91673447)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 47495 [IN](S) Sending msg 116795793 on secure session with LSID: 22451
I: 47848 [EM]>>> [E:51895r S:22451 M:91673448 (Ack:116795793)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 47860 [IM]Received status response, status is 0x00
E: 47869 [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
I: 47883 [EM]<<< [E:51895r S:22451 M:116795794 (Ack:91673448)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 47895 [IN](S) Sending msg 116795794 on secure session with LSID: 22451
I: 48047 [EM]>>> [E:51895r S:22451 M:91673449 (Ack:116795794)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 48059 [IM]Received status response, status is 0x00
E: 48067 [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
I: 48079 [EM]<<< [E:51895r S:22451 M:116795795 (Ack:91673449)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 48090 [IN](S) Sending msg 116795795 on secure session with LSID: 22451
I: 48328 [EM]>>> [E:51895r S:22451 M:91673450 (Ack:116795795)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 48340 [IM]Received status response, status is 0x00
E: 48347 [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b
I: 48358 [EM]<<< [E:51895r S:22451 M:116795796 (Ack:91673450)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 48370 [IN](S) Sending msg 116795796 on secure session with LSID: 22451
I: 48526 [EM]>>> [E:51895r S:22451 M:91673451 (Ack:116795796)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 48538 [IM]Received status response, status is 0x00
E: 48547 [DMG]Error retrieving data from clusterId: 0x0000_0003, err = b
I: 48559 [EM]<<< [E:51895r S:22451 M:116795797 (Ack:91673451)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 48571 [IN](S) Sending msg 116795797 on secure session with LSID: 22451
I: cnt:16 , feed
I: 48806 [EM]>>> [E:51895r S:22451 M:91673452 (Ack:116795797)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 48818 [IM]Received status response, status is 0x00
E: 48825 [DMG]Error retrieving data from clusterId: 0x0000_001D, err = b
I: 48837 [EM]<<< [E:51895r S:22451 M:116795798 (Ack:91673452)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 48848 [IN](S) Sending msg 116795798 on secure session with LSID: 22451
I: 49126 [EM]>>> [E:51895r S:22451 M:91673453 (Ack:116795798)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 49138 [IM]Received status response, status is 0x00
E: 49146 [DMG]Error retrieving data from clusterId: 0x0000_002F, err = b
I: 49157 [EM]<<< [E:51895r S:22451 M:116795799 (Ack:91673453)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 49169 [IN](S) Sending msg 116795799 on secure session with LSID: 22451
I: 49328 [EM]>>> [E:51895r S:22451 M:91673454 (Ack:116795799)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 49340 [IM]Received status response, status is 0x00
I: 49346 [DL]Get the fixed label with index:0 at endpoint:1
I: 49351 [DL]Get the fixed label with index:1 at endpoint:1
I: 49357 [DL]Get the fixed label with index:2 at endpoint:1
I: 49362 [DL]Get the fixed label with index:3 at endpoint:1
E: 49368 [DMG]Error retrieving data from clusterId: 0x0000_0040, err = b
I: 49380 [EM]<<< [E:51895r S:22451 M:116795800 (Ack:91673454)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 49392 [IN](S) Sending msg 116795800 on secure session with LSID: 22451
I: 49526 [EM]>>> [E:51895r S:22451 M:91673455 (Ack:116795800)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 49538 [IM]Received status response, status is 0x00
E: 49547 [DMG]Error retrieving data from clusterId: 0x0000_0102, err = b
I: 49558 [EM]<<< [E:51895r S:22451 M:116795801 (Ack:91673455)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 49570 [IN](S) Sending msg 116795801 on secure session with LSID: 22451
I: 49726 [EM]>>> [E:51895r S:22451 M:91673456 (Ack:116795801)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 49738 [IM]Received status response, status is 0x00
I: 49747 [EM]<<< [E:51895r S:22451 M:116795802 (Ack:91673456)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 49759 [IN](S) Sending msg 116795802 on secure session with LSID: 22451
I: 49929 [EM]>>> [E:51895r S:22451 M:91673457 (Ack:116795802)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 49942 [IM]Received status response, status is 0x00
I: 49946 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
I: 49954 [EM]<<< [E:51895r S:22451 M:116795803 (Ack:91673457)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:04 (IM:SubscribeResponse)
I: 49967 [IN](S) Sending msg 116795803 on secure session with LSID: 22451
I: 49985 [EM]<<< [E:43505i S:22451 M:116795804] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 49996 [IN](S) Sending msg 116795804 on secure session with LSID: 22451
I: 50004 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
I: 50048 [EM]>>> [E:51895r S:22451 M:91673458 (Ack:116795803)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 50087 [EM]>>> [E:43505i S:22451 M:91673459 (Ack:116795804)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 50099 [IM]Received status response, status is 0x00
I: 50105 [EM]<<< [E:43505i S:22451 M:116795805 (Ack:91673459)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 50118 [IN](S) Sending msg 116795805 on secure session with LSID: 22451
I: 50895 [EM]>>> [E:51898r S:22451 M:91673460] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 50906 [IM]Received Read request
I: 50911 [EM]<<< [E:51898r S:22451 M:116795806 (Ack:91673460)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 50923 [IN](S) Sending msg 116795806 on secure session with LSID: 22451
I: 51098 [EM]>>> [E:51898r S:22451 M:91673461 (Ack:116795806)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 51148 [EM]>>> [E:51903r S:22451 M:91673462] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:06 (IM:WriteRequest)
D: 51159 [IM]Received Write request
I: 51196 [EM]<<< [E:51903r S:22451 M:116795807 (Ack:91673462)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:07 (IM:WriteResponse)
I: 51209 [IN](S) Sending msg 116795807 on secure session with LSID: 22451
I: 51225 [EM]<<< [E:43506i S:22451 M:116795808] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 51236 [IN](S) Sending msg 116795808 on secure session with LSID: 22451
I: 51243 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
I: 51291 [EM]>>> [E:51904r S:22451 M:91673463] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 51303 [ZCL]OpCreds: Received an UpdateFabricLabel command
I: 51337 [FP]Metadata for Fabric 0x1 persisted to storage.
I: 51344 [EM]<<< [E:51904r S:22451 M:116795809 (Ack:91673463)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 51357 [IN](S) Sending msg 116795809 on secure session with LSID: 22451
I: 51477 [EM]>>> [E:51903r S:22451 M:91673464 (Ack:116795807)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 51515 [EM]>>> [E:43506i S:22451 M:91673465 (Ack:116795808)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 51530 [IM]Received status response, status is 0x00
I: 51536 [EM]<<< [E:43506i S:22451 M:116795810 (Ack:91673465)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 51550 [IN](S) Sending msg 116795810 on secure session with LSID: 22451
I: 51567 [EM]<<< [E:43507i S:22451 M:116795811] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 51578 [IN](S) Sending msg 116795811 on secure session with LSID: 22451
I: 51587 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
I: 51596 [EM]>>> [E:51904r S:22451 M:91673466 (Ack:116795809)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 51611 [EM]>>> [E:51909r S:22451 M:91673467] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 51623 [IM]Received Read request
I: 51630 [EM]<<< [E:51909r S:22451 M:116795812 (Ack:91673467)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 51642 [IN](S) Sending msg 116795812 on secure session with LSID: 22451
I: 51671 [EM]>>> [E:51910r S:22451 M:91673468] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 51683 [IM]Received Read request
I: 51688 [EM]<<< [E:51910r S:22451 M:116795813 (Ack:91673468)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 51701 [IN](S) Sending msg 116795813 on secure session with LSID: 22451
I: cnt:17 , feed
I: 51755 [EM]>>> [E:43507i S:22451 M:91673469 (Ack:116795811)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 51769 [IM]Received status response, status is 0x00
I: 51774 [EM]<<< [E:43507i S:22451 M:116795814 (Ack:91673469)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 51789 [IN](S) Sending msg 116795814 on secure session with LSID: 22451
I: 51808 [EM]>>> [E:51909r S:22451 M:91673470 (Ack:116795812)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 51858 [EM]>>> [E:51910r S:22451 M:91673471 (Ack:116795813)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 51894 [EM]>>> [E:51915r S:22451 M:91673472] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 51906 [IM]Received Read request
I: 51911 [EM]<<< [E:51915r S:22451 M:116795815 (Ack:91673472)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 51925 [IN](S) Sending msg 116795815 on secure session with LSID: 22451
I: 51935 [EM]>>> [E:51916r S:22451 M:91673473] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 51946 [IM]Received Read request
I: 51951 [EM]<<< [E:51916r S:22451 M:116795816 (Ack:91673473)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 51963 [IN](S) Sending msg 116795816 on secure session with LSID: 22451
I: 52070 [EM]>>> [E:51915r S:22451 M:91673474 (Ack:116795815)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 52087 [EM]>>> [E:51916r S:22451 M:91673475 (Ack:116795816)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 52889 [EM]>>> [E:51921r S:22451 M:91673476] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 52899 [IM]Received Read request
I: 52906 [EM]<<< [E:51921r S:22451 M:116795817 (Ack:91673476)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 52918 [IN](S) Sending msg 116795817 on secure session with LSID: 22451
I: 53082 [EM]>>> [E:51921r S:22451 M:91673477 (Ack:116795817)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 53482 [EM]>>> [E:51922r S:22451 M:91673478] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 53495 [IM]Received Read request
I: 53500 [EM]<<< [E:51922r S:22451 M:116795818 (Ack:91673478)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 53512 [IN](S) Sending msg 116795818 on secure session with LSID: 22451
I: 53521 [EM]>>> [E:51923r S:22451 M:91673479] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 53533 [IM]Received Read request
I: 53538 [EM]<<< [E:51923r S:22451 M:116795819 (Ack:91673479)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 53549 [IN](S) Sending msg 116795819 on secure session with LSID: 22451
I: 53585 [EM]>>> [E:51922r S:22451 M:91673480 (Ack:116795818)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 53601 [EM]>>> [E:51923r S:22451 M:91673481 (Ack:116795819)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 53798 [EM]>>> [E:51924r S:22451 M:91673482] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 53808 [IM]Received Read request
I: 53815 [EM]<<< [E:51924r S:22451 M:116795820 (Ack:91673482)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 53827 [IN](S) Sending msg 116795820 on secure session with LSID: 22451
I: 54008 [EM]>>> [E:51924r S:22451 M:91673483 (Ack:116795820)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 54025 [EM]>>> [E:51925r S:22451 M:91673484] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 54035 [IM]Received Read request
I: 54042 [EM]<<< [E:51925r S:22451 M:116795821 (Ack:91673484)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 54053 [IN](S) Sending msg 116795821 on secure session with LSID: 22451
I: 54223 [EM]>>> [E:51925r S:22451 M:91673485 (Ack:116795821)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: cnt:18 , feed
I: 56420 [EM]>>> [E:51934r S:22451 M:91673486] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 56431 [IM]Received Read request
I: 56437 [EM]<<< [E:51934r S:22451 M:116795822 (Ack:91673486)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 56449 [IN](S) Sending msg 116795822 on secure session with LSID: 22451
I: 56849 [EM]>>> [E:51934r S:22451 M:91673487 (Ack:116795822)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 56881 [EM]>>> [E:51935r S:22451 M:91673488] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 56894 [IM]Received Read request
I: 56899 [EM]<<< [E:51935r S:22451 M:116795823 (Ack:91673488)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 56911 [IN](S) Sending msg 116795823 on secure session with LSID: 22451
I: 56919 [EM]>>> [E:51936r S:22451 M:91673489] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 56931 [IM]Received Read request
I: 56937 [EM]<<< [E:51936r S:22451 M:116795824 (Ack:91673489)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 56949 [IN](S) Sending msg 116795824 on secure session with LSID: 22451
I: 56983 [EM]>>> [E:51935r S:22451 M:91673490 (Ack:116795823)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 56999 [EM]>>> [E:51936r S:22451 M:91673491 (Ack:116795824)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 57195 [EM]>>> [E:51937r S:22451 M:91673492] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 57206 [IM]Received Read request
I: 57212 [EM]<<< [E:51937r S:22451 M:116795825 (Ack:91673492)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 57224 [IN](S) Sending msg 116795825 on secure session with LSID: 22451
I: 57394 [EM]>>> [E:51937r S:22451 M:91673493 (Ack:116795825)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 57425 [EM]>>> [E:51938r S:22451 M:91673494] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 57436 [IM]Received Read request
I: 57442 [EM]<<< [E:51938r S:22451 M:116795826 (Ack:91673494)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 57456 [IN](S) Sending msg 116795826 on secure session with LSID: 22451
I: 57465 [EM]>>> [E:51939r S:22451 M:91673495] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:02 (IM:ReadRequest)
D: 57477 [IM]Received Read request
I: 57483 [EM]<<< [E:51939r S:22451 M:116795827 (Ack:91673495)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 57494 [IN](S) Sending msg 116795827 on secure session with LSID: 22451
I: 57526 [EM]>>> [E:51938r S:22451 M:91673496 (Ack:116795826)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: cnt:19 , feed
I: 57749 [EM]>>> [E:51939r S:22451 M:91673497 (Ack:116795827)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: cnt:20 , feed
I: 61763 [DL]OpenThread SED interval is 2000ms
I: cnt:21 , feed
I: cnt:22 , feed
I: 67559 [SWU]Stopping the Periodic Query timer
I: 67563 [SWU]Starting the watchdog timer, timeout: 21600 seconds
D: 67569 [SWU]Establishing session to provider node ID 0x00000000E4176285 on fabric index 1
I: 67577 [DIS]Found an existing secure session to [1:00000000E4176285]!
D: 67583 [DIS]OperationalSessionSetup[1:00000000E4176285]: State change 1 --> 5
I: 67592 [EM]<<< [E:43508i S:22451 M:116795828] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 67604 [IN](S) Sending msg 116795828 on secure session with LSID: 22451
I: 67612 [DL]OpenThread SED interval is 200ms
I: 67627 [EM]<<< [E:43509i S:22451 M:116795829] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 67638 [IN](S) Sending msg 116795829 on secure session with LSID: 22451
I: 67646 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
I: 67676 [EM]>>> [E:43508i S:22451 M:91673498 (Ack:116795828)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 67690 [DMG]Received Command Response Data, Endpoint=0 Cluster=0x0000_0029 Command=0x0000_0001
D: 67700 [SWU]QueryImageResponse:
D: 67703 [SWU]  status: 2
I: 67705 [SWU]Stopping the watchdog timer
I: 67709 [SWU]Starting the periodic query timer, timeout: 86400 seconds
I: 67716 [EM]<<< [E:43508i S:22451 M:116795830 (Ack:91673498)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 67729 [IN](S) Sending msg 116795830 on secure session with LSID: 22451
I: 67765 [EM]>>> [E:43509i S:22451 M:91673499 (Ack:116795829)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 67777 [IM]Received status response, status is 0x00
I: 67783 [EM]<<< [E:43509i S:22451 M:116795831 (Ack:91673499)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 67796 [IN](S) Sending msg 116795831 on secure session with LSID: 22451
I: 67813 [EM]<<< [E:43510i S:22451 M:116795832] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 67823 [IN](S) Sending msg 116795832 on secure session with LSID: 22451
I: 67831 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
I: 67954 [EM]>>> [E:43510i S:22451 M:91673500 (Ack:116795832)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 67967 [IM]Received status response, status is 0x00
I: 67972 [EM]<<< [E:43510i S:22451 M:116795833 (Ack:91673500)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 67986 [IN](S) Sending msg 116795833 on secure session with LSID: 22451
I: cnt:23 , feed
I: 71973 [DL]OpenThread SED interval is 2000ms
I: cnt:24 , feed
I: cnt:25 , feed
I: cnt:26 , feed
I: cnt:27 , feed
I: cnt:28 , feed
I: cnt:29 , feed
I: cnt:30 , feed
I: cnt:31 , feed
I: 93819 [EM]>>> [E:51940r S:22451 M:91673501] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 93831 [ZCL]GoToLiftPercentage 5000 command received
I: 93837 [EM]<<< [E:51940r S:22451 M:116795834 (Ack:91673501)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 93850 [IN](S) Sending msg 116795834 on secure session with LSID: 22451
I: 93858 [DL]OpenThread SED interval is 200ms
I: 93871 [EM]<<< [E:43511i S:22451 M:116795835] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 93883 [IN](S) Sending msg 116795835 on secure session with LSID: 22451
I: 93891 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
D: user_attribute_handler

D: attribute_callack
D: object_id:1
D: attribute_id:00010007
I: WINDOW_COVERING_TARGET_POSITION_PER_100THS:5000
I: 93917 [EM]>>> [E:51940r S:22451 M:91673502 (Ack:116795834)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 94121 [EM]>>> [E:43511i S:22451 M:91673503 (Ack:116795835)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 94136 [IM]Received status response, status is 0x00
I: 94141 [EM]<<< [E:43511i S:22451 M:116795836 (Ack:91673503)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 94154 [IN](S) Sending msg 116795836 on secure session with LSID: 22451
I: 94163 [EM]>>> [E:51941r S:22451 M:91673504] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 94176 [ZCL]GoToLiftPercentage 2000 command received
I: 94183 [EM]<<< [E:51941r S:22451 M:116795837 (Ack:91673504)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 94196 [IN](S) Sending msg 116795837 on secure session with LSID: 22451
I: 94212 [EM]<<< [E:43512i S:22451 M:116795838] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 94223 [IN](S) Sending msg 116795838 on secure session with LSID: 22451
I: 94231 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
D: user_attribute_handler

D: attribute_callack
D: object_id:1
D: attribute_id:00010007
I: WINDOW_COVERING_TARGET_POSITION_PER_100THS:2000
I: 94258 [EM]>>> [E:51941r S:22451 M:91673505 (Ack:116795837)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 94275 [EM]>>> [E:43512i S:22451 M:91673506 (Ack:116795838)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 94287 [IM]Received status response, status is 0x00
I: 94293 [EM]<<< [E:43512i S:22451 M:116795839 (Ack:91673506)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 94306 [IN](S) Sending msg 116795839 on secure session with LSID: 22451
I: 95382 [EM]>>> [E:51942r S:22451 M:91673507] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 95394 [ZCL]GoToLiftPercentage 2100 command received
I: 95401 [EM]<<< [E:51942r S:22451 M:116795840 (Ack:91673507)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 95413 [IN](S) Sending msg 116795840 on secure session with LSID: 22451
I: 95430 [EM]<<< [E:43513i S:22451 M:116795841] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 95441 [IN](S) Sending msg 116795841 on secure session with LSID: 22451
I: 95448 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
D: user_attribute_handler

D: attribute_callack
D: object_id:1
D: attribute_id:00010007
I: WINDOW_COVERING_TARGET_POSITION_PER_100THS:2100
I: 95479 [EM]>>> [E:51942r S:22451 M:91673508 (Ack:116795840)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 95495 [EM]>>> [E:43513i S:22451 M:91673509 (Ack:116795841)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 95508 [IM]Received status response, status is 0x00
I: 95513 [EM]<<< [E:43513i S:22451 M:116795842 (Ack:91673509)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 95527 [IN](S) Sending msg 116795842 on secure session with LSID: 22451
I: cnt:32 , feed
I: 97294 [EM]>>> [E:51943r S:22451 M:91673510] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 97306 [ZCL]GoToLiftPercentage 4600 command received
I: 97312 [EM]<<< [E:51943r S:22451 M:116795843 (Ack:91673510)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 97325 [IN](S) Sending msg 116795843 on secure session with LSID: 22451
I: 97342 [EM]<<< [E:43514i S:22451 M:116795844] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 97352 [IN](S) Sending msg 116795844 on secure session with LSID: 22451
I: 97360 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
D: user_attribute_handler

D: attribute_callack
D: object_id:1
D: attribute_id:00010007
I: WINDOW_COVERING_TARGET_POSITION_PER_100THS:4600
I: 97406 [EM]>>> [E:51943r S:22451 M:91673511 (Ack:116795843)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 97433 [EM]>>> [E:43514i S:22451 M:91673512 (Ack:116795844)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 97446 [IM]Received status response, status is 0x00
I: 97452 [EM]<<< [E:43514i S:22451 M:116795845 (Ack:91673512)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 97465 [IN](S) Sending msg 116795845 on secure session with LSID: 22451
I: 98421 [EM]>>> [E:51944r S:22451 M:91673513] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 98433 [ZCL]GoToLiftPercentage 4500 command received
I: 98439 [EM]<<< [E:51944r S:22451 M:116795846 (Ack:91673513)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 98452 [IN](S) Sending msg 116795846 on secure session with LSID: 22451
I: 98469 [EM]<<< [E:43515i S:22451 M:116795847] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 98479 [IN](S) Sending msg 116795847 on secure session with LSID: 22451
I: 98487 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
D: user_attribute_handler

D: attribute_callack
D: object_id:1
D: attribute_id:00010007
I: WINDOW_COVERING_TARGET_POSITION_PER_100THS:4500
I: 98512 [EM]>>> [E:51944r S:22451 M:91673514 (Ack:116795846)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 98720 [EM]>>> [E:43515i S:22451 M:91673515 (Ack:116795847)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 98733 [IM]Received status response, status is 0x00
I: 98738 [EM]<<< [E:43515i S:22451 M:116795848 (Ack:91673515)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 98751 [IN](S) Sending msg 116795848 on secure session with LSID: 22451
I: 99315 [EM]>>> [E:51945r S:22451 M:91673516] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 99328 [ZCL]GoToLiftPercentage 8800 command received
I: 99334 [EM]<<< [E:51945r S:22451 M:116795849 (Ack:91673516)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 99346 [IN](S) Sending msg 116795849 on secure session with LSID: 22451
I: 99363 [EM]<<< [E:43516i S:22451 M:116795850] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0001:05 (IM:ReportData)
I: 99375 [IN](S) Sending msg 116795850 on secure session with LSID: 22451
I: 99383 [DMG]Refresh Subscribe Sync Timer with min 0 seconds and max 120 seconds
D: user_attribute_handler

D: attribute_callack
D: object_id:1
D: attribute_id:00010007
I: WINDOW_COVERING_TARGET_POSITION_PER_100THS:8800
I: 99438 [EM]>>> [E:51945r S:22451 M:91673517 (Ack:116795849)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 99455 [EM]>>> [E:43516i S:22451 M:91673518 (Ack:116795850)] (S) Msg RX from 1:00000000E4176285 [8E75] --- Type 0001:01 (IM:StatusResponse)
I: 99467 [IM]Received status response, status is 0x00
I: 99473 [EM]<<< [E:43516i S:22451 M:116795851 (Ack:91673518)] (S) Msg TX to 1:00000000E4176285 [8E75] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 99486 [IN](S) Sending msg 116795851 on secure session with LSID: 22451
I: cnt:33 , feed
I: cnt:34 , feed
I: 103472 [DL]OpenThread SED interval is 2000ms
I: cnt:35 , feed


Connection closed.

Disconnected from remote host(com 10) at 17:17:04.

Type `help' to learn how to use Xshell prompt.
[C:\~]$