Software Version: 1
Software Version String: 1.0.1+1



**** CC35XX Wi-Fi Init ****

Disabling Power Management (DISABLE_SLEEP)


** Wlan_Start(<StackHandler>)


 ---Wlan_Start---

cme: osi_ThreadCreate

cme_Thread: thrd is up and stopped

cme_Thread: thrd is running

Event_Thread: thrd is running

Hardware init DONE! 

Starting software download.....

received ROM init complete.....

-------------- Download Fisrt CMD

-------------- Download IniParams

-------------- Wait for IniParams complete


Memory map :: numRxMemBlks:54 numTxDescriptor:176 numTxMemBlks:114

Wlan_Start success!


** Wlan_Set(WLAN_SET_TX_CTRL) **


 ---Wlan_Set:29--- 

Wlan_Set success!


** Wlan_RoleUp(WLAN_ROLE_STA) **


 ---Wlan_RoleUp internal--- 0

CME_WlanSetMode cont ---
CME: hWlanSetMode : send to CME with role switch request, wait for completion , timeout: 429496
CME :CmeStationFlowSmValidateTransitionUserEvent: Valid state !! , New User owner request (current user ENUM(Cme_Users_e, 0) new user ENUM(Cme_Users_e, 0)), event:0 current state ENUM(Cme_STA_states_e, 0)

Init supplicant driver:ifname:ti_sta0 allocated 664 bytes for ti driver interface data


regulatoryDomain_init start

regulatoryDomain_init finish

ti_driver: state wpa_s->wpa_state=2, new_state=2, old_state=0, pDrv->roleStarted=0, CmeStationFlow_GetCurrentState()=0 roleType=0

ti_driver: state wpa_s->wpa_state=0, new_state=0, old_state=2, pDrv->roleStarted=0, CmeStationFlow_GetCurrentState()=0 roleType=0

supplicantRunSta: done

CME Cme STA Init

CME_WlanSetMode : signal role status change done, status :0


CME_WlanSetMode : Got CME role switch acknowledgments, role switch completed

Wlan_RoleUp success!


** WllanSetMode : nothing to do ,role 
 ---Wlan_Scan---

CME_ng 10 seconds for scan results

e for site survey candidate extended results ?:0

cmeSetScanCmeInfo:Scan alloc ready for scan type :ENUM(EScanRequestType, 3)

CME received CME_MESSAGE_ID_SCAN_COMPLETE FW

CME_SCAN_STATUS_COMPLETE, (roleid=0) type ENUM(EScanRequestType,3) status ENUM(cmeScanStatus_e,0)

CME_MESSAGE_ID_SCAN_COMPLETE, stopping survey scan timer

Site survey detected 18 networks

Site survey results are valid,results to send(requested) 18, starting from 0 (# NumOfResultsFound 18)

Site survey send internal async event

--> WlanStackEventHandler


--> WlanStackEventHandler Id = 3


--> WlanStackEventHandler WLAN_EVENT_SCAN_RESULT


--> WlanStackEventHandler WLAN_EVENT_SCAN_RESULT

[WLAN EVENT HANDLER] Number of scan results received: 18 

SCAN : 00 :                       HobsonHaus : 9c:05:d6:bd:88:26 :  1, 9488, -54 

SCAN : 01 :                       HobsonHaus : 92:30:66:9a:4c:0a :  1, 9488, -72 

SCAN : 02 :                                  : 94:a6:7e:5a:ca:7f : 157, 8488, -77 

SCAN : 03 :                           Briley : 70:3a:cb:c3:bf:79 :  6, 8488, -89 

SCAN : 04 :                           ORBI09 : 9a:a6:7e:5a:ca:7c :  8, 8488, -59 

SCAN : 05 :                                  : 9e:a6:7e:5c:e2:a4 :  8, 8488, -67 

SCAN : 06 :                           ORBI09 : 9a:a6:7e:5c:e2:a4 :  8, 8488, -64 

SCAN : 07 :                      TownsendDen : 80:ab:4d:b2:db:24 : 10, 9488, -68 

SCAN : 08 :                                  : aa:b5:7c:29:3e:32 : 10, 8488, -86 

SCAN : 09 :                                  : ca:3a:6b:f0:67:aa : 10, 8488, -76 

SCAN : 10 :                           ORBI09 : 94:a6:7e:5a:ca:7e : 48, 8488, -78 

SCAN : 11 :                      TownsendDen : 80:ab:4d:b2:db:28 : 52, 9488, -78 

SCAN : 12 :                       HobsonHaus : 9c:05:d6:bd:88:27 : 149, 9488, -66 

SCAN : 13 :                                  : a2:05:d6:bd:88:27 : 149, 8488, -66 

SCAN : 14 :                       HobsonHaus : 8c:30:66:9a:4c:0b : 149, 9488, -78 

SCAN : 15 :                                  : 92:30:66:9a:4c:0b : 149, 8488, -79 

SCAN : 16 :                                  : 96:30:66:9a:4c:0b : 149, 8488, -79 

SCAN : 17 :                                  : 94:a6:7e:5c:e2:a7 : 157, 8488, -82 


** Wlan_Connect(SSID/TYPE/PSWD) **


 ---Wlan_Connect---

CME:or callback for connection

alled. generalData=0x2

CME: CMEisSameConnectParams = 0

Remove all profiles from Supplicant

CME :CmeStationFlowSmValidateTransitionUserEvent: Valid state !! , New User owner request (current user ENUM(Cme_Users_e, 0) new user ENUM(Cme_Users_e, 1)), event:2 current state ENUM(Cme_STA_states_e, 1)

CME: cmeWlanConnect(); just a regular ad-hoc !! 


CME: cmeWlanConnect: Start connection
[ti_drv] Set security for HobsonHaus: Alg=1, kMgmt=502 


CME:Connecting to a new network,flushing supplicant scan result

CME:wpa_supplicant_select_network

CME:wpa_supplicant_select_network done

ti_driver: state wpa_s->wpa_state=3, new_state=3, old_state=0, pDrv->roleStarted=0, CmeStationFlow_GetCurrentState()=2 roleType=0

Scan CMD: start one shot connection scan roleId=0, RoleType=0, iface=ti_sta0

CME-SCAN Successful Allocated 952 bytes for connection scan results

cmeSetScanCmeInfo:Scan alloc ready for scan type :ENUM(EScanRequestType, 2)

ti_driver: start one shot timer

CME received CME_MESSAGE_ID_SCAN_COMPLETE FW

CME_SCAN_STATUS_COMPLETE, (roleid=0) type ENUM(EScanRequestType,2) status ENUM(cmeScanStatus_e,0)

cmeScanComplete: ScanType=2, Roletype=0

CME_MESSAGE_ID_SCAN_COMPLETE, stopping one shot scan timer

Completed ENUM(EScanRequestType, 2) # candidates 1

Added profile to supplicant conf. supp. profile id 0

Is ad-hoc profile 1, is open profile 0, unique profile ID 1, supplicant profile ID 0

ti_driver: state wpa_s->wpa_state=4, new_state=4, old_state=3, pDrv->roleStarted=0, CmeStationFlow_GetCurrentState()=2 roleType=0

Peer basicRateSetBM:0x1e remotePeerSupportedRatesBM(remote):0x1ffffe localSupportedRatesBM:0x1ffffe

drv_handleRxMngPacket : sendRxAuthEvent

.

drv_handleRxMngPacket : sendRxAuthEvent


ti_driver: state wpa_s->wpa_state=5, new_state=5, old_state=4, pDrv->roleStarted=1, CmeStationFlow_GetCurrentState()=2 roleType=0

--> WlanStackEventHandler


--> WlanStackEventHandler Id = 6


--> WlanStackEventHandler WLAN_EVENT_CONNECTING

[WLAN EVENT HANDLER] STA Connecting to the AP: , BSSID: 0:0:0:0:0:0, 


drv_handleRxMngPacket : WLAN_FC_STYPE_ASSOC_RESP

drv_handleRxMngPacket : sendRxAssocEvent


ti_driver: state wpa_s->wpa_state=6, new_state=6, old_state=5, pDrv->roleStarted=1, CmeStationFlow_GetCurrentState()=2 roleType=0

ti_driver: state wpa_s->wpa_state=7, new_state=7, old_state=6, pDrv->roleStarted=1, CmeStationFlow_GetCurrentState()=2 roleType=0

Send L2 packet of type ETHERTYPE_EAPOL dataLen:146

CME: CME_MESSAGE_ID_ASSOCIATED role ID 0

--> WlanStackEventHandler


--> WlanStackEventHandler Id = 8


--> WlanStackEventHandler WLAN_EVENT_ASSOCIATED


Send L2 packet of type ETHERTYPE_EAPOL dataLen:99

ti_driver: state wpa_s->wpa_state=8, new_state=8, old_state=7, pDrv->roleStarted=1, CmeStationFlow_GetCurrentState()=2 roleType=0

ti_driver: state wpa_s->wpa_state=9, new_state=9, old_state=8, pDrv->roleStarted=1, CmeStationFlow_GetCurrentState()=2 roleType=0

Enable BA policy for linkId:0

CME :CmeStationFlowSmValidateTransitionUserEvent: Valid state !! , New User owner request (current user ENUM(Cme_Users_e, 1) new user ENUM(Cme_Users_e, 1)), event:4 current state ENUM(Cme_STA_states_e, 2)

CME: cme_InternalEventHandler:is_first_connect=0 OR currently connected bssid is EQUAL to the previously connected one (0x26:88:bd:d6:5:9c), keep the ARP entries as is

--> WlanStackEventHandler


--> WlanStackEventHandler Id = 1


--> WlanStackEventHandler WLAN_EVENT_CONNECT

[WLAN EVENT HANDLER] STA Connected to the AP: HobsonHaus, BSSID: 9c:5:d6:bd:88:26, Channel : 1


CME Wccallback==UP starting DHCP
 IP is 0.0.0.0

lid state !! , New User owner request (current user ENUM(Cme_Users_e, 1) new user ENUM(Cme_Users_e, 1)), event:6 current state ENUM(Cme_STA_states_e, 3)

re-key: starting to use new key. New PN: 0x000000000005

.

 ---Wlan_Get:0---
status_callback==UP, local interface IP is 192.168.1.145

Recieved IP address
Start CHIPDeviceManager and Start Event Loop Task
Initiavent task runSubscription persistence not supported
Server initializing...
Initializing FabricTable from persistent storage
Last Known Good Time: 2025-10-04T15:00:28
Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x83E158656A58A498, FabricId 0x00000000792407D3, NodeId 0x000000007BB66C20, VendorId 0x1349
Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xAFEF77F268CFD7F1, FabricId 0x0000000036BB40FC, NodeId 0x000000004A5B961A, VendorId 0x1384
AccessControl: initializing
Examples::AccessControlDelegate::Init
AccessControl: setting
DefaultAclStorage: initializing
DefaultAclStorage: 3 entries loaded
UDP::Init bind&listen port=5540
UDP::Init bound to port=5540
UDP::Init bind&listen port=5540
UDP::Init bound to port=5540
TransportMgr initialized
Using ZAP configuration...
Failed to read stored attribute (0, 0x0000_0028, 0x0000_0005: a0
Failed to read stored attribute (0, 0x0000_0028, 0x0000_0010: a0
Failed to read stored attribute (1, 0x0000_0006, 0x0000_4003: a0
Failed to read stored attribute (1, 0x0000_0008, 0x0000_4000: a0
Failed to read stored attribute (1, 0x0000_0300, 0x0000_0003: a0
Failed to read stored attribute (1, 0x0000_0300, 0x0000_0004: a0
Failed to read stored attribute (1, 0x0000_0300, 0x0000_4000: a0
Failed to read stored attribute (1, 0x0000_0300, 0x0000_4002: a0
Failed to read stored attribute (1, 0x0000_0300, 0x0000_4003: a0
Failed to read stored attribute (1, 0x0000_0300, 0x0000_4004: a0
Failed to read stored attribute (1, 0x0000_0300, 0x0000_4010: a0
AccessControlCluster: initializing
Initiating Admin Commissioning cluster.
GeneralDiagnostics: OnDeviceReboot
Endpoint 0, Cluster 0x0000_0033 update version to 69836c93
LogEvent event number: 0x0000000000020000 priority: 2, endpoint id:  0x0 cluster id: 0x0000_0033 event id: 0x3 Sys timestamp: 0x0000000000000137
Endpoint 1, Cluster 0x0000_0003 update version to 12245ab1
Cluster callback: 0x0000_0003
Identify attribute ID: 0x0000_0001 Type: 48 Value: 2, length 1
Endpoint 1, Cluster 0x0000_0004 update version to ee54c182
Cluster callback: 0x0000_0004
Groups attribute ID: 0x0000_0000 Type: 24 Value: 128, length 1
Endpoint 1, Cluster 0x0000_0004 update version to ee54c183
Cluster callback: 0x0000_0004
Groups attribute ID: 0x0000_FFFC Type: 27 Value: 1, length 4
Endpoint 1 On/off already set to new value
Endpoint 1, Cluster 0x0000_0008 update version to a3d31740
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc73d
Cluster callback: 0x0000_0062
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc73e
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc73f
Endpoint 1, Cluster 0x0000_0300 update version to 3baa7f61
Cluster callback: 0x0000_0300
Endpoint 1, Cluster 0x0000_0300 update version to 3baa7f62
Cluster callback: 0x0000_0300
Endpoint 1, Cluster 0x0000_0300 update version to 3baa7f63
Cluster callback: 0x0000_0300
Fabric already commissioned. Disabling BLE advertisement
Updating services using commissioning mode 0
CHIP minimal mDNS started advertising.
Failed to get primary mac address of device. Generating a random one.
Advertise operational node 83E158656A58A498-000000007BB66C20
Responding with _matter._tcp.local
Responding with 83E158656A58A498-000000007BB66C20._matter._tcp.local
Responding with 83E158656A58A498-000000007BB66C20._matter._tcp.local
Responding with B89E36373047.local
Responding with B89E36373047.local
Responding with _I83E158656A58A498._sub._matter._tcp.local
CHIP minimal mDNS configured as 'Operational device'; instance name: 83E158656A58A498-000000007BB66C20.
mDNS service published: _matter._tcp
Advertise operational node AFEF77F268CFD7F1-000000004A5B961A
Responding with _matter._tcp.local
Responding with AFEF77F268CFD7F1-000000004A5B961A._matter._tcp.local
Responding with AFEF77F268CFD7F1-000000004A5B961A._matter._tcp.local
Responding with B89E36373047.local
Responding with B89E36373047.local
Responding with _IAFEF77F268CFD7F1._sub._matter._tcp.local
CHIP minimal mDNS configured as 'Operational device'; instance name: AFEF77F268CFD7F1-000000004A5B961A.
mDNS service published: _matter._tcp
Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 cp=0
Responding with _matterc._udp.local
Responding with 720263EC7AF0D569._matterc._udp.local
Responding with B89E36373047.local
Responding with B89E36373047.local
Responding with _V65521._sub._matterc._udp.local
Responding with _S15._sub._matterc._udp.local
Responding with _L3840._sub._matterc._udp.local
Responding with 720263EC7AF0D569._matterc._udp.local
CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 720263EC7AF0D569.
mDNS service published: _matterc._udp
Scheduling extended discovery timeout in 900s
CASE Server enabling CASE session setups
SecureSession[0x28034b60]: Allocated Type:2 LSID:21469
Allocated SecureSession (0x28034b60) - waiting for Sigma1 msg
Joining Multicast groups
Emitting StartUp event
LogEvent event number: 0x0000000000020001 priority: 2, endpoint id:  0x0 cluster id: 0x0000_0028 event id: 0x0 Sys timestamp: 0x00000000000002A4
Server initialization complete
ServertivityManagerImInitialize LEDs
Initialize buttons
On/Off ep1 value: 1
Device Configuration:
  Serial Number: TEST_SN
  Vendor Id: 65521 (0xFFF1)
  Product Id: 32773 (0x8005)
  Product Name: TEST_PRODUCT
  Hardware Version: 0
  Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
  Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
  Manufacturing Date: (not set)
  Device Type: 65535 (0xFFFF)
SetupQRCode: [MT:6FCJ1AFN00KA0648G00]
Copy/paste the below URL in a browser to see the QR Code:
https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ1AFN00KA0648G00
Manual pairing code: [34970112332]
ConnectivityManagerImpl::OnIpAcquired() : Start DNS Server
IdentiEg servmDNS started advertising.
de 0

ee times now
Advertise operational node 83E158656A58A498-000000007BB66C20
Responding with _matter._tcp.local
Responding with 83E158656A58A498-000000007BB66C20._matter._tcp.local
Responding with 83E158656A58A498-000000007BB66C20._matter._tcp.local
Responding with B89E36373047.local
Responding with B89E36373047.local
Responding with _I83E158656A58A498._sub._matter._tcp.local
CHIP minimal mDNS configured as 'Operational device'; instance name: 83E158656A58A498-000000007BB66C20.
mDNS service published: _matter._tcp
Advertise operational node AFEF77F268CFD7F1-000000004A5B961A
Responding with _matter._tcp.local
Responding with AFEF77F268CFD7F1-000000004A5B961A._matter._tcp.local
Responding with AFEF77F268CFD7F1-000000004A5B961A._matter._tcp.local
Responding with B89E36373047.local
Responding with B89E36373047.local
Responding with _IAFEF77F268CFD7F1._sub._matter._tcp.local
CHIP minimal mDNS configured as 'Operational device'; instance name: AFEF77F268CFD7F1-000000004A5B961A.
mDNS service published: _matter._tcp
Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 cp=0
Responding with _matterc._udp.local
Responding with 720263EC7AF0D569._matterc._udp.local
Responding with B89E36373047.local
Responding with B89E36373047.local
Responding with _V65521._sub._matterc._udp.local
Responding with _S15._sub._matterc._udp.local
Responding with _L3840._sub._matterc._udp.local
Responding with 720263EC7AF0D569._matterc._udp.local
CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 720263EC7AF0D569.
mDNS service published: _matterc._udp
Long dispatch time: 200 ms, for event type 32782
>>> [E:51587r S:0 M:211378488] (U) Msg RX from 0:51F47064633AD1C3 [0000] to 0000000000000000 --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:234)
Handling via exchange: 51587r, Delegate: 0x28035a88
CASE Server received Sigma1 message . Starting handshake. EC 0x28036888
<<< [E:51587r S:0 M:965474 (Ack:211378488)] (U) Msg TX from 0000000000000000 to 0:51F47064633AD1C3 [0000] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
Flushed pending ack for MessageCounter:211378488 on exchange 51587r
Received Sigma1 msg
Found MRP parameters in the message
Peer assigned session key ID 18377
<<< [E:51587r S:0 M:965475 (Ack:211378488)] (U) Msg TX from 0000000000000000 to 0:51F47064633AD1C3 [0000] [UDP:192.168.1.72:49325] --- Type 0000:33 (SecureChannel:CASE_Sigma2Resume) (B:100)
??1 [E:51587r S:0 M:965475] (U) Msg Retransmission to 0:0000000000000000 in 338ms [State:Active II:500 AI:300 AT:4000]
Sent Sigma2Resume msg
>>> [E:51587r S:0 M:211378489 (Ack:965475)] (U) Msg RX from 0:51F47064633AD1C3 [0000] to 0000000000000000 --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
Found matching exchange: 51587r, Delegate: 0x28035aa4
Rxd Ack; Removing MessageCounter:965475 from Retrans Table on exchange 51587r
Success status report received. Session was established
SecureSession[0x28034b60, LSID:21469]: State change 'kEstablishing' --> 'kActive'
SecureSession[0x28034b60]: Activated - Type:2 LSID:21469
New secure session activated for device <00000000DBB8886E, 1>, LSID:21469 PSID:18377!
CASE Session established to peer: <00000000DBB8886E, 1>
SecureSession[0x28034c40]: Allocated Type:2 LSID:21470
Allocated SecureSession (0x28034c40) - waiting for Sigma1 msg
<<< [E:51587r S:0 M:965476 (Ack:211378489)] (U) Msg TX from 0000000000000000 to 0:51F47064633AD1C3 [0000] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
Flushed pending ack for MessageCounter:211378489 on exchange 51587r
Long dispatch time: 168 ms, for event type 3
>>> [E:51588r S:21469 M:181244273] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
Handling via exchange: 51588r, Delegate: 0x2802e76c
Received command for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0000
Toggle ep1 on/off from state 1 to 0
Setting on/off to OFF due to level change
Toggle ep1 on/off from state 1 to 0
Endpoint 1, Cluster 0x0000_0006 update version to f5d3995a
Cluster callback: 0x0000_0006
*****************************************
Light Off initiated
*****************************************
Off completed. reset OnTime to  0
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc740
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc741
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc742
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc743
Command handler moving to [NewRespons]
Command handler moving to [ Preparing]
Command handler moving to [AddingComm]
Command handler moving to [AddedComma]
Decreasing reference count for CommandHandlerImpl, remaining 1
Decreasing reference count for CommandHandlerImpl, remaining 0
Command handler moving to [AwaitingDe]
<<< [E:51588r S:21469 M:239432507 (Ack:181244273)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
??1 [E:51588r S:21469 M:239432507] (S) Msg Retransmission to 1:00000000DBB8886E in 363ms [State:Active II:500 AI:300 AT:4000]
Command response sender moving to [AllInvokeR]
Long dispatch time: 188 ms, for event type 3
ConnectivityManagerImpl::_OnPlatformEvent()

Received a duplicate message with MessageCounter:181244273 on exchange 51588r
>>> [E:51588r S:21469 M:181244273] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
Found matching exchange: 51588r, Delegate: 0
Forcing tx of solitary ack for duplicate MessageCounter:181244273 on exchange 51588r
<<< [E:51588r S:21469 M:239432508 (Ack:181244273)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51588r S:21469 M:181244274 (Ack:239432507)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
Found matching exchange: 51588r, Delegate: 0
Rxd Ack; Removing MessageCounter:239432507 from Retrans Table on exchange 51588r
Endpoint 1 On/off already set to new value
*****************************************
Light Off completed
*****************************************
>>> [E:51589r S:21469 M:181244275] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:08 (IM:InvokeCommandRequest) (B:59)
Handling via exchange: 51589r, Delegate: 0x2802e76c
Received command for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0001
Toggle ep1 on/off from state 0 to 1
On Command - OffWaitTime :  0
On/Toggle Command - Stop Timer
Endpoint 1, Cluster 0x0000_0006 update version to f5d3995b
Cluster callback: 0x0000_0006
*****************************************
Light On initiated
*****************************************
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc744
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc745
Command handler moving to [NewRespons]
Command handler moving to [ Preparing]
Command handler moving to [AddingComm]
Command handler moving to [AddedComma]
Decreasing reference count for CommandHandlerImpl, remaining 1
Decreasing reference count for CommandHandlerImpl, remaining 0
Command handler moving to [AwaitingDe]
<<< [E:51589r S:21469 M:239432509 (Ack:181244275)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
??1 [E:51589r S:21469 M:239432509] (S) Msg Retransmission to 1:00000000DBB8886E in 391ms [State:Active II:500 AI:300 AT:4000]
Command response sender moving to [AllInvokeR]
Long dispatch time: 151 ms, for event type 3
>>> [E:51589r S:21469 M:181244276 (Ack:239432509)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
Found matching exchange: 51589r, Delegate: 0
Rxd Ack; Removing MessageCounter:239432509 from Retrans Table on exchange 51589r
>>> [E:51590r S:21469 M:181244277] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:08 (IM:InvokeCommandRequest) (B:70)
Handling via exchange: 51590r, Delegate: 0x2802e76c
Received command for Endpoint=1 Cluster=0x0000_0008 Command=0x0000_0004
RX level-control: MOVE_TO_LEVEL_WITH_ON_OFF fe null 0 0
Setting on/off to ON due to level change
Endpoint 1 On/off already set to new value
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc746
Endpoint 1, Cluster 0x0000_0062 update version to 6c7dc747
Command handler moving to [NewRespons]
Command handler moving to [ Preparing]
Command handler moving to [AddingComm]
Command handler moving to [AddedComma]
Decreasing reference count for CommandHandlerImpl, remaining 1
Decreasing reference count for CommandHandlerImpl, remaining 0
Command handler moving to [AwaitingDe]
<<< [E:51590r S:21469 M:239432510 (Ack:181244277)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67)
??1 [E:51590r S:21469 M:239432510] (S) Msg Retransmission to 1:00000000DBB8886E in 347ms [State:Active II:500 AI:300 AT:4000]
Command response sender moving to [AllInvokeR]
Long dispatch time: 137 ms, for event type 3
>>> [E:51590r S:21469 M:181244278 (Ack:239432510)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
Found matching exchange: 51590r, Delegate: 0
Rxd Ack; Removing MessageCounter:239432510 from Retrans Table on exchange 51590r
Event: move from 1
 to 254 
(diff +1)
Endpoint 1, Cluster 0x0000_0008 update version to a3d31741
Cluster callback: 0x0000_0008
Setting on/off to ON due to level change
Endpoint 1 On/off already set to new value
Endpoint 1 On/off already set to new value
*****************************************
Light On completed
*****************************************
>>> [E:51595r S:0 M:211378490] (U) Msg RX from 0:5FFE7C52887CA2D5 [0000] to 0000000000000000 --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:234)
Handling via exchange: 51595r, Delegate: 0x28035a88
CASE Server received Sigma1 message . Starting handshake. EC 0x28036888
<<< [E:51595r S:0 M:965477 (Ack:211378490)] (U) Msg TX from 0000000000000000 to 0:5FFE7C52887CA2D5 [0000] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
Flushed pending ack for MessageCounter:211378490 on exchange 51595r
Received Sigma1 msg
Found MRP parameters in the message
Peer assigned session key ID 18378
<<< [E:51595r S:0 M:965478 (Ack:211378490)] (U) Msg TX from 0000000000000000 to 0:5FFE7C52887CA2D5 [0000] [UDP:192.168.1.72:49325] --- Type 0000:33 (SecureChannel:CASE_Sigma2Resume) (B:100)
??1 [E:51595r S:0 M:965478] (U) Msg Retransmission to 0:0000000000000000 in 407ms [State:Active II:500 AI:300 AT:4000]
Sent Sigma2Resume msg
>>> [E:51595r S:0 M:211378491 (Ack:965478)] (U) Msg RX from 0:5FFE7C52887CA2D5 [0000] to 0000000000000000 --- Type 0000:40 (SecureChannel:StatusReport) (B:34)
Found matching exchange: 51595r, Delegate: 0x28035aa4
Rxd Ack; Removing MessageCounter:965478 from Retrans Table on exchange 51595r
Success status report received. Session was established
SecureSession[0x28034c40, LSID:21470]: State change 'kEstablishing' --> 'kActive'
SecureSession[0x28034c40]: Activated - Type:2 LSID:21470
New secure session activated for device <00000000DBB8886E, 1>, LSID:21470 PSID:18378!
CASE Session established to peer: <00000000DBB8886E, 1>
SecureSession[0x28034d20]: Allocated Type:2 LSID:21471
Allocated SecureSession (0x28034d20) - waiting for Sigma1 msg
<<< [E:51595r S:0 M:965479 (Ack:211378491)] (U) Msg TX from 0000000000000000 to 0:5FFE7C52887CA2D5 [0000] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26)
Flushed pending ack for MessageCounter:211378491 on exchange 51595r
Long dispatch time: 166 ms, for event type 3
>>> [E:51596r S:21470 M:219959727] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:03 (IM:SubscribeRequest) (B:498)
Handling via exchange: 51596r, Delegate: 0x2802e76c
Received Subscribe request
Final negotiated min/max parameters: Min = 0s, Max = 600s
IM RH moving to [CanStartReporting]
ConnectivityManagerImpl::_OnPlatformEvent()

Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 1d, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 1d, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 1d, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 1d, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 1d, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 1d, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 1d, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 1d, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 1d, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 1f, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 1f, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 1f, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 1f, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 1f, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0004 (expanded=1)
<RE:Run> Cluster 1f, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 1f, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 1f, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 1f, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 1f, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 28, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 28, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 28, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 28, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 28, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0004 (expanded=1)
<RE:Run> Cluster 28, Attribute 5 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0005 (expanded=1)
<RE:Run> Cluster 28, Attribute 6 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0006 (expanded=1)
<RE:Run> Cluster 28, Attribute 7 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0007 (expanded=1)
<RE:Run> Cluster 28, Attribute 8 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0008 (expanded=1)
<RE:Run> Cluster 28, Attribute 9 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0009 (expanded=1)
<RE:Run> Cluster 28, Attribute a is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000A (expanded=1)
<RE:Run> Cluster 28, Attribute b is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000B (expanded=1)
<RE:Run> Cluster 28, Attribute c is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000C (expanded=1)
<RE:Run> Cluster 28, Attribute d is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000D (expanded=1)
<RE:Run> Cluster 28, Attribute e is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000E (expanded=1)
<RE:Run> Cluster 28, Attribute f is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000F (expanded=1)
<RE:Run> Cluster 28, Attribute 10 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0010 (expanded=1)
Next attribute value does not fit in packet, roll back on clusterId: 0x0000_0028, attributeId: 0x0000_0010, err = b
<RE:Run> We cannot put more chunks into this report. Enable chunking.
<RE:Run> first cluster event is too big so that it fails to fit in the packet!
Fetched 0 events
<RE> Sending report (payload has 1153 bytes)...
<<< [E:51596r S:21470 M:69335160 (Ack:219959727)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:1187)
??1 [E:51596r S:21470 M:69335160] (S) Msg Retransmission to 1:00000000DBB8886E in 390ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has more messages
Long dispatch time: 528 ms, for event type 3
Received a duplicate message with MessageCounter:219959727 on exchange 51596r
>>> [E:51596r S:21470 M:219959727] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:03 (IM:SubscribeRequest) (B:498)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959727 on exchange 51596r
<<< [E:51596r S:21470 M:69335161 (Ack:219959727)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959728 (Ack:69335160)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335160 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 28, Attribute 10 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0010 (expanded=1)
<RE:Run> Cluster 28, Attribute 12 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0012 (expanded=1)
<RE:Run> Cluster 28, Attribute 13 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0013 (expanded=1)
<RE:Run> Cluster 28, Attribute 15 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0015 (expanded=1)
<RE:Run> Cluster 28, Attribute 16 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0016 (expanded=1)
<RE:Run> Cluster 28, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 28, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 28, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 28, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 28, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 2a, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 2a, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 2a, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 2a, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 2a, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 2a, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 2a, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 2a, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 2a, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 30, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 30, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 30, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 30, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 30, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0004 (expanded=1)
<RE:Run> Cluster 30, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 30, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 30, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 30, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 30, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 31, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 31, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0031, attributeId: 0x0000_0001err = 501
<RE:Run> Cluster 31, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 31, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 31, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0004 (expanded=1)
<RE:Run> Cluster 31, Attribute 5 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0005 (expanded=1)
<RE:Run> Cluster 31, Attribute 6 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0006 (expanded=1)
<RE:Run> Cluster 31, Attribute 7 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0007 (expanded=1)
<RE:Run> Cluster 31, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 31, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
Next attribute value does not fit in packet, roll back on clusterId: 0x0000_0031, attributeId: 0x0000_FFFD, err = b
<RE:Run> We cannot put more chunks into this report. Enable chunking.
<RE:Run> first cluster event is too big so that it fails to fit in the packet!
Fetched 0 events
<RE> Sending report (payload has 1164 bytes)...
<<< [E:51596r S:21470 M:69335162 (Ack:219959728)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:1198)
??1 [E:51596r S:21470 M:69335162] (S) Msg Retransmission to 1:00000000DBB8886E in 353ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has more messages
Long dispatch time: 562 ms, for event type 3
Received a duplicate message with MessageCounter:219959728 on exchange 51596r
>>> [E:51596r S:21470 M:219959728 (Ack:69335160)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959728 on exchange 51596r
<<< [E:51596r S:21470 M:69335163 (Ack:219959728)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959729 (Ack:69335162)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335162 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 31, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 31, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 31, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 31, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 32, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0032 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 32, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0032 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 32, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0032 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 32, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0032 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 32, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0032 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 33, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 33, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 33, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 33, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 33, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0004 (expanded=1)
<RE:Run> Cluster 33, Attribute 5 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0005 (expanded=1)
<RE:Run> Cluster 33, Attribute 6 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0006 (expanded=1)
<RE:Run> Cluster 33, Attribute 7 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0007 (expanded=1)
<RE:Run> Cluster 33, Attribute 8 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0008 (expanded=1)
<RE:Run> Cluster 33, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 33, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 33, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 33, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 33, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 34, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 34, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 34, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 34, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 34, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 34, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 34, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 34, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 34, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 35, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0000err = 2d
<RE:Run> Cluster 35, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0001err = 2d
<RE:Run> Cluster 35, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0002err = 2d
<RE:Run> Cluster 35, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0003err = 2d
<RE:Run> Cluster 35, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0004 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0004err = 2d
<RE:Run> Cluster 35, Attribute 5 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0005 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0005err = 2d
<RE:Run> Cluster 35, Attribute 6 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0006 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0006err = 2d
<RE:Run> Cluster 35, Attribute 7 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0007 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0007err = 2d
<RE:Run> We cannot put more chunks into this report. Enable chunking.
<RE:Run> first cluster event is too big so that it fails to fit in the packet!
Fetched 0 events
<RE> Sending report (payload has 1168 bytes)...
<<< [E:51596r S:21470 M:69335164 (Ack:219959729)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:1202)
??1 [E:51596r S:21470 M:69335164] (S) Msg Retransmission to 1:00000000DBB8886E in 376ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has more messages
Long dispatch time: 627 ms, for event type 3
Received a duplicate message with MessageCounter:219959729 on exchange 51596r
>>> [E:51596r S:21470 M:219959729 (Ack:69335162)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959729 on exchange 51596r
<<< [E:51596r S:21470 M:69335165 (Ack:219959729)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959730 (Ack:69335164)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335164 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 35, Attribute 7 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0007 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0007err = 2d
<RE:Run> Cluster 35, Attribute 8 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0008 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0008err = 2d
<RE:Run> Cluster 35, Attribute 9 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0009 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0009err = 2d
<RE:Run> Cluster 35, Attribute a is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000A (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_000Aerr = 2d
<RE:Run> Cluster 35, Attribute b is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000B (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_000Berr = 2d
<RE:Run> Cluster 35, Attribute c is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000C (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_000Cerr = 2d
<RE:Run> Cluster 35, Attribute d is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000D (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_000Derr = 2d
<RE:Run> Cluster 35, Attribute e is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000E (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_000Eerr = 2d
<RE:Run> Cluster 35, Attribute f is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000F (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_000Ferr = 2d
<RE:Run> Cluster 35, Attribute 10 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0010 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0010err = 2d
<RE:Run> Cluster 35, Attribute 11 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0011 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0011err = 2d
<RE:Run> Cluster 35, Attribute 12 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0012 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0012err = 2d
<RE:Run> Cluster 35, Attribute 13 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0013 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0013err = 2d
<RE:Run> Cluster 35, Attribute 14 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0014 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0014err = 2d
<RE:Run> Cluster 35, Attribute 15 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0015 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0015err = 2d
<RE:Run> Cluster 35, Attribute 16 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0016 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0016err = 2d
<RE:Run> Cluster 35, Attribute 17 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0017 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0017err = 2d
<RE:Run> Cluster 35, Attribute 18 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0018 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0018err = 2d
<RE:Run> Cluster 35, Attribute 19 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0019 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0019err = 2d
<RE:Run> Cluster 35, Attribute 1a is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001A (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_001Aerr = 2d
<RE:Run> Cluster 35, Attribute 1b is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001B (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_001Berr = 2d
<RE:Run> Cluster 35, Attribute 1c is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001C (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_001Cerr = 2d
<RE:Run> Cluster 35, Attribute 1d is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001D (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_001Derr = 2d
<RE:Run> Cluster 35, Attribute 1e is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001E (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_001Eerr = 2d
<RE:Run> Cluster 35, Attribute 1f is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001F (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_001Ferr = 2d
<RE:Run> Cluster 35, Attribute 20 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0020 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0020err = 2d
<RE:Run> Cluster 35, Attribute 21 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0021 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0021err = 2d
<RE:Run> Cluster 35, Attribute 22 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0022 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0022err = 2d
<RE:Run> Cluster 35, Attribute 23 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0023 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0023err = 2d
<RE:Run> Cluster 35, Attribute 24 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0024 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0024err = 2d
<RE:Run> Cluster 35, Attribute 25 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0025 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0025err = 2d
<RE:Run> Cluster 35, Attribute 26 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0026 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0026err = 2d
<RE:Run> Cluster 35, Attribute 27 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0027 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0027err = 2d
<RE:Run> Cluster 35, Attribute 28 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0028 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0028err = 2d
<RE:Run> Cluster 35, Attribute 29 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0029 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0029err = 2d
<RE:Run> Cluster 35, Attribute 2a is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002A (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_002Aerr = 2d
<RE:Run> Cluster 35, Attribute 2b is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002B (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_002Berr = 2d
<RE:Run> Cluster 35, Attribute 2c is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002C (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_002Cerr = 2d
<RE:Run> Cluster 35, Attribute 2d is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002D (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_002Derr = 2d
<RE:Run> Cluster 35, Attribute 2e is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002E (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_002Eerr = 2d
<RE:Run> Cluster 35, Attribute 2f is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002F (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_002Ferr = 2d
<RE:Run> Cluster 35, Attribute 30 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0030 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0030err = 2d
<RE:Run> Cluster 35, Attribute 31 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0031 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0031err = 2d
<RE:Run> Cluster 35, Attribute 32 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0032 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0032err = 2d
<RE:Run> Cluster 35, Attribute 33 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0033 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0033err = 2d
<RE:Run> Cluster 35, Attribute 34 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0034 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0034err = 2d
<RE:Run> Cluster 35, Attribute 35 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0035 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0035err = 2d
<RE:Run> Cluster 35, Attribute 36 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0036 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0036err = 2d
<RE:Run> Cluster 35, Attribute 37 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0037 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0037err = 2d
<RE:Run> Cluster 35, Attribute 38 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0038 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0038err = 2d
<RE:Run> Cluster 35, Attribute 39 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0039 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0039err = 2d
<RE:Run> We cannot put more chunks into this report. Enable chunking.
<RE:Run> first cluster event is too big so that it fails to fit in the packet!
Fetched 0 events
<RE> Sending report (payload has 1166 bytes)...
<<< [E:51596r S:21470 M:69335166 (Ack:219959730)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:1200)
??1 [E:51596r S:21470 M:69335166] (S) Msg Retransmission to 1:00000000DBB8886E in 352ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has more messages
Long dispatch time: 1177 ms, for event type 3
Received a duplicate message with MessageCounter:219959730 on exchange 51596r
>>> [E:51596r S:21470 M:219959730 (Ack:69335164)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959730 on exchange 51596r
<<< [E:51596r S:21470 M:69335167 (Ack:219959730)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
Received a duplicate message with MessageCounter:219959730 on exchange 51596r
>>> [E:51596r S:21470 M:219959730 (Ack:69335164)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959730 on exchange 51596r
<<< [E:51596r S:21470 M:69335168 (Ack:219959730)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959731 (Ack:69335166)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335166 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 35, Attribute 39 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0039 (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_0039err = 2d
<RE:Run> Cluster 35, Attribute 3a is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003A (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_003Aerr = 2d
<RE:Run> Cluster 35, Attribute 3b is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003B (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_003Berr = 2d
<RE:Run> Cluster 35, Attribute 3c is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003C (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_003Cerr = 2d
<RE:Run> Cluster 35, Attribute 3d is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003D (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_003Derr = 2d
<RE:Run> Cluster 35, Attribute 3e is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003E (expanded=1)
Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0035, attributeId: 0x0000_003Eerr = 2d
<RE:Run> Cluster 35, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 35, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 35, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 35, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 35, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 36, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
The WiFi interface is not currently connected.
<RE:Run> Cluster 36, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
The current 802.11 standard version in use by the Node is not available
<RE:Run> Cluster 36, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0004 (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute 5 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0005 (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute 6 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0006 (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute 7 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0007 (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute 8 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0008 (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute 9 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_0009 (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute a is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_000A (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute b is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_000B (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute c is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_000C (expanded=1)
The WiFi interface is not currently configured or operational.
<RE:Run> Cluster 36, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 36, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 36, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 36, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 36, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0036 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 37, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
The Ethernet interface is not currently configured or operational
<RE:Run> Cluster 37, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
The Ethernet interface is not currently configured or operational
<RE:Run> Cluster 37, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 37, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 37, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_0004 (expanded=1)
<RE:Run> Cluster 37, Attribute 5 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_0005 (expanded=1)
<RE:Run> Cluster 37, Attribute 6 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_0006 (expanded=1)
<RE:Run> Cluster 37, Attribute 7 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_0007 (expanded=1)
The Ethernet interface is not currently configured or operational
<RE:Run> Cluster 37, Attribute 8 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_0008 (expanded=1)
<RE:Run> Cluster 37, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
Next attribute value does not fit in packet, roll back on clusterId: 0x0000_0037, attributeId: 0x0000_FFFC, err = b
<RE:Run> We cannot put more chunks into this report. Enable chunking.
<RE:Run> first cluster event is too big so that it fails to fit in the packet!
Fetched 0 events
<RE> Sending report (payload has 1166 bytes)...
<<< [E:51596r S:21470 M:69335169 (Ack:219959731)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:1200)
??1 [E:51596r S:21470 M:69335169] (S) Msg Retransmission to 1:00000000DBB8886E in 341ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has more messages
Long dispatch time: 695 ms, for event type 3
Received a duplicate message with MessageCounter:219959731 on exchange 51596r
>>> [E:51596r S:21470 M:219959731 (Ack:69335166)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959731 on exchange 51596r
<<< [E:51596r S:21470 M:69335170 (Ack:219959731)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
Received a duplicate message with MessageCounter:219959731 on exchange 51596r
>>> [E:51596r S:21470 M:219959731 (Ack:69335166)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959731 on exchange 51596r
<<< [E:51596r S:21470 M:69335171 (Ack:219959731)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959732 (Ack:69335169)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335169 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 37, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 37, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 37, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 37, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 37, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0037 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 3b, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_003B Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 3b, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_003B Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 3b, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_003B Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 3b, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_003B Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 3b, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_003B Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 3b, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_003B Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 3b, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_003B Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 3c, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 3c, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 3c, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 3c, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 3c, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 3c, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 3c, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 3c, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 3e, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 3e, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 3e, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 3e, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
Next attribute value does not fit in packet, roll back on clusterId: 0x0000_003E, attributeId: 0x0000_0003, err = b
<RE:Run> We cannot put more chunks into this report. Enable chunking.
<RE:Run> first cluster event is too big so that it fails to fit in the packet!
Fetched 0 events
<RE> Sending report (payload has 1171 bytes)...
<<< [E:51596r S:21470 M:69335172 (Ack:219959732)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:1205)
??1 [E:51596r S:21470 M:69335172] (S) Msg Retransmission to 1:00000000DBB8886E in 383ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has more messages
Long dispatch time: 387 ms, for event type 3
Received a duplicate message with MessageCounter:219959732 on exchange 51596r
>>> [E:51596r S:21470 M:219959732 (Ack:69335169)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959732 on exchange 51596r
<<< [E:51596r S:21470 M:69335173 (Ack:219959732)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959733 (Ack:69335172)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335172 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 3e, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 3e, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0004 (expanded=1)
<RE:Run> Cluster 3e, Attribute 5 is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0005 (expanded=1)
<RE:Run> Cluster 3e, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 3e, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 3e, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 3e, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 3e, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 3f, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 3f, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 3f, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 3f, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 3f, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 3f, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 3f, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 3f, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 3f, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 40, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0040 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
Get the fixed label with index:0 at endpoint:0
Get the fixed label with index:1 at endpoint:0
Get the fixed label with index:2 at endpoint:0
Get the fixed label with index:3 at endpoint:0
<RE:Run> Cluster 40, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0040 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
Next attribute value does not fit in packet, roll back on clusterId: 0x0000_0040, attributeId: 0x0000_FFFC, err = b
<RE:Run> We cannot put more chunks into this report. Enable chunking.
<RE:Run> first cluster event is too big so that it fails to fit in the packet!
Fetched 0 events
<RE> Sending report (payload has 1162 bytes)...
<<< [E:51596r S:21470 M:69335174 (Ack:219959733)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:1196)
??1 [E:51596r S:21470 M:69335174] (S) Msg Retransmission to 1:00000000DBB8886E in 351ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has more messages
Long dispatch time: 361 ms, for event type 3
Received a duplicate message with MessageCounter:219959733 on exchange 51596r
>>> [E:51596r S:21470 M:219959733 (Ack:69335172)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959733 on exchange 51596r
<<< [E:51596r S:21470 M:69335175 (Ack:219959733)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959734 (Ack:69335174)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335174 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 40, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0040 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 40, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0040 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 40, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0040 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 40, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0040 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 40, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0040 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 41, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0041 Endpoint=0 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 41, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0041 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 41, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0041 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 41, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0041 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 41, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0041 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 41, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0041 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 3, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 3, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 3, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 3, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 3, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 3, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 3, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 4, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 4, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 4, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 4, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 4, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 4, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 6, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 6, Attribute 4000 is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4000 (expanded=1)
<RE:Run> Cluster 6, Attribute 4001 is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4001 (expanded=1)
<RE:Run> Cluster 6, Attribute 4002 is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4002 (expanded=1)
<RE:Run> Cluster 6, Attribute 4003 is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4003 (expanded=1)
<RE:Run> Cluster 6, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 6, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 6, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 6, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 6, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 8, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 8, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 8, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 8, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 8, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0004 (expanded=1)
Next attribute value does not fit in packet, roll back on clusterId: 0x0000_0008, attributeId: 0x0000_0004, err = b
<RE:Run> We cannot put more chunks into this report. Enable chunking.
<RE:Run> first cluster event is too big so that it fails to fit in the packet!
Fetched 0 events
<RE> Sending report (payload has 1165 bytes)...
<<< [E:51596r S:21470 M:69335176 (Ack:219959734)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:1199)
??1 [E:51596r S:21470 M:69335176] (S) Msg Retransmission to 1:00000000DBB8886E in 361ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has more messages
Long dispatch time: 549 ms, for event type 3
Received a duplicate message with MessageCounter:219959734 on exchange 51596r
>>> [E:51596r S:21470 M:219959734 (Ack:69335174)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959734 on exchange 51596r
<<< [E:51596r S:21470 M:69335177 (Ack:219959734)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959735 (Ack:69335176)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335176 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 8, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0004 (expanded=1)
<RE:Run> Cluster 8, Attribute 5 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0005 (expanded=1)
<RE:Run> Cluster 8, Attribute 6 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0006 (expanded=1)
<RE:Run> Cluster 8, Attribute f is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_000F (expanded=1)
<RE:Run> Cluster 8, Attribute 10 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0010 (expanded=1)
<RE:Run> Cluster 8, Attribute 11 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0011 (expanded=1)
<RE:Run> Cluster 8, Attribute 12 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0012 (expanded=1)
<RE:Run> Cluster 8, Attribute 13 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0013 (expanded=1)
<RE:Run> Cluster 8, Attribute 14 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0014 (expanded=1)
<RE:Run> Cluster 8, Attribute 4000 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_4000 (expanded=1)
<RE:Run> Cluster 8, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 8, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 8, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 8, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 8, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 1d, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 1d, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 1d, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 1d, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 1d, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 1d, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 1d, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 1d, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 1d, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 62, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0062 Endpoint=1 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 62, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0062 Endpoint=1 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 62, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0062 Endpoint=1 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 62, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0062 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 62, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0062 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 62, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0062 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 62, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0062 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 62, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0062 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1)
<RE:Run> Cluster 300, Attribute 0 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_0000 (expanded=1)
<RE:Run> Cluster 300, Attribute 1 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_0001 (expanded=1)
<RE:Run> Cluster 300, Attribute 2 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_0002 (expanded=1)
<RE:Run> Cluster 300, Attribute 3 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_0003 (expanded=1)
<RE:Run> Cluster 300, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_0004 (expanded=1)
Next attribute value does not fit in packet, roll back on clusterId: 0x0000_0300, attributeId: 0x0000_0004, err = b
<RE:Run> We cannot put more chunks into this report. Enable chunking.
<RE:Run> first cluster event is too big so that it fails to fit in the packet!
Fetched 0 events
<RE> Sending report (payload has 1158 bytes)...
<<< [E:51596r S:21470 M:69335178 (Ack:219959735)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:1192)
??1 [E:51596r S:21470 M:69335178] (S) Msg Retransmission to 1:00000000DBB8886E in 378ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has more messages
Long dispatch time: 536 ms, for event type 3
Received a duplicate message with MessageCounter:219959735 on exchange 51596r
>>> [E:51596r S:21470 M:219959735 (Ack:69335176)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959735 on exchange 51596r
<<< [E:51596r S:21470 M:69335179 (Ack:219959735)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959736 (Ack:69335178)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335178 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000
<RE:Run> Cluster 300, Attribute 4 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_0004 (expanded=1)
<RE:Run> Cluster 300, Attribute 7 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_0007 (expanded=1)
<RE:Run> Cluster 300, Attribute 8 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_0008 (expanded=1)
<RE:Run> Cluster 300, Attribute f is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_000F (expanded=1)
<RE:Run> Cluster 300, Attribute 10 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_0010 (expanded=1)
<RE:Run> Cluster 300, Attribute 4000 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_4000 (expanded=1)
<RE:Run> Cluster 300, Attribute 4001 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_4001 (expanded=1)
<RE:Run> Cluster 300, Attribute 4002 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_4002 (expanded=1)
<RE:Run> Cluster 300, Attribute 4003 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_4003 (expanded=1)
<RE:Run> Cluster 300, Attribute 4004 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_4004 (expanded=1)
<RE:Run> Cluster 300, Attribute 4005 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_4005 (expanded=1)
<RE:Run> Cluster 300, Attribute 4006 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_4006 (expanded=1)
<RE:Run> Cluster 300, Attribute 400a is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_400A (expanded=1)
<RE:Run> Cluster 300, Attribute 400b is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_400B (expanded=1)
<RE:Run> Cluster 300, Attribute 400c is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_400C (expanded=1)
<RE:Run> Cluster 300, Attribute 400d is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_400D (expanded=1)
<RE:Run> Cluster 300, Attribute 4010 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_4010 (expanded=1)
<RE:Run> Cluster 300, Attribute fffc is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1)
<RE:Run> Cluster 300, Attribute fffd is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1)
<RE:Run> Cluster 300, Attribute fff8 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1)
<RE:Run> Cluster 300, Attribute fff9 is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1)
<RE:Run> Cluster 300, Attribute fffb is dirty
Reading attribute: Cluster=0x0000_0300 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1)
Fetched 2 events
<RE> Sending report (payload has 810 bytes)...
<<< [E:51596r S:21470 M:69335180 (Ack:219959736)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:05 (IM:ReportData) (B:844)
??1 [E:51596r S:21470 M:69335180] (S) Msg Retransmission to 1:00000000DBB8886E in 402ms [State:Active II:500 AI:300 AT:4000]
IM RH moving to [AwaitingReportResponse]
<RE> ReportsInFlight = 1 with readHandler 0, RE has no more messages
All ReadHandler-s are clean, clear GlobalDirtySet
Long dispatch time: 329 ms, for event type 3
Received a duplicate message with MessageCounter:219959736 on exchange 51596r
>>> [E:51596r S:21470 M:219959736 (Ack:69335178)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Forcing tx of solitary ack for duplicate MessageCounter:219959736 on exchange 51596r
<<< [E:51596r S:21470 M:69335181 (Ack:219959736)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
>>> [E:51596r S:21470 M:219959737 (Ack:69335180)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0001:01 (IM:StatusResponse) (B:42)
Found matching exchange: 51596r, Delegate: 0x2803001c
Rxd Ack; Removing MessageCounter:69335180 from Retrans Table on exchange 51596r
Received status response, status is 0x00
<<< [E:51596r S:21470 M:69335182 (Ack:219959737)] (S) Msg TX from 000000007BB66C20 to 1:00000000DBB8886E [A498] [UDP:192.168.1.72:49325] --- Type 0001:04 (IM:SubscribeResponse) (B:49)
??1 [E:51596r S:21470 M:69335182] (S) Msg Retransmission to 1:00000000DBB8886E in 362ms [State:Active II:500 AI:300 AT:4000]
Registered a ReadHandler that will schedule a report between system Timestamp: 0x0000000000089D5A and system Timestamp 0x000000000011C51A.
<RE> OnReportConfirm: NumReports = 0
IM RH moving to [CanStartReporting]
>>> [E:51596r S:21470 M:219959738 (Ack:69335182)] (S) Msg RX from 1:00000000DBB8886E [A498] to 000000007BB66C20 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)
Found matching exchange: 51596r, Delegate: 0
Rxd Ack; Removing MessageCounter:69335182 from Retrans Table on exchange 51596r
Extended discovery timed out
Updating services using commissioning mode 0
CHIP minimal mDNS started advertising.
Advertise operational node 83E158656A58A498-000000007BB66C20
Responding with _matter._tcp.local
Responding with 83E158656A58A498-000000007BB66C20._matter._tcp.local
Responding with 83E158656A58A498-000000007BB66C20._matter._tcp.local
Responding with B89E36373047.local
Responding with B89E36373047.local
Responding with _I83E158656A58A498._sub._matter._tcp.local
CHIP minimal mDNS configured as 'Operational device'; instance name: 83E158656A58A498-000000007BB66C20.
mDNS service published: _matter._tcp
Advertise operational node AFEF77F268CFD7F1-000000004A5B961A
Responding with _matter._tcp.local
Responding with AFEF77F268CFD7F1-000000004A5B961A._matter._tcp.local
Responding with AFEF77F268CFD7F1-000000004A5B961A._matter._tcp.local
Responding with B89E36373047.local
Responding with B89E36373047.local
Responding with _IAFEF77F268CFD7F1._sub._matter._tcp.local
CHIP minimal mDNS configured as 'Operational device'; instance name: AFEF77F268CFD7F1-000000004A5B961A.
mDNS service published: _matter._tcp