I: Factory Reset Triggered. Release button within 3000ms to cancel. I: Factory Reset triggered I: 103490 [TS]Pending Last Known Good Time: 2022-10-17T10:52:00 I: 103497 [TS]Previous Last Known Good Time: 2022-10-17T10:52:00 I: 103502 [TS]Reverted Last Known Good Time to previous value D: 103508 [ZCL]Emitting ShutDown event D: 103512 [EVL]LogEvent event number: 0x0000000000000002 priority: 2, endpoint id: 0x0 cluster id: 0x0000_0028 event id: 0x1 Sys timestamp: 0x0000000000019457 D: 103526 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 103532 [DL]Performing factory reset I: 103550 [DL]Erasing Thread persistent info... I: nRF5 802154 radio initialized I: 4 Sectors of 4096 bytes I: alloc wra: 0, c48 I: data wra: 0, 16c ooting Zephyr OS build v3.1.99-ncs1 *** I: Init CHIP stack 32muart:~$ I: 232 [DL]BLE address: D9:7E:16:9F:22:1F I: SoftDevice Controller build revision: I: f2 e7 5f 6f 23 a2 f3 e8 |.._o#... I: 10 2f c3 35 9e d7 1d fe |./.5.... I: 8f 80 42 f9 |..B. I: 259 [DL]OpenThread started: OK I: 262 [DL]Setting OpenThread device type to ROUTER I: 266 [SVR]Server initializing... D: 270 [FP]Initializing FabricTable from persistent storage I: 276 [TS]Last Known Good Time: [unknown] I: 280 [TS]Setting Last Known Good Time to firmware build time 2022-10-17T10:52:00 I: 292 [DMG]AccessControl: initializing I: 295 [DMG]Examples::AccessControlDelegate::Init I: 300 [DMG]AccessControl: setting I: 303 [DMG]DefaultAclStorage: initializing I: 307 [DMG]DefaultAclStorage: 0 entries loaded D: 311 [IN]UDP::Init bind&listen port=5540 E: 315 [IN]SO_REUSEPORT failed: 109 D: 318 [IN]UDP::Init bound to port=5540 D: 322 [IN]BLEBase::Init - setting/overriding transport D: 327 [IN]TransportMgr initialized D: 338 [DL]Using Thread extended MAC for hostname. I: 347 [ZCL]Using ZAP configuration... D: 351 [DMG]Failed to read stored attribute (0, 0x0000_0028, 0x0000_0005: a0 D: 359 [DMG]Failed to read stored attribute (0, 0x0000_0028, 0x0000_0010: a0 D: 367 [DMG]Failed to read stored attribute (1, 0x0000_0006, 0x0000_0000: a0 D: 375 [DMG]Failed to read stored attribute (1, 0x0000_0006, 0x0000_4003: a0 D: 383 [DMG]Failed to read stored attribute (1, 0x0000_0008, 0x0000_0000: a0 D: 391 [DMG]Failed to read stored attribute (1, 0x0000_0008, 0x0000_4000: a0 I: 397 [DMG]AccessControlCluster: initializing D: 402 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to aff38dbc D: 408 [DL]Boot reason: 6 D: 410 [ZCL]GeneralDiagnostics: OnDeviceReboot D: 415 [DMG]Endpoint 0, Cluster 0x0000_0033 update version to 3f85d006 D: 421 [EVL]LogEvent event number: 0x0000000000000000 priority: 2, endpoint id: 0x0 cluster id: 0x0000_0033 event id: 0x3 Sys timestamp: 0x00000000000001A5 I: 435 [ZCL]Initiating Admin Commissioning cluster. D: 440 [DMG]Endpoint 0, Cluster 0x0000_0004 update version to b9b38298 D: 446 [DMG]Endpoint 0, Cluster 0x0000_0004 update version to b9b38299 D: 452 [DMG]Endpoint 1, Cluster 0x0000_0004 update version to 8724885b D: 459 [DMG]Endpoint 1, Cluster 0x0000_0004 update version to 8724885c D: 468 [DMG]Endpoint 1, Cluster 0x0000_0006 update version to 6944069c I: 474 [ZCL]Cluster OnOff: attribute OnOff set to 0 D: 482 [DMG]Endpoint 1, Cluster 0x0000_0008 update version to 4bbed545 I: 488 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 I: 495 [ZCL]On/Off set value: 1 0 I: 498 [ZCL]On/off already set to new value D: 502 [DMG]Endpoint 1, Cluster 0x0000_0008 update version to 4bbed546 I: 508 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 D: 517 [IN]SecureSession[0x200034f0]: Allocated Type:1 LSID:29013 D: 523 [SC]Assigned local session key ID 29013 D: 527 [SC]Waiting for PBKDF param request D: 531 [DL]CHIPoBLE advertising set to on I: 535 [DIS]Updating services using commissioning mode 1 D: 540 [DL]Using Thread extended MAC for hostname. D: 545 [DL]Using Thread extended MAC for hostname. I: 550 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3848/15 cm=1 E: 559 [DIS]Failed to advertise commissionable node: 3 E: 564 [DIS]Failed to finalize service update: 1c I: 569 [DIS]Updating services using commissioning mode 1 D: 574 [DL]Using Thread extended MAC for hostname. D: 579 [DL]Using Thread extended MAC for hostname. I: 584 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3848/15 cm=1 E: 593 [DIS]Failed to advertise commissionable node: 3 E: 598 [DIS]Failed to finalize service update: 1c I: 602 [DIS]Delaying proxy of operational discovery: missing delegate I: 609 [IN]CASE Server enabling CASE session setups D: 614 [IN]SecureSession[0x200035a8]: Allocated Type:2 LSID:29014 D: 619 [SC]Allocated SecureSession (0x200035a8) - waiting for Sigma1 msg I: 626 [SVR]Joining Multicast groups D: 629 [ZCL]Emitting StartUp event D: 633 [EVL]LogEvent event number: 0x0000000000000001 priority: 2, endpoint id: 0x0 cluster id: 0x0000_0028 event id: 0x0 Sys timestamp: 0x0000000000000278 I: 646 [SVR]Server Listening... I: 649 [DL]Device Configuration: I: 653 [DL] Serial Number: 11223344556677889900 I: 657 [DL] Vendor Id: 65521 (0xFFF1) I: 661 [DL] Product Id: 32773 (0x8005) I: 665 [DL] Hardware Version: 0 I: 669 [DL] Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 I: 675 [DL] Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3848 (0xF08) I: 682 [DL] Manufacturing Date: (not set) I: 686 [DL] Device Type: 65535 (0xFFFF) I: 691 [SVR]SetupQRCode: [MT:6FCJ142C00LA0648G00] I: 696 [SVR]Copy/paste the below URL in a browser to see the QR Code: I: 702 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ142C00LA0648G00 I: 711 [SVR]Manual pairing code: [34970112332] I: 716 [DL]CHIP task running I: 721 [DL]CHIPoBLE advertising started I: 726 [DL]NFC Tag emulation started uart:~$ I: BLE advertising is already enabled I: 30725 [DL]CHIPoBLE advertising mode changed to slow uart:~$ I: BLE advertising is already enabled uart:~$ I: 89915 [DL]BLE connection established (ConnId: 0x00) I: 89919 [DL]Current number of connections: 1/1 I: 89924 [DL]CHIPoBLE advertising stopped I: 89927 [DL]NFC Tag emulation stopped D: 90947 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 90954 [BLE]local and remote recv window sizes = 5 I: 90959 [BLE]selected BTP version 4 I: 90963 [BLE]using BTP fragment sizes rx 128 / tx 128. D: 90968 [DL]ConnId: 0x00, New CCCD value: 0x0002 D: 90973 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6) D: 90981 [IN]BLE EndPoint 0x20011b80 Connection Complete I: 90986 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 131) D: 90994 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 91142 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 91149 [EM]Received message of type 0x20 with protocolId (0, 0) and MessageCounter:236853349 on exchange 44949r D: 91159 [EM]Handling via exchange: 44949r, Delegate: 0x200056d4 D: 91165 [SC]Received PBKDF param request D: 91169 [SC]Peer assigned session ID 32420 D: 91173 [SC]Found MRP parameters in the message D: 91177 [SC]Including MRP parameters in PBKDF param response I: 91183 [IN]Prepared unauthenticated message 0x2002e5a0 to 0x0000000000000000 (0) of type 0x21 and protocolId (0, 0) on exchange 44949r with MessageCounter:221911333. I: 91199 [IN]Sending unauthenticated msg 0x2002e5a0 with MessageCounter:221911333 to 0x0000000000000000 at monotonic time: 000000000001643E msec D: 91211 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 91219 [SC]Sent PBKDF param response D: 91292 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 91299 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 14) D: 91392 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 91400 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 91407 [EM]Received message of type 0x22 with protocolId (0, 0) and MessageCounter:236853350 on exchange 44949r D: 91417 [EM]Found matching exchange: 44949r, Delegate: 0x200056d4 D: 91423 [SC]Received spake2p msg1 I: 93405 [IN]Prepared unauthenticated message 0x2002e530 to 0x0000000000000000 (0) of type 0x23 and protocolId (0, 0) on exchange 44949r with MessageCounter:221911334. I: 93420 [IN]Sending unauthenticated msg 0x2002e530 with MessageCounter:221911334 to 0x0000000000000000 at monotonic time: 0000000000016CEB msec D: 93432 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 93440 [SC]Sent spake2p msg2 E: 93443 [DL]Long dispatch time: 2043 ms, for event type 16388 D: 93542 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 93549 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6) D: 93642 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 93650 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 93657 [EM]Received message of type 0x24 with protocolId (0, 0) and MessageCounter:236853351 on exchange 44949r D: 93667 [EM]Found matching exchange: 44949r, Delegate: 0x200056d4 D: 93673 [SC]Received spake2p msg3 D: 93677 [SC]Sending status report. Protocol code 0, exchange 44949 I: 93683 [IN]Prepared unauthenticated message 0x2002e5b8 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 44949r with MessageCounter:221911335. I: 93698 [IN]Sending unauthenticated msg 0x2002e5b8 with MessageCounter:221911335 to 0x0000000000000000 at monotonic time: 0000000000016E02 msec D: 93711 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 35) I: 93720 [SC]SecureSession[0x200034f0]: Moving from state 'kEstablishing' --> 'kActive' D: 93727 [IN]SecureSession[0x200034f0]: Activated - Type:1 LSID:29013 D: 93734 [IN]New secure session activated for device , LSID:29013 PSID:32420! I: 93743 [SVR]Commissioning completed session establishment step I: 93749 [DIS]Updating services using commissioning mode 0 D: 93754 [DL]Using Thread extended MAC for hostname. D: 93759 [DL]Using Thread extended MAC for hostname. I: 93765 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3848/15 cm=0 E: 93774 [DIS]Failed to advertise extended commissionable node: 3 D: 93780 [DIS]Scheduling extended discovery timeout in 900s E: 93785 [DIS]Failed to finalize service update: 1c D: 93790 [DL]CHIPoBLE advertising set to off I: 93795 [SVR]Device completed Rendezvous process E: 93799 [DL]Long dispatch time: 149 ms, for event type 16388 D: 93805 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 93812 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 93820 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:23627778 on exchange 44950r D: 93830 [EM]Handling via exchange: 44950r, Delegate: 0x2000f54c D: 93836 [IM]Received Read request D: 93840 [DMG]IM RH moving to [GeneratingReports] D: 93845 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 93853 [DMG] Cluster 28, Attribute 2 is dirty D: 93858 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0002 (expanded=0) D: 93867 [DMG] Sending report (payload has 37 bytes)... I: 93873 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 44950r with MessageCounter:223714748. I: 93888 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:223714748 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000016EBF msec D: 93901 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 93908 [DMG] OnReportConfirm: NumReports = 0 D: 93913 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 93920 [DMG]IM RH moving to [AwaitingDestruction] D: 93925 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 93992 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 94000 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 94008 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:23627779 on exchange 44951r D: 94018 [EM]Handling via exchange: 44951r, Delegate: 0x2000f54c D: 94023 [IM]Received Read request D: 94027 [DMG]IM RH moving to [GeneratingReports] D: 94032 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 94041 [DMG] Cluster 28, Attribute 4 is dirty D: 94046 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0004 (expanded=0) D: 94055 [DMG] Sending report (payload has 37 bytes)... I: 94061 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 44951r with MessageCounter:223714749. I: 94075 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:223714749 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000016F7B msec D: 94088 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 94096 [DMG] OnReportConfirm: NumReports = 0 D: 94100 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 94108 [DMG]IM RH moving to [AwaitingDestruction] D: 94112 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 94242 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 94250 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 94258 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:23627780 on exchange 44952r D: 94268 [EM]Handling via exchange: 44952r, Delegate: 0x2000f54c D: 94274 [IM]Received Read request D: 94277 [DMG]IM RH moving to [GeneratingReports] D: 94282 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 94291 [DMG] Cluster 3e, Attribute 2 is dirty D: 94296 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0002 (expanded=0) D: 94305 [DMG] Sending report (payload has 36 bytes)... I: 94311 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 44952r with MessageCounter:223714750. I: 94325 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:223714750 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000017075 msec D: 94338 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 94346 [DMG] OnReportConfirm: NumReports = 0 D: 94350 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 94358 [DMG]IM RH moving to [AwaitingDestruction] D: 94362 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 94442 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 94450 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 94458 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:23627781 on exchange 44953r D: 94468 [EM]Handling via exchange: 44953r, Delegate: 0x2000f54c D: 94474 [IM]Received Read request D: 94477 [DMG]IM RH moving to [GeneratingReports] D: 94482 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 94491 [DMG] Cluster 3e, Attribute 3 is dirty D: 94496 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0003 (expanded=0) D: 94505 [DMG] Sending report (payload has 36 bytes)... I: 94511 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 44953r with MessageCounter:223714751. I: 94525 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:223714751 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000001713D msec D: 94538 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 94546 [DMG] OnReportConfirm: NumReports = 0 D: 94550 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 94558 [DMG]IM RH moving to [AwaitingDestruction] D: 94562 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 94642 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 94692 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 94700 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:23627782 on exchange 44954r D: 94710 [EM]Handling via exchange: 44954r, Delegate: 0x2000f54c D: 94716 [IM]Received Read request D: 94720 [DMG]IM RH moving to [GeneratingReports] D: 94724 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 94733 [DMG] Cluster 1d, Attribute 1 is dirty D: 94738 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0001 (expanded=0) D: 94748 [DMG] Sending report (payload has 400 bytes)... I: 94755 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 44954r with MessageCounter:223714752. I: 94770 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:223714752 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000017231 msec D: 94782 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 94790 [DMG] OnReportConfirm: NumReports = 0 D: 94795 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 94802 [DMG]IM RH moving to [AwaitingDestruction] D: 94807 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 94842 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 94849 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 94942 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 94949 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 95292 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 95299 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 57) D: 95492 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 95500 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 95597 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 95605 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:23627783 on exchange 44955r D: 95615 [EM]Handling via exchange: 44955r, Delegate: 0x2000f54c D: 95621 [IM]Received Read request D: 95624 [DMG]IM RH moving to [GeneratingReports] D: 95629 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 95638 [DMG] Cluster 31, Attribute fffc is dirty D: 95643 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFC (expanded=0) D: 95652 [DMG] Sending report (payload has 37 bytes)... I: 95658 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 44955r with MessageCounter:223714753. I: 95673 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:223714753 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000000175B8 msec D: 95685 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 95693 [DMG] OnReportConfirm: NumReports = 0 D: 95698 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 95705 [DMG]IM RH moving to [AwaitingDestruction] D: 95710 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 95777 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 96407 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 96415 [EM]Received message of type 0xa with protocolId (0, 1) and MessageCounter:23627784 on exchange 44956r D: 96425 [EM]Handling via exchange: 44956r, Delegate: 0x2000f54c D: 96431 [DMG]Got Timed Request with timeout 10000: handler 0x2000f948 exchange 44956r I: 96439 [IN]Prepared secure message 0x2002e520 to 0xFFFFFFFB00000000 (0) of type 0x1 and protocolId (0, 1) on exchange 44956r with MessageCounter:223714754. I: 96453 [IN]Sending encrypted msg 0x2002e520 with MessageCounter:223714754 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000000178C5 msec D: 96466 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43) D: 96474 [DMG]Timed Request time limit 0x0000000000019FE9: handler 0x2000f948 exchange 44956r D: 96587 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 96595 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 96603 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627785 on exchange 44956r D: 96613 [EM]Found matching exchange: 44956r, Delegate: 0x2000f948 D: 96619 [DMG]Timed following action arrived at 0x000000000001796B: handler 0x2000f948 exchange 44956r D: 96628 [DMG]Handing timed invoke to IM engine: handler 0x2000f948 exchange 44956r D: 96636 [DMG]Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0000 I: 96644 [FS]GeneralCommissioning: Received ArmFailSafe (120s) D: 96649 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to aff38dbd D: 96656 [DMG]Command handler moving to [ Preparing] D: 96661 [DMG]Command handler moving to [AddingComm] D: 96665 [DMG]Command handler moving to [AddedComma] D: 96670 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 96678 [IN]Prepared secure message 0x2002e5a0 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 44956r with MessageCounter:223714755. I: 96692 [IN]Sending encrypted msg 0x2002e5a0 with MessageCounter:223714755 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000000179B4 msec D: 96704 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 96712 [DMG]Command handler moving to [CommandSen] D: 96717 [DMG]Command handler moving to [AwaitingDe] E: 96722 [DL]Long dispatch time: 127 ms, for event type 16388 D: 96767 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 96775 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 96783 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:23627786 on exchange 44957r D: 96793 [EM]Handling via exchange: 44957r, Delegate: 0x2000f54c D: 96799 [IM]Received Read request D: 96802 [DMG]IM RH moving to [GeneratingReports] D: 96807 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 96816 [DMG] Cluster 30, Attribute 3 is dirty D: 96821 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0003 (expanded=0) D: 96830 [DMG] Sending report (payload has 36 bytes)... I: 96836 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 44957r with MessageCounter:223714756. I: 96850 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:223714756 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000017A52 msec D: 96863 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 96871 [DMG] OnReportConfirm: NumReports = 0 D: 96875 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 96883 [DMG]IM RH moving to [AwaitingDestruction] D: 96887 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 96947 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 96955 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 96963 [EM]Received message of type 0xa with protocolId (0, 1) and MessageCounter:23627787 on exchange 44958r D: 96973 [EM]Handling via exchange: 44958r, Delegate: 0x2000f54c D: 96979 [DMG]Got Timed Request with timeout 10000: handler 0x2000f948 exchange 44958r I: 96987 [IN]Prepared secure message 0x2002e520 to 0xFFFFFFFB00000000 (0) of type 0x1 and protocolId (0, 1) on exchange 44958r with MessageCounter:223714757. I: 97001 [IN]Sending encrypted msg 0x2002e520 with MessageCounter:223714757 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000017AE9 msec D: 97014 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43) D: 97022 [DMG]Timed Request time limit 0x000000000001A20E: handler 0x2000f948 exchange 44958r D: 97172 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 97180 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 97188 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627788 on exchange 44958r D: 97198 [EM]Found matching exchange: 44958r, Delegate: 0x2000f948 D: 97204 [DMG]Timed following action arrived at 0x0000000000017BB4: handler 0x2000f948 exchange 44958r D: 97213 [DMG]Handing timed invoke to IM engine: handler 0x2000f948 exchange 44958r D: 97221 [DMG]Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0002 D: 97237 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to aff38dbe D: 97244 [DMG]Command handler moving to [ Preparing] D: 97248 [DMG]Command handler moving to [AddingComm] D: 97253 [DMG]Command handler moving to [AddedComma] D: 97258 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 97266 [IN]Prepared secure message 0x2002e5a0 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 44958r with MessageCounter:223714758. I: 97280 [IN]Sending encrypted msg 0x2002e5a0 with MessageCounter:223714758 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000017C00 msec D: 97293 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 97300 [DMG]Command handler moving to [CommandSen] D: 97305 [DMG]Command handler moving to [AwaitingDe] E: 97310 [DL]Long dispatch time: 130 ms, for event type 16388 D: 97397 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 97405 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 97413 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627789 on exchange 44959r D: 97423 [EM]Handling via exchange: 44959r, Delegate: 0x2000f54c D: 97429 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002 I: 97437 [ZCL]OpCreds: Certificate Chain request received for DAC D: 97443 [DMG]Command handler moving to [ Preparing] D: 97448 [DMG]Command handler moving to [AddingComm] D: 97452 [DMG]Command handler moving to [AddedComma] D: 97457 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 97467 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 44959r with MessageCounter:223714759. I: 97481 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:223714759 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000017CC8 msec D: 97494 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 97502 [DMG]Command handler moving to [CommandSen] D: 97506 [DMG]Command handler moving to [AwaitingDe] E: 97511 [DL]Long dispatch time: 106 ms, for event type 16388 D: 97577 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 97584 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 97712 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 97719 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 97802 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 97809 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 98027 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 98035 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 98042 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 57) D: 98117 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 98125 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 98133 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627790 on exchange 44960r D: 98143 [EM]Handling via exchange: 44960r, Delegate: 0x2000f54c D: 98149 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002 I: 98157 [ZCL]OpCreds: Certificate Chain request received for PAI D: 98163 [DMG]Command handler moving to [ Preparing] D: 98167 [DMG]Command handler moving to [AddingComm] D: 98172 [DMG]Command handler moving to [AddedComma] D: 98177 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 98186 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 44960r with MessageCounter:223714760. I: 98201 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:223714760 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000017F98 msec D: 98214 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 98221 [DMG]Command handler moving to [CommandSen] D: 98226 [DMG]Command handler moving to [AwaitingDe] E: 98231 [DL]Long dispatch time: 106 ms, for event type 16388 D: 98387 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 98394 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 98477 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 98484 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 98612 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 98619 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 98702 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 98710 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 98717 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 28) D: 98837 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 98845 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 98853 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627791 on exchange 44961r D: 98863 [EM]Handling via exchange: 44961r, Delegate: 0x2000f54c D: 98869 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0000 I: 98877 [ZCL]OpCreds: Received an AttestationRequest command I: 98913 [ZCL]OpCreds: AttestationRequest successful. D: 98918 [DMG]Command handler moving to [ Preparing] D: 98923 [DMG]Command handler moving to [AddingComm] D: 98928 [DMG]Command handler moving to [AddedComma] D: 98933 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 98943 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 44961r with MessageCounter:223714761. I: 98957 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:223714761 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000001828D msec D: 98970 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 98977 [DMG]Command handler moving to [CommandSen] D: 98982 [DMG]Command handler moving to [AwaitingDe] E: 98987 [DL]Long dispatch time: 142 ms, for event type 16388 D: 99062 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 99069 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 99197 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 99205 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 99287 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 99294 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 99422 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 99467 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 99474 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 99647 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 99654 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 92) D: 99737 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 99782 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 99790 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627792 on exchange 44962r D: 99800 [EM]Handling via exchange: 44962r, Delegate: 0x2000f54c D: 99807 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0004 I: 99814 [ZCL]OpCreds: Received a CSRRequest command D: 99819 [ZCL]OpCreds: Finding fabric with fabricIndex 0x0 I: 100021 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded I: 100057 [ZCL]OpCreds: CSRRequest successful. D: 100061 [DMG]Command handler moving to [ Preparing] D: 100066 [DMG]Command handler moving to [AddingComm] D: 100071 [DMG]Command handler moving to [AddedComma] D: 100076 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 100084 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 44962r with MessageCounter:223714762. I: 100099 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:223714762 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000018702 msec D: 100111 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 100119 [DMG]Command handler moving to [CommandSen] D: 100124 [DMG]Command handler moving to [AwaitingDe] E: 100129 [DL]Long dispatch time: 347 ms, for event type 16388 D: 100187 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 100194 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 100322 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 100329 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 100412 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 101582 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 101590 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:23627793 on exchange 44963r D: 101600 [EM]Handling via exchange: 44963r, Delegate: 0x2000f54c D: 101606 [IM]Received Read request D: 101610 [DMG]IM RH moving to [GeneratingReports] D: 101614 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 101623 [DMG] Cluster 3e, Attribute 1 is dirty D: 101628 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0001 (expanded=0) D: 101638 [DMG] Sending report (payload has 36 bytes)... I: 101644 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (0) of type 0x5 and protocolId (0, 1) on exchange 44963r with MessageCounter:223714763. I: 101658 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:223714763 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000018D1A msec D: 101671 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 101679 [DMG] OnReportConfirm: NumReports = 0 D: 101683 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 101691 [DMG]IM RH moving to [AwaitingDestruction] D: 101695 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 101807 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 101815 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 101897 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 101987 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 101997 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627794 on exchange 44964r D: 102007 [EM]Handling via exchange: 44964r, Delegate: 0x2000f54c D: 102013 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B I: 102021 [ZCL]OpCreds: Received an AddTrustedRootCertificate command I: 102085 [ZCL]OpCreds: AddTrustedRootCertificate successful. D: 102090 [DMG]Command handler moving to [ Preparing] D: 102095 [DMG]Command handler moving to [AddingComm] D: 102100 [DMG]Command handler moving to [AddedComma] D: 102105 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 102112 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (0) of type 0x9 and protocolId (0, 1) on exchange 44964r with MessageCounter:223714764. I: 102127 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:223714764 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000018EEF msec D: 102139 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 68) D: 102147 [DMG]Command handler moving to [CommandSen] D: 102152 [DMG]Command handler moving to [AwaitingDe] E: 102157 [DL]Long dispatch time: 170 ms, for event type 16388 D: 102212 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 102220 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 102302 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 102437 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 102527 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 102535 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 102752 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 102760 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 102842 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 102853 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627795 on exchange 44965r D: 102863 [EM]Handling via exchange: 44965r, Delegate: 0x2000f54c D: 102870 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0006 I: 102877 [ZCL]OpCreds: Received an AddNOC command I: 102886 [FP]Validating NOC chain I: 103004 [FP]NOC chain validation successful I: 103008 [FP]Added new fabric at index: 0x1 I: 103012 [FP]Assigned compressed fabric ID: 0x3199E9CFDD25901B, node ID: 0x00000000B50FD39C I: 103021 [TS]Last Known Good Time: 2022-10-17T10:52:00 I: 103026 [TS]New proposed Last Known Good Time: 2022-10-17T03:10:22 I: 103032 [TS]Retaining current Last Known Good Time D: 103037 [DMG]Endpoint 0, Cluster 0x0000_003E update version to c4de9bf0 D: 103043 [DMG]Endpoint 0, Cluster 0x0000_003E update version to c4de9bf1 D: 103082 [EVL]LogEvent event number: 0x0000000000000002 priority: 1, endpoint id: 0x0 cluster id: 0x0000_001F event id: 0x0 Sys timestamp: 0x00000000000192AA I: 103096 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0xFFFFFFFD00010001 D: 103105 [DL]Using Thread extended MAC for hostname. I: 103110 [DIS]Advertise operational node 3199E9CFDD25901B-00000000B50FD39C D: 103117 [DMG]Endpoint 0, Cluster 0x0000_003E update version to c4de9bf2 D: 103124 [DMG]Endpoint 0, Cluster 0x0000_003E update version to c4de9bf3 D: 103130 [DMG]Command handler moving to [ Preparing] D: 103135 [DMG]Command handler moving to [AddingComm] D: 103140 [DMG]Command handler moving to [AddedComma] I: 103145 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC D: 103152 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 103159 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (1) of type 0x9 and protocolId (0, 1) on exchange 44965r with MessageCounter:223714765. I: 103173 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:223714765 to 0xFFFFFFFB00000000 (1) at monotonic time: 0000000000019305 msec D: 103186 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 103194 [DMG]Command handler moving to [CommandSen] D: 103198 [DMG]Command handler moving to [AwaitingDe] E: 103203 [DL]Long dispatch time: 361 ms, for event type 16388 D: 103337 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 103345 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 103472 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 103481 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627796 on exchange 44966r D: 103490 [EM]Handling via exchange: 44966r, Delegate: 0x2000f54c D: 103497 [DMG]Received command for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0003 D: 103518 [DMG]Command handler moving to [ Preparing] D: 103523 [DMG]Command handler moving to [AddingComm] D: 103528 [DMG]Command handler moving to [AddedComma] D: 103532 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to aff38dbf D: 103539 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 103547 [IN]Prepared secure message 0x2002e618 to 0xFFFFFFFB00000000 (1) of type 0x9 and protocolId (0, 1) on exchange 44966r with MessageCounter:223714766. I: 103561 [IN]Sending encrypted msg 0x2002e618 with MessageCounter:223714766 to 0xFFFFFFFB00000000 (1) at monotonic time: 0000000000019489 msec D: 103574 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 103582 [DMG]Command handler moving to [CommandSen] D: 103587 [DMG]Command handler moving to [AwaitingDe] E: 103591 [DL]Long dispatch time: 119 ms, for event type 16388 D: 103652 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 103660 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 103668 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:23627797 on exchange 44967r D: 103678 [EM]Handling via exchange: 44967r, Delegate: 0x2000f54c D: 103684 [IM]Received Read request D: 103688 [DMG]IM RH moving to [GeneratingReports] D: 103692 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 103701 [DMG] Cluster 31, Attribute 3 is dirty D: 103706 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0003 (expanded=0) D: 103716 [DMG] Sending report (payload has 36 bytes)... I: 103722 [IN]Prepared secure message 0x2002e750 to 0xFFFFFFFB00000000 (1) of type 0x5 and protocolId (0, 1) on exchange 44967r with MessageCounter:223714767. I: 103736 [IN]Sending encrypted msg 0x2002e750 with MessageCounter:223714767 to 0xFFFFFFFB00000000 (1) at monotonic time: 0000000000019538 msec D: 103749 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 103757 [DMG] OnReportConfirm: NumReports = 0 D: 103761 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 103769 [DMG]IM RH moving to [AwaitingDestruction] D: 103774 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 103832 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 103877 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 103885 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:23627798 on exchange 44968r D: 103895 [EM]Handling via exchange: 44968r, Delegate: 0x2000f54c D: 103901 [DMG]Received command for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0006 D: 103929 [DMG]Decreasing reference count for CommandHandler, remaining 1 D: 103936 [DL]OpenThread State Changed (Flags: 0x101fc100) D: 103941 [DL] Network Name: NEST-PAN-BFD8 D: 103945 [DL] PAN Id: 0xBFD8 D: 103948 [DL] Extended PAN Id: 0x4311AB3B79DBEA79 D: 103953 [DL] Channel: 22 D: 103955 [DL] Mesh Prefix: fd4f:2727:5e06::/64 D: 103962 [DL]OpenThread State Changed (Flags: 0x1100101d) D: 103967 [DL] Device Role: DETACHED D: 103971 [DL] Thread Unicast Addresses: D: 103975 [DL] fd4f:2727:5e06:0:5765:71b7:12f5:f9ef/64 valid D: 103981 [DL] fe80::70ea:fb0b:b33b:2d8a/64 valid preferred I: 105281 [DL]SRP Client was started, detected server: fd4f:2727:5e06:0000:473a:1d06:8def:f7c5 D: 105290 [DL]OpenThread State Changed (Flags: 0x200012a4) D: 105464 [DL] Device Role: CHILD D: 105468 [DL] Partition Id: 0xA95283D E: 105523 [DL]Long dispatch time: 233 ms, for event type 32777 D: 105531 [DL]OpenThread State Changed (Flags: 0x00000001) D: 105536 [DL] Thread Unicast Addresses: D: 105540 [DL] fd23:7405:2b5e:1:e69:cc5:465b:3992/64 valid preferred D: 105548 [DL] fd4f:2727:5e06::ff:fe00:ac03/64 valid rloc D: 105554 [DL] fd4f:2727:5e06:0:5765:71b7:12f5:f9ef/64 valid D: 105560 [DL] fe80::70ea:fb0b:b33b:2d8a/64 valid preferred D: 105568 [DMG]Command handler moving to [ Preparing] D: 105572 [DMG]Command handler moving to [AddingComm] D: 105577 [DMG]Command handler moving to [AddedComma] D: 105582 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to aff38dc0 D: 105589 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 105596 [IN]Prepared secure message 0x2002e808 to 0xFFFFFFFB00000000 (1) of type 0x9 and protocolId (0, 1) on exchange 44968r with MessageCounter:223714768. I: 105612 [IN]Sending encrypted msg 0x2002e808 with MessageCounter:223714768 to 0xFFFFFFFB00000000 (1) at monotonic time: 0000000000019C8B msec D: 105627 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 70) D: 105635 [DMG]Command handler moving to [CommandSen] D: 105640 [DMG]Command handler moving to [AwaitingDe] D: 105645 [DL]Using Thread extended MAC for hostname. I: 105650 [DIS]Advertise operational node 3199E9CFDD25901B-00000000B50FD39C I: 105657 [SVR]Operational advertising enabled D: 105722 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 105784 [DL]SRP update succeeded I: 105787 [DIS]Setting operational delegate post init I: 105796 [SWU]Stopping the watchdog timer I: 105800 [SWU]Starting the periodic query timer, timeout: 86400 seconds I: 105807 [DIS]Updating services using commissioning mode 0 D: 105812 [DL]Using Thread extended MAC for hostname. I: 105817 [DIS]Advertise operational node 3199E9CFDD25901B-00000000B50FD39C I: 105824 [DL]advertising srp service: 3199E9CFDD25901B-00000000B50FD39C._matter._tcp D: 105832 [DL]Using Thread extended MAC for hostname. I: 105838 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3848/15 cm=0 I: 105884 [DL]advertising srp service: C6D0FCC421FBA92C._matterc._udp D: 105891 [DMG]Endpoint 0, Cluster 0x0000_002A update version to 3c0442c9 D: 105898 [DMG]Endpoint 0, Cluster 0x0000_002A update version to 3c0442ca D: 106386 [DL]SRP update succeeded I: 106712 [DL]BLE GAP connection terminated (reason 0x13) I: 106718 [DL]Current number of connections: 0/1 I: 109535 [EM]Received message of type 0x30 with protocolId (0, 0) and MessageCounter:236853352 on exchange 44969r D: 109545 [EM]Handling via exchange: 44969r, Delegate: 0x20004160 I: 109551 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004e70 I: 109559 [IN]CASE Server disabling CASE session setups D: 109565 [EM]Sending Standalone Ack for MessageCounter:236853352 on exchange 44969r I: 109572 [IN]Prepared unauthenticated message 0x2002e4d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 44969r with MessageCounter:221911336. I: 109587 [IN]Sending unauthenticated msg 0x2002e4d8 with MessageCounter:221911336 to 0x0000000000000000 at monotonic time: 000000000001AC13 msec D: 109601 [EM]Flushed pending ack for MessageCounter:236853352 on exchange 44969r I: 109609 [SC]Received Sigma1 msg D: 109612 [SC]Found MRP parameters in the message D: 109617 [SC]Peer assigned session key ID 32421 I: 109626 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x00000000B50FD39C D: 109855 [SC]Including MRP parameters I: 109859 [IN]Prepared unauthenticated message 0x20005124 to 0x0000000000000000 (0) of type 0x31 and protocolId (0, 0) on exchange 44969r with MessageCounter:221911337. I: 109874 [IN]Sending unauthenticated msg 0x20005124 with MessageCounter:221911337 to 0x0000000000000000 at monotonic time: 000000000001AD32 msec I: 109889 [SC]Sent Sigma2 msg I: 110238 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:236853353 on exchange 44969r D: 110248 [EM]Found matching exchange: 44969r, Delegate: 0x2000417c D: 110255 [EM]Rxd Ack; Removing MessageCounter:221911337 from Retrans Table on exchange 44969r D: 110263 [EM]Removed CHIP MessageCounter:221911337 from RetransTable on exchange 44969r I: 110439 [EM]Received message of type 0x32 with protocolId (0, 0) and MessageCounter:236853354 on exchange 44969r D: 110449 [EM]Found matching exchange: 44969r, Delegate: 0x2000417c D: 110455 [EM]CHIP MessageCounter:221911337 not in RetransTable on exchange 44969r D: 110462 [EM]Sending Standalone Ack for MessageCounter:236853354 on exchange 44969r I: 110470 [IN]Prepared unauthenticated message 0x2002e4c0 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 44969r with MessageCounter:221911338. I: 110485 [IN]Sending unauthenticated msg 0x2002e4c0 with MessageCounter:221911338 to 0x0000000000000000 at monotonic time: 000000000001AF95 msec D: 110499 [EM]Flushed pending ack for MessageCounter:236853354 on exchange 44969r I: 110506 [SC]Received Sigma3 msg E: 110515 [SC]The device does not support GetClock_RealTimeMS() API: 3. Falling back to Last Known Good UTC Time D: 110863 [SC]Sending status report. Protocol code 0, exchange 44969 I: 110870 [IN]Prepared unauthenticated message 0x20005124 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 44969r with MessageCounter:221911339. I: 110888 [IN]Sending unauthenticated msg 0x20005124 with MessageCounter:221911339 to 0x0000000000000000 at monotonic time: 000000000001B128 msec I: 110903 [SC]SecureSession[0x200035a8]: Moving from state 'kEstablishing' --> 'kActive' D: 110911 [IN]SecureSession[0x200035a8]: Activated - Type:2 LSID:29014 D: 110918 [IN]New secure session activated for device <0000000018EB5475, 1>, LSID:29014 PSID:32421! I: 110927 [IN]CASE Session established to peer: <0000000018EB5475, 1> I: 110933 [IN]CASE Server enabling CASE session setups D: 110939 [IN]SecureSession[0x20003660]: Allocated Type:2 LSID:29015 D: 110945 [SC]Allocated SecureSession (0x20003660) - waiting for Sigma1 msg D: 110952 [IN]Received a duplicate message with MessageCounter:236853354 on exchange 44969r I: 110960 [EM]Received message of type 0x32 with protocolId (0, 0) and MessageCounter:236853354 on exchange 44969r D: 110970 [EM]Found matching exchange: 44969r, Delegate: (nil) D: 110976 [EM]Forcing tx of solitary ack for duplicate MessageCounter:236853354 on exchange 44969r D: 110985 [EM]Sending Standalone Ack for MessageCounter:236853354 on exchange 44969r I: 110992 [IN]Prepared unauthenticated message 0x2002e4c0 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 44969r with MessageCounter:221911340. I: 111007 [IN]Sending unauthenticated msg 0x2002e4c0 with MessageCounter:221911340 to 0x0000000000000000 at monotonic time: 000000000001B19F msec I: 111083 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:236853355 on exchange 44969r D: 111094 [EM]Found matching exchange: 44969r, Delegate: (nil) D: 111099 [EM]Rxd Ack; Removing MessageCounter:221911339 from Retrans Table on exchange 44969r D: 111110 [EM]Removed CHIP MessageCounter:221911339 from RetransTable on exchange 44969r I: 111118 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336026 on exchange 44970r D: 111128 [EM]Handling via exchange: 44970r, Delegate: 0x2000f54c D: 111134 [IM]Received Read request D: 111138 [DMG]IM RH moving to [GeneratingReports] D: 111143 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 111152 [DMG] Cluster 3c, Attribute 0 is dirty D: 111157 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_0000 (expanded=0) D: 111166 [DMG] Sending report (payload has 36 bytes)... I: 111172 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x5 and protocolId (0, 1) on exchange 44970r with MessageCounter:190807796. I: 111186 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807796 to 0x0000000018EB5475 (1) at monotonic time: 000000000001B252 msec D: 111200 [DMG] OnReportConfirm: NumReports = 0 D: 111205 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 111213 [DMG]IM RH moving to [AwaitingDestruction] D: 111218 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 111263 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336027 on exchange 44970r D: 111274 [EM]Found matching exchange: 44970r, Delegate: (nil) D: 111279 [EM]Rxd Ack; Removing MessageCounter:190807796 from Retrans Table on exchange 44970r D: 111290 [EM]Removed CHIP MessageCounter:190807796 from RetransTable on exchange 44970r I: 111298 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336028 on exchange 44971r D: 111308 [EM]Handling via exchange: 44971r, Delegate: 0x2000f54c D: 111314 [IM]Received Read request D: 111318 [DMG]IM RH moving to [GeneratingReports] D: 111323 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 111331 [DMG] Cluster 1d, Attribute 3 is dirty D: 111337 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0003 (expanded=0) D: 111346 [DMG] Sending report (payload has 64 bytes)... I: 111352 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x5 and protocolId (0, 1) on exchange 44971r with MessageCounter:190807797. I: 111366 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807797 to 0x0000000018EB5475 (1) at monotonic time: 000000000001B306 msec D: 111380 [DMG] OnReportConfirm: NumReports = 0 D: 111385 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 111393 [DMG]IM RH moving to [AwaitingDestruction] D: 111398 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 111490 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336029 on exchange 44971r D: 111502 [EM]Found matching exchange: 44971r, Delegate: (nil) D: 111508 [EM]Rxd Ack; Removing MessageCounter:190807797 from Retrans Table on exchange 44971r D: 111516 [EM]Removed CHIP MessageCounter:190807797 from RetransTable on exchange 44971r I: 111525 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336030 on exchange 44972r D: 111535 [EM]Handling via exchange: 44972r, Delegate: 0x2000f54c D: 111541 [IM]Received Read request D: 111545 [DMG]IM RH moving to [GeneratingReports] D: 111549 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 111558 [DMG] Cluster 1d, Attribute 0 is dirty D: 111563 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0000 (expanded=0) D: 111572 [DMG] Sending report (payload has 70 bytes)... I: 111579 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x5 and protocolId (0, 1) on exchange 44972r with MessageCounter:190807798. I: 111593 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807798 to 0x0000000018EB5475 (1) at monotonic time: 000000000001B3E8 msec D: 111607 [DMG] OnReportConfirm: NumReports = 0 D: 111611 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 111619 [DMG]IM RH moving to [AwaitingDestruction] D: 111624 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 111747 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336031 on exchange 44972r D: 111758 [EM]Found matching exchange: 44972r, Delegate: (nil) D: 111763 [EM]Rxd Ack; Removing MessageCounter:190807798 from Retrans Table on exchange 44972r D: 111772 [EM]Removed CHIP MessageCounter:190807798 from RetransTable on exchange 44972r I: 111782 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336032 on exchange 44973r D: 111792 [EM]Handling via exchange: 44973r, Delegate: 0x2000f54c D: 111798 [IM]Received Read request D: 111802 [DMG]IM RH moving to [GeneratingReports] D: 111807 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 111815 [DMG] Cluster 1d, Attribute 1 is dirty D: 111820 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0001 (expanded=0) D: 111830 [DMG] Sending report (payload has 400 bytes)... I: 111838 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x5 and protocolId (0, 1) on exchange 44973r with MessageCounter:190807799. I: 111852 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807799 to 0x0000000018EB5475 (1) at monotonic time: 000000000001B4EC msec D: 111866 [DMG] OnReportConfirm: NumReports = 0 D: 111871 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 111879 [DMG]IM RH moving to [AwaitingDestruction] D: 111884 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 112184 [IN]Received a duplicate message with MessageCounter:139336032 on exchange 44973r I: 112194 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336032 on exchange 44973r D: 112204 [EM]Found matching exchange: 44973r, Delegate: (nil) D: 112210 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336032 on exchange 44973r D: 112219 [EM]Sending Standalone Ack for MessageCounter:139336032 on exchange 44973r I: 112229 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44973r with MessageCounter:190807800. I: 112243 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807800 to 0x0000000018EB5475 (1) at monotonic time: 000000000001B673 msec D: 112257 [EM]Retransmitting MessageCounter:190807799 on exchange 44973r Send Cnt 1 I: 112266 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807799 to 0x0000000018EB5475 (1) at monotonic time: 000000000001B689 msec D: 112280 [CSM]UpdatePeerAddress: No existing OperationalSessionSetup instance found D: 112288 [CTL]OperationalSessionSetup[1:0000000018EB5475]: State change 1 --> 2 I: 112295 [DIS]Resolving 3199E9CFDD25901B:0000000018EB5475 ... I: 112303 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336033 on exchange 44973r D: 112313 [EM]Found matching exchange: 44973r, Delegate: (nil) D: 112320 [EM]Rxd Ack; Removing MessageCounter:190807799 from Retrans Table on exchange 44973r D: 112328 [EM]Removed CHIP MessageCounter:190807799 from RetransTable on exchange 44973r I: 112337 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336034 on exchange 44974r D: 112347 [EM]Handling via exchange: 44974r, Delegate: 0x2000f54c D: 112353 [IM]Received Read request D: 112358 [DMG]IM RH moving to [GeneratingReports] D: 112363 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 112371 [DMG] Cluster 1d, Attribute 2 is dirty D: 112377 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0002 (expanded=0) D: 112386 [DMG] Sending report (payload has 64 bytes)... I: 112392 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x5 and protocolId (0, 1) on exchange 44974r with MessageCounter:190807801. I: 112407 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807801 to 0x0000000018EB5475 (1) at monotonic time: 000000000001B717 msec D: 112421 [DMG] OnReportConfirm: NumReports = 0 D: 112426 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 112434 [DMG]IM RH moving to [AwaitingDestruction] D: 112438 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 112495 [DIS]Checking node lookup status after 200 ms D: 112828 [EM]Retransmitting MessageCounter:190807801 on exchange 44974r Send Cnt 1 I: 112837 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807801 to 0x0000000018EB5475 (1) at monotonic time: 000000000001B8C4 msec D: 113212 [EM]Retransmitting MessageCounter:190807801 on exchange 44974r Send Cnt 2 I: 113220 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807801 to 0x0000000018EB5475 (1) at monotonic time: 000000000001BA44 msec D: 113794 [EM]Retransmitting MessageCounter:190807801 on exchange 44974r Send Cnt 3 I: 113802 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807801 to 0x0000000018EB5475 (1) at monotonic time: 000000000001BC8A msec D: 114674 [EM]Retransmitting MessageCounter:190807801 on exchange 44974r Send Cnt 4 I: 114681 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807801 to 0x0000000018EB5475 (1) at monotonic time: 000000000001BFF9 msec D: 114816 [IN]Received a duplicate message with MessageCounter:139336034 on exchange 44974r I: 114824 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336034 on exchange 44974r D: 114834 [EM]Found matching exchange: 44974r, Delegate: (nil) D: 114840 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336034 on exchange 44974r D: 114849 [EM]Sending Standalone Ack for MessageCounter:139336034 on exchange 44974r I: 114858 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44974r with MessageCounter:190807802. I: 114872 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807802 to 0x0000000018EB5475 (1) at monotonic time: 000000000001C0B8 msec E: 116034 [EM]Failed to Send CHIP MessageCounter:190807801 on exchange 44974r sendCount: 4 max retries: 4 D: 116043 [IN]SecureSession[0x200035a8]: MarkAsDefunct Type:2 LSID:29014 I: 116050 [SC]SecureSession[0x200035a8]: Moving from state 'kActive' --> 'kDefunct' D: 116924 [IN]Received a duplicate message with MessageCounter:139336034 on exchange 44974r I: 116933 [SC]SecureSession[0x200035a8]: Moving from state 'kDefunct' --> 'kActive' I: 116942 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336034 on exchange 44974r D: 116952 [EM]Generating StandaloneAck via exchange: 44974r D: 116960 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336034 on exchange 44974r D: 116969 [EM]Sending Standalone Ack for MessageCounter:139336034 on exchange 44974r I: 116979 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44974r with MessageCounter:190807803. I: 116993 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807803 to 0x0000000018EB5475 (1) at monotonic time: 000000000001C901 msec I: 117010 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336035 on exchange 44974r I: 117021 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336036 on exchange 44975r D: 117031 [EM]Handling via exchange: 44975r, Delegate: 0x2000f54c D: 117038 [IM]Received Read request D: 117042 [DMG]IM RH moving to [GeneratingReports] D: 117047 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 117056 [DMG] Cluster 1d, Attribute 3 is dirty D: 117061 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0003 (expanded=0) D: 117071 [DMG] Sending report (payload has 36 bytes)... I: 117077 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x5 and protocolId (0, 1) on exchange 44975r with MessageCounter:190807804. I: 117091 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807804 to 0x0000000018EB5475 (1) at monotonic time: 000000000001C963 msec D: 117105 [DMG] OnReportConfirm: NumReports = 0 D: 117110 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 117118 [DMG]IM RH moving to [AwaitingDestruction] D: 117123 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 117130 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336037 on exchange 44974r D: 117140 [IN]Received a duplicate message with MessageCounter:139336036 on exchange 44975r I: 117149 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336036 on exchange 44975r D: 117159 [EM]Found matching exchange: 44975r, Delegate: (nil) D: 117166 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336036 on exchange 44975r D: 117174 [EM]Sending Standalone Ack for MessageCounter:139336036 on exchange 44975r I: 117183 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44975r with MessageCounter:190807805. I: 117198 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807805 to 0x0000000018EB5475 (1) at monotonic time: 000000000001C9CD msec D: 117440 [EM]Retransmitting MessageCounter:190807804 on exchange 44975r Send Cnt 1 I: 117448 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807804 to 0x0000000018EB5475 (1) at monotonic time: 000000000001CAC7 msec D: 117802 [EM]Retransmitting MessageCounter:190807804 on exchange 44975r Send Cnt 2 I: 117810 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807804 to 0x0000000018EB5475 (1) at monotonic time: 000000000001CC32 msec D: 118418 [EM]Retransmitting MessageCounter:190807804 on exchange 44975r Send Cnt 3 I: 118426 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807804 to 0x0000000018EB5475 (1) at monotonic time: 000000000001CE9A msec I: 119225 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336038 on exchange 44974r D: 119249 [IN]Received a duplicate message with MessageCounter:139336036 on exchange 44975r I: 119258 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336036 on exchange 44975r D: 119270 [EM]Found matching exchange: 44975r, Delegate: (nil) D: 119276 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336036 on exchange 44975r D: 119286 [EM]Sending Standalone Ack for MessageCounter:139336036 on exchange 44975r I: 119294 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44975r with MessageCounter:190807806. I: 119311 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807806 to 0x0000000018EB5475 (1) at monotonic time: 000000000001D20E msec D: 119327 [IN]Received a duplicate message with MessageCounter:139336036 on exchange 44975r I: 119336 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336036 on exchange 44975r D: 119346 [EM]Found matching exchange: 44975r, Delegate: (nil) D: 119352 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336036 on exchange 44975r D: 119363 [EM]Sending Standalone Ack for MessageCounter:139336036 on exchange 44975r I: 119371 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44975r with MessageCounter:190807807. I: 119387 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807807 to 0x0000000018EB5475 (1) at monotonic time: 000000000001D25B msec D: 119403 [EM]Retransmitting MessageCounter:190807804 on exchange 44975r Send Cnt 4 I: 119412 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807804 to 0x0000000018EB5475 (1) at monotonic time: 000000000001D273 msec I: 119427 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336039 on exchange 44974r D: 119438 [IN]Received a duplicate message with MessageCounter:139336036 on exchange 44975r I: 119446 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336036 on exchange 44975r D: 119457 [EM]Found matching exchange: 44975r, Delegate: (nil) D: 119463 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336036 on exchange 44975r D: 119472 [EM]Sending Standalone Ack for MessageCounter:139336036 on exchange 44975r I: 119480 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44975r with MessageCounter:190807808. I: 119495 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807808 to 0x0000000018EB5475 (1) at monotonic time: 000000000001D2C7 msec I: 119509 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336040 on exchange 44975r D: 119520 [EM]Found matching exchange: 44975r, Delegate: (nil) D: 119526 [EM]Rxd Ack; Removing MessageCounter:190807804 from Retrans Table on exchange 44975r D: 119534 [EM]Removed CHIP MessageCounter:190807804 from RetransTable on exchange 44975r I: 119544 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336041 on exchange 44976r D: 119554 [EM]Handling via exchange: 44976r, Delegate: 0x2000f54c D: 119560 [IM]Received Read request D: 119564 [DMG]IM RH moving to [GeneratingReports] D: 119569 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 119578 [DMG] Cluster 1d, Attribute 0 is dirty D: 119584 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0000 (expanded=0) D: 119593 [DMG] Sending report (payload has 71 bytes)... I: 119600 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x5 and protocolId (0, 1) on exchange 44976r with MessageCounter:190807809. I: 119615 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807809 to 0x0000000018EB5475 (1) at monotonic time: 000000000001D33E msec D: 119628 [DMG] OnReportConfirm: NumReports = 0 D: 119633 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 119641 [DMG]IM RH moving to [AwaitingDestruction] D: 119646 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 119652 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336042 on exchange 44975r D: 119664 [IN]Received a duplicate message with MessageCounter:139336041 on exchange 44976r I: 119672 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336041 on exchange 44976r D: 119683 [EM]Found matching exchange: 44976r, Delegate: (nil) D: 119689 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336041 on exchange 44976r D: 119698 [EM]Sending Standalone Ack for MessageCounter:139336041 on exchange 44976r I: 119707 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44976r with MessageCounter:190807810. I: 119722 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807810 to 0x0000000018EB5475 (1) at monotonic time: 000000000001D3A9 msec D: 119983 [EM]Retransmitting MessageCounter:190807809 on exchange 44976r Send Cnt 1 I: 119991 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807809 to 0x0000000018EB5475 (1) at monotonic time: 000000000001D4B7 msec D: 120398 [EM]Retransmitting MessageCounter:190807809 on exchange 44976r Send Cnt 2 I: 120406 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807809 to 0x0000000018EB5475 (1) at monotonic time: 000000000001D656 msec E: 120635 [BLE]ack recv timeout, closing ep 0x20011b80 D: 120640 [IN]Clearing BLE pending packets. I: 120644 [BLE]Releasing end point's BLE connection back to application. I: 120651 [DL]Closing BLE GATT connection (ConnId 00) D: 121035 [EM]Retransmitting MessageCounter:190807809 on exchange 44976r Send Cnt 3 I: 121043 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807809 to 0x0000000018EB5475 (1) at monotonic time: 000000000001D8D3 msec I: 121731 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336043 on exchange 44975r D: 121744 [IN]Received a duplicate message with MessageCounter:139336041 on exchange 44976r I: 121753 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336041 on exchange 44976r D: 121764 [EM]Found matching exchange: 44976r, Delegate: (nil) D: 121770 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336041 on exchange 44976r D: 121781 [EM]Sending Standalone Ack for MessageCounter:139336041 on exchange 44976r I: 121789 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44976r with MessageCounter:190807811. I: 121805 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807811 to 0x0000000018EB5475 (1) at monotonic time: 000000000001DBCD msec I: 121820 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336044 on exchange 44975r D: 121832 [IN]Received a duplicate message with MessageCounter:139336041 on exchange 44976r I: 121841 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336041 on exchange 44976r D: 121853 [EM]Found matching exchange: 44976r, Delegate: (nil) D: 121859 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336041 on exchange 44976r D: 121870 [EM]Sending Standalone Ack for MessageCounter:139336041 on exchange 44976r I: 121878 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44976r with MessageCounter:190807812. I: 121894 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807812 to 0x0000000018EB5475 (1) at monotonic time: 000000000001DC26 msec D: 121910 [IN]Received a duplicate message with MessageCounter:139336041 on exchange 44976r I: 121919 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336041 on exchange 44976r D: 121929 [EM]Found matching exchange: 44976r, Delegate: (nil) D: 121935 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336041 on exchange 44976r D: 121944 [EM]Sending Standalone Ack for MessageCounter:139336041 on exchange 44976r I: 121953 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44976r with MessageCounter:190807813. I: 121967 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807813 to 0x0000000018EB5475 (1) at monotonic time: 000000000001DC6F msec I: 121982 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336045 on exchange 44975r I: 121994 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336046 on exchange 44976r D: 122004 [EM]Found matching exchange: 44976r, Delegate: (nil) D: 122012 [EM]Rxd Ack; Removing MessageCounter:190807809 from Retrans Table on exchange 44976r D: 122020 [EM]Removed CHIP MessageCounter:190807809 from RetransTable on exchange 44976r I: 122029 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336047 on exchange 44977r D: 122042 [EM]Handling via exchange: 44977r, Delegate: 0x2000f54c D: 122048 [IM]Received Read request D: 122054 [DMG]IM RH moving to [GeneratingReports] D: 122059 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 122068 [DMG] Cluster 1d, Attribute 1 is dirty D: 122074 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0001 (expanded=0) D: 122083 [DMG] Sending report (payload has 176 bytes)... I: 122090 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x5 and protocolId (0, 1) on exchange 44977r with MessageCounter:190807814. I: 122105 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807814 to 0x0000000018EB5475 (1) at monotonic time: 000000000001DCF9 msec D: 122119 [DMG] OnReportConfirm: NumReports = 0 D: 122124 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 122132 [DMG]IM RH moving to [AwaitingDestruction] D: 122137 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 122143 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336048 on exchange 44976r D: 122155 [IN]Received a duplicate message with MessageCounter:139336047 on exchange 44977r I: 122164 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336047 on exchange 44977r D: 122174 [EM]Found matching exchange: 44977r, Delegate: (nil) D: 122180 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336047 on exchange 44977r D: 122190 [EM]Sending Standalone Ack for MessageCounter:139336047 on exchange 44977r I: 122198 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44977r with MessageCounter:190807815. I: 122213 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807815 to 0x0000000018EB5475 (1) at monotonic time: 000000000001DD65 msec I: 122228 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336049 on exchange 44976r D: 122482 [EM]Retransmitting MessageCounter:190807814 on exchange 44977r Send Cnt 1 I: 122490 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807814 to 0x0000000018EB5475 (1) at monotonic time: 000000000001DE7A msec D: 122852 [EM]Retransmitting MessageCounter:190807814 on exchange 44977r Send Cnt 2 I: 122860 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807814 to 0x0000000018EB5475 (1) at monotonic time: 000000000001DFEC msec D: 123481 [EM]Retransmitting MessageCounter:190807814 on exchange 44977r Send Cnt 3 I: 123489 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807814 to 0x0000000018EB5475 (1) at monotonic time: 000000000001E261 msec D: 124269 [IN]Received a duplicate message with MessageCounter:139336047 on exchange 44977r I: 124277 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336047 on exchange 44977r D: 124288 [EM]Found matching exchange: 44977r, Delegate: (nil) D: 124293 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336047 on exchange 44977r D: 124304 [EM]Sending Standalone Ack for MessageCounter:139336047 on exchange 44977r I: 124314 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44977r with MessageCounter:190807816. I: 124331 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807816 to 0x0000000018EB5475 (1) at monotonic time: 000000000001E5AB msec I: 124348 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336050 on exchange 44976r D: 124359 [IN]Received a duplicate message with MessageCounter:139336047 on exchange 44977r I: 124368 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336047 on exchange 44977r D: 124378 [EM]Found matching exchange: 44977r, Delegate: (nil) D: 124384 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336047 on exchange 44977r D: 124395 [EM]Sending Standalone Ack for MessageCounter:139336047 on exchange 44977r I: 124406 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44977r with MessageCounter:190807817. I: 124422 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807817 to 0x0000000018EB5475 (1) at monotonic time: 000000000001E606 msec D: 124437 [IN]Received a duplicate message with MessageCounter:139336047 on exchange 44977r I: 124446 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336047 on exchange 44977r D: 124457 [EM]Found matching exchange: 44977r, Delegate: (nil) D: 124462 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336047 on exchange 44977r D: 124471 [EM]Sending Standalone Ack for MessageCounter:139336047 on exchange 44977r I: 124480 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44977r with MessageCounter:190807818. I: 124495 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807818 to 0x0000000018EB5475 (1) at monotonic time: 000000000001E64E msec I: 124509 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336051 on exchange 44977r D: 124519 [EM]Found matching exchange: 44977r, Delegate: (nil) D: 124525 [EM]Rxd Ack; Removing MessageCounter:190807814 from Retrans Table on exchange 44977r D: 124534 [EM]Removed CHIP MessageCounter:190807814 from RetransTable on exchange 44977r I: 124544 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336052 on exchange 44978r D: 124555 [EM]Handling via exchange: 44978r, Delegate: 0x2000f54c D: 124561 [IM]Received Read request D: 124565 [DMG]IM RH moving to [GeneratingReports] D: 124570 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 124579 [DMG] Cluster 1d, Attribute 2 is dirty D: 124585 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0002 (expanded=0) D: 124594 [DMG] Sending report (payload has 64 bytes)... I: 124601 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x5 and protocolId (0, 1) on exchange 44978r with MessageCounter:190807819. I: 124615 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807819 to 0x0000000018EB5475 (1) at monotonic time: 000000000001E6C7 msec D: 124630 [DMG] OnReportConfirm: NumReports = 0 D: 124637 [DMG] ReportsInFlight = 0 with readHandler 0, RE has no more messages D: 124645 [DMG]IM RH moving to [AwaitingDestruction] D: 124649 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 124657 [IN]Received a duplicate message with MessageCounter:139336052 on exchange 44978r I: 124665 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336052 on exchange 44978r D: 124676 [EM]Found matching exchange: 44978r, Delegate: (nil) D: 124682 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336052 on exchange 44978r D: 124691 [EM]Sending Standalone Ack for MessageCounter:139336052 on exchange 44978r I: 124700 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44978r with MessageCounter:190807820. I: 124714 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807820 to 0x0000000018EB5475 (1) at monotonic time: 000000000001E72A msec I: 124729 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336053 on exchange 44977r D: 125022 [EM]Retransmitting MessageCounter:190807819 on exchange 44978r Send Cnt 1 I: 125030 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807819 to 0x0000000018EB5475 (1) at monotonic time: 000000000001E866 msec D: 125428 [EM]Retransmitting MessageCounter:190807819 on exchange 44978r Send Cnt 2 I: 125436 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807819 to 0x0000000018EB5475 (1) at monotonic time: 000000000001E9FB msec D: 125986 [EM]Retransmitting MessageCounter:190807819 on exchange 44978r Send Cnt 3 I: 125994 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807819 to 0x0000000018EB5475 (1) at monotonic time: 000000000001EC2A msec I: 126649 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336054 on exchange 44977r D: 126669 [IN]Received a duplicate message with MessageCounter:139336052 on exchange 44978r I: 126678 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336052 on exchange 44978r D: 126690 [EM]Found matching exchange: 44978r, Delegate: (nil) D: 126695 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336052 on exchange 44978r D: 126706 [EM]Sending Standalone Ack for MessageCounter:139336052 on exchange 44978r I: 126714 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44978r with MessageCounter:190807821. I: 126729 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807821 to 0x0000000018EB5475 (1) at monotonic time: 000000000001EF08 msec I: 126743 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336055 on exchange 44977r D: 126755 [IN]Received a duplicate message with MessageCounter:139336052 on exchange 44978r I: 126764 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336052 on exchange 44978r D: 126778 [EM]Found matching exchange: 44978r, Delegate: (nil) D: 126783 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336052 on exchange 44978r D: 126794 [EM]Sending Standalone Ack for MessageCounter:139336052 on exchange 44978r I: 126802 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44978r with MessageCounter:190807822. I: 126818 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807822 to 0x0000000018EB5475 (1) at monotonic time: 000000000001EF62 msec D: 126835 [IN]Received a duplicate message with MessageCounter:139336052 on exchange 44978r I: 126844 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:139336052 on exchange 44978r D: 126854 [EM]Found matching exchange: 44978r, Delegate: (nil) D: 126860 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336052 on exchange 44978r D: 126869 [EM]Sending Standalone Ack for MessageCounter:139336052 on exchange 44978r I: 126877 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44978r with MessageCounter:190807823. I: 126892 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807823 to 0x0000000018EB5475 (1) at monotonic time: 000000000001EFAB msec I: 126906 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336056 on exchange 44978r D: 126916 [EM]Found matching exchange: 44978r, Delegate: (nil) D: 126923 [EM]Rxd Ack; Removing MessageCounter:190807819 from Retrans Table on exchange 44978r D: 126932 [EM]Removed CHIP MessageCounter:190807819 from RetransTable on exchange 44978r I: 126940 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336057 on exchange 44978r I: 126951 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336058 on exchange 44978r I: 126962 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336059 on exchange 44978r I: 127295 [DIS]Checking node lookup status after 15000 ms E: 127301 [DIS]OperationalSessionSetup[1:0000000018EB5475]: operational discovery failed: 32 I: 127334 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:139336060 on exchange 44979r D: 127344 [EM]Handling via exchange: 44979r, Delegate: 0x2000f54c D: 127350 [DMG]Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0004 I: 127358 [FS]GeneralCommissioning: Received CommissioningComplete I: 127465 [FP]Metadata for Fabric 0x1 persisted to storage. I: 127660 [TS]Committing Last Known Good Time to storage: 2022-10-17T10:52:00 I: 127742 [ZCL]OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0x3199E9CFDD25901B, FabricId EB8133EB1A5D3D4C, NodeId 00000000B50FD39C, VendorId 0x6006 I: 127781 [FS]GeneralCommissioning: Successfully commited pending fabric data I: 127788 [FS]Fail-safe cleanly disarmed D: 127792 [DMG]Endpoint 0, Cluster 0x0000_0030 update version to aff38dc1 D: 127799 [DMG]Command handler moving to [ Preparing] D: 127803 [DMG]Command handler moving to [AddingComm] D: 127808 [DMG]Command handler moving to [AddedComma] D: 127813 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 127821 [IN]Prepared secure message 0x20005124 to 0x0000000018EB5475 (1) of type 0x9 and protocolId (0, 1) on exchange 44979r with MessageCounter:190807824. I: 127835 [IN]Sending encrypted msg 0x20005124 with MessageCounter:190807824 to 0x0000000018EB5475 (1) at monotonic time: 000000000001F35B msec D: 127849 [DMG]Command handler moving to [CommandSen] D: 127854 [DMG]Command handler moving to [AwaitingDe] I: 127859 [SWU]Device commissioned, schedule a default provider query I: 127866 [SVR]Commissioning completed successfully I: 127871 [DIS]Updating services using commissioning mode 0 D: 127876 [DL]Using Thread extended MAC for hostname. I: 127882 [DIS]Advertise operational node 3199E9CFDD25901B-00000000B50FD39C D: 127889 [DL]Using Thread extended MAC for hostname. I: 127895 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3848/15 cm=0 D: 127905 [IN]Expiring all PASE sessions D: 127909 [IN]SecureSession[0x200034f0]: MarkForEviction Type:1 LSID:29013 I: 127916 [SC]SecureSession[0x200034f0]: Moving from state 'kActive' --> 'kPendingEviction' D: 127925 [IN]SecureSession[0x200034f0]: Released - Type:1 LSID:29013 D: 127932 [ZCL]Commissioning complete, notify platform driver to persist network credentials. E: 127962 [DL]Long dispatch time: 103 ms, for event type 32781 D: 127968 [IN]Received a duplicate message with MessageCounter:139336060 on exchange 44979r I: 127977 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:139336060 on exchange 44979r D: 127987 [EM]Found matching exchange: 44979r, Delegate: (nil) D: 127992 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336060 on exchange 44979r D: 128001 [EM]Sending Standalone Ack for MessageCounter:139336060 on exchange 44979r I: 128009 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44979r with MessageCounter:190807825. I: 128026 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807825 to 0x0000000018EB5475 (1) at monotonic time: 000000000001F41A msec D: 128041 [IN]Received a duplicate message with MessageCounter:139336060 on exchange 44979r I: 128049 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:139336060 on exchange 44979r D: 128061 [EM]Found matching exchange: 44979r, Delegate: (nil) D: 128068 [EM]Forcing tx of solitary ack for duplicate MessageCounter:139336060 on exchange 44979r D: 128076 [EM]Sending Standalone Ack for MessageCounter:139336060 on exchange 44979r I: 128085 [IN]Prepared secure message 0x2002e4d0 to 0x0000000018EB5475 (1) of type 0x10 and protocolId (0, 0) on exchange 44979r with MessageCounter:190807826. I: 128100 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:190807826 to 0x0000000018EB5475 (1) at monotonic time: 000000000001F463 msec I: 128114 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:139336061 on exchange 44979r D: 128124 [EM]Found matching exchange: 44979r, Delegate: (nil) D: 128131 [EM]Rxd Ack; Removing MessageCounter:190807824 from Retrans Table on exchange 44979r D: 128139 [EM]Removed CHIP MessageCounter:190807824 from RetransTable on exchange 44979r I: 140091 [EM]Received message of type 0x30 with protocolId (0, 0) and MessageCounter:19698706 on exchange 2106r D: 140101 [EM]Handling via exchange: 2106r, Delegate: 0x20004160 I: 140107 [IN]CASE Server received Sigma1 message. Starting handshake. EC 0x20004e70 I: 140114 [IN]CASE Server disabling CASE session setups D: 140119 [EM]Sending Standalone Ack for MessageCounter:19698706 on exchange 2106r I: 140127 [IN]Prepared unauthenticated message 0x2002e4d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 2106r with MessageCounter:221911341. I: 140142 [IN]Sending unauthenticated msg 0x2002e4d8 with MessageCounter:221911341 to 0x0000000000000000 at monotonic time: 000000000002236E msec D: 140156 [EM]Flushed pending ack for MessageCounter:19698706 on exchange 2106r I: 140163 [SC]Received Sigma1 msg D: 140166 [SC]Found MRP parameters in the message D: 140171 [SC]Peer assigned session key ID 2197 I: 140179 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x00000000B50FD39C D: 140410 [SC]Including MRP parameters I: 140414 [IN]Prepared unauthenticated message 0x20005124 to 0x0000000000000000 (0) of type 0x31 and protocolId (0, 0) on exchange 2106r with MessageCounter:221911342. I: 140429 [IN]Sending unauthenticated msg 0x20005124 with MessageCounter:221911342 to 0x0000000000000000 at monotonic time: 000000000002248D msec I: 140444 [SC]Sent Sigma2 msg I: 140565 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:19698707 on exchange 2106r D: 140575 [EM]Found matching exchange: 2106r, Delegate: 0x2000417c D: 140581 [EM]Rxd Ack; Removing MessageCounter:221911342 from Retrans Table on exchange 2106r D: 140589 [EM]Removed CHIP MessageCounter:221911342 from RetransTable on exchange 2106r I: 140759 [EM]Received message of type 0x32 with protocolId (0, 0) and MessageCounter:19698708 on exchange 2106r D: 140770 [EM]Found matching exchange: 2106r, Delegate: 0x2000417c D: 140776 [EM]CHIP MessageCounter:221911342 not in RetransTable on exchange 2106r D: 140783 [EM]Sending Standalone Ack for MessageCounter:19698708 on exchange 2106r I: 140790 [IN]Prepared unauthenticated message 0x2002e4c0 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 2106r with MessageCounter:221911343. I: 140805 [IN]Sending unauthenticated msg 0x2002e4c0 with MessageCounter:221911343 to 0x0000000000000000 at monotonic time: 0000000000022605 msec D: 140819 [EM]Flushed pending ack for MessageCounter:19698708 on exchange 2106r I: 140826 [SC]Received Sigma3 msg E: 140835 [SC]The device does not support GetClock_RealTimeMS() API: 3. Falling back to Last Known Good UTC Time D: 141280 [SC]Sending status report. Protocol code 0, exchange 2106 I: 141286 [IN]Prepared unauthenticated message 0x20005124 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 2106r with MessageCounter:221911344. I: 141301 [IN]Sending unauthenticated msg 0x20005124 with MessageCounter:221911344 to 0x0000000000000000 at monotonic time: 00000000000227F5 msec I: 141316 [SC]SecureSession[0x20003660]: Moving from state 'kEstablishing' --> 'kActive' D: 141325 [IN]SecureSession[0x20003660]: Activated - Type:2 LSID:29015 D: 141333 [IN]New secure session activated for device <00000000E0A03CBA, 1>, LSID:29015 PSID:2197! I: 141342 [IN]CASE Session established to peer: <00000000E0A03CBA, 1> I: 141348 [IN]CASE Server enabling CASE session setups D: 141355 [IN]SecureSession[0x200034f0]: Allocated Type:2 LSID:29016 D: 141361 [SC]Allocated SecureSession (0x200034f0) - waiting for Sigma1 msg I: 141369 [EM]Received message of type 0x3 with protocolId (0, 1) and MessageCounter:46575687 on exchange 2107r D: 141379 [EM]Handling via exchange: 2107r, Delegate: 0x2000f54c D: 141384 [IM]Received Subscribe request I: 141389 [DMG]Final negotiated min/max parameters: Min = 0s, Max = 120s D: 141395 [DMG]IM RH moving to [GeneratingReports] D: 141400 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 141409 [DMG] Cluster 4, Attribute 0 is dirty D: 141414 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 141423 [DMG] Cluster 4, Attribute fffc is dirty D: 141428 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 141437 [DMG] Cluster 4, Attribute fffd is dirty D: 141443 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 141452 [DMG] Cluster 4, Attribute fff8 is dirty D: 141457 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 141466 [DMG] Cluster 4, Attribute fff9 is dirty D: 141471 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 141481 [DMG] Cluster 4, Attribute fffb is dirty D: 141486 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 141495 [DMG] Cluster 1d, Attribute 0 is dirty D: 141500 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 141510 [DMG] Cluster 1d, Attribute 1 is dirty D: 141515 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0001 (expanded=1) E: 141524 [DMG]Error retrieving data from clusterId: 0x0000_001D, err = b D: 141531 [DMG] We cannot put more chunks into this report. Enable chunking. D: 141538 [DMG] Sending report (payload has 984 bytes)... D: 141544 [DMG]IM RH moving to [AwaitingReportResponse] I: 141554 [IN]Prepared secure message 0x2000513c to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473408. I: 141568 [IN]Sending encrypted msg 0x2000513c with MessageCounter:132473408 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000228FF msec D: 141582 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages I: 141590 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:19698709 on exchange 2106r D: 141601 [EM]Found matching exchange: 2106r, Delegate: (nil) D: 141606 [EM]Rxd Ack; Removing MessageCounter:221911344 from Retrans Table on exchange 2106r D: 141615 [EM]Removed CHIP MessageCounter:221911344 from RetransTable on exchange 2106r D: 141722 [IN]Received a duplicate message with MessageCounter:46575687 on exchange 2107r I: 141731 [EM]Received message of type 0x3 with protocolId (0, 1) and MessageCounter:46575687 on exchange 2107r D: 141741 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 141747 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575687 on exchange 2107r D: 141756 [EM]Sending Standalone Ack for MessageCounter:46575687 on exchange 2107r I: 141765 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473409. I: 141779 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473409 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000229D3 msec I: 141856 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575688 on exchange 2107r D: 141866 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 141873 [EM]Rxd Ack; Removing MessageCounter:132473408 from Retrans Table on exchange 2107r D: 141882 [EM]Removed CHIP MessageCounter:132473408 from RetransTable on exchange 2107r I: 141890 [IM]Received status response, status is 0x00 D: 141895 [DMG] OnReportConfirm: NumReports = 0 D: 141900 [DMG]IM RH moving to [GeneratingReports] D: 141905 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 141913 [DMG] Cluster 1d, Attribute 1 is dirty D: 141919 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 141928 [DMG] Cluster 1d, Attribute 2 is dirty D: 141934 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 141943 [DMG] Cluster 1d, Attribute 3 is dirty D: 141948 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 141957 [DMG] Cluster 1d, Attribute fffc is dirty D: 141962 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 141971 [DMG] Cluster 1d, Attribute fffd is dirty D: 141977 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 141986 [DMG] Cluster 1d, Attribute fff8 is dirty D: 141991 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 142000 [DMG] Cluster 1d, Attribute fff9 is dirty D: 142006 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 142015 [DMG] Cluster 1d, Attribute fffb is dirty D: 142020 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 142030 [DMG] Cluster 1f, Attribute 0 is dirty D: 142035 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 142044 [DMG] Cluster 1f, Attribute 1 is dirty D: 142049 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 142059 [DMG] Cluster 1f, Attribute 2 is dirty D: 142064 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 142073 [DMG] Cluster 1f, Attribute 3 is dirty D: 142078 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 142087 [DMG] Cluster 1f, Attribute 4 is dirty D: 142093 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_0004 (expanded=1) D: 142102 [DMG] Cluster 1f, Attribute fffc is dirty D: 142107 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 142116 [DMG] Cluster 1f, Attribute fffd is dirty D: 142122 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 142131 [DMG] Cluster 1f, Attribute fff8 is dirty D: 142136 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 142145 [DMG] Cluster 1f, Attribute fff9 is dirty D: 142150 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 142159 [DMG] Cluster 1f, Attribute fffb is dirty D: 142165 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) E: 142174 [DMG]Error retrieving data from clusterId: 0x0000_001F, err = b D: 142180 [DMG] We cannot put more chunks into this report. Enable chunking. D: 142188 [DMG] Sending report (payload has 982 bytes)... D: 142194 [DMG]IM RH moving to [AwaitingReportResponse] I: 142203 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473410. I: 142218 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473410 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000022B89 msec D: 142232 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 142242 [IN]Received a duplicate message with MessageCounter:46575688 on exchange 2107r I: 142251 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575688 on exchange 2107r D: 142261 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 142267 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575688 on exchange 2107r D: 142276 [EM]Sending Standalone Ack for MessageCounter:46575688 on exchange 2107r I: 142285 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473411. I: 142300 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473411 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000022BDC msec I: 142436 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575689 on exchange 2107r D: 142446 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 142452 [EM]Rxd Ack; Removing MessageCounter:132473410 from Retrans Table on exchange 2107r D: 142460 [EM]Removed CHIP MessageCounter:132473410 from RetransTable on exchange 2107r I: 142468 [IM]Received status response, status is 0x00 D: 142473 [DMG] OnReportConfirm: NumReports = 0 D: 142477 [DMG]IM RH moving to [GeneratingReports] D: 142482 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 142491 [DMG] Cluster 1f, Attribute fffb is dirty D: 142496 [DMG]Reading attribute: Cluster=0x0000_001F Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 142506 [DMG] Cluster 28, Attribute 0 is dirty D: 142511 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 142520 [DMG] Cluster 28, Attribute 1 is dirty D: 142525 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 142534 [DMG] Cluster 28, Attribute 2 is dirty D: 142539 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 142548 [DMG] Cluster 28, Attribute 3 is dirty D: 142553 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 142562 [DMG] Cluster 28, Attribute 4 is dirty D: 142568 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0004 (expanded=1) D: 142577 [DMG] Cluster 28, Attribute 5 is dirty D: 142582 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0005 (expanded=1) D: 142591 [DMG] Cluster 28, Attribute 6 is dirty D: 142596 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0006 (expanded=1) D: 142606 [DMG] Cluster 28, Attribute 7 is dirty D: 142611 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0007 (expanded=1) D: 142621 [DMG] Cluster 28, Attribute 8 is dirty D: 142626 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0008 (expanded=1) D: 142635 [DMG] Cluster 28, Attribute 9 is dirty D: 142640 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0009 (expanded=1) D: 142649 [DMG] Cluster 28, Attribute a is dirty D: 142654 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000A (expanded=1) D: 142663 [DMG] Cluster 28, Attribute b is dirty D: 142669 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000B (expanded=1) D: 142679 [DMG] Cluster 28, Attribute c is dirty D: 142684 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000C (expanded=1) D: 142693 [DMG] Cluster 28, Attribute d is dirty D: 142698 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000D (expanded=1) D: 142707 [DMG] Cluster 28, Attribute e is dirty D: 142712 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000E (expanded=1) D: 142721 [DMG] Cluster 28, Attribute f is dirty D: 142727 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_000F (expanded=1) D: 142737 [DMG] Cluster 28, Attribute 10 is dirty D: 142742 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0010 (expanded=1) D: 142751 [DMG] Cluster 28, Attribute 11 is dirty D: 142756 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0011 (expanded=1) D: 142765 [DMG] Cluster 28, Attribute 12 is dirty D: 142770 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0012 (expanded=1) D: 142780 [DMG] Cluster 28, Attribute 13 is dirty D: 142786 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0013 (expanded=1) D: 142796 [DMG] Cluster 28, Attribute fffc is dirty D: 142802 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 142811 [DMG] Cluster 28, Attribute fffd is dirty D: 142816 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 142825 [DMG] Cluster 28, Attribute fff8 is dirty D: 142831 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 142840 [DMG] Cluster 28, Attribute fff9 is dirty D: 142845 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) E: 142854 [DMG]Error retrieving data from clusterId: 0x0000_0028, err = b D: 142861 [DMG] We cannot put more chunks into this report. Enable chunking. D: 142869 [DMG] Sending report (payload has 992 bytes)... D: 142874 [DMG]IM RH moving to [AwaitingReportResponse] I: 142884 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473412. I: 142898 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473412 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000022E32 msec D: 142913 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 142921 [IN]Received a duplicate message with MessageCounter:46575689 on exchange 2107r I: 142930 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575689 on exchange 2107r D: 142940 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 142947 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575689 on exchange 2107r D: 142955 [EM]Sending Standalone Ack for MessageCounter:46575689 on exchange 2107r I: 142964 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473413. I: 142978 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473413 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000022E82 msec I: 143078 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575690 on exchange 2107r D: 143088 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 143094 [EM]Rxd Ack; Removing MessageCounter:132473412 from Retrans Table on exchange 2107r D: 143102 [EM]Removed CHIP MessageCounter:132473412 from RetransTable on exchange 2107r I: 143110 [IM]Received status response, status is 0x00 D: 143115 [DMG] OnReportConfirm: NumReports = 0 D: 143120 [DMG]IM RH moving to [GeneratingReports] D: 143124 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 143133 [DMG] Cluster 28, Attribute fff9 is dirty D: 143138 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 143147 [DMG] Cluster 28, Attribute fffb is dirty D: 143153 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 143163 [DMG] Cluster 2a, Attribute 0 is dirty D: 143168 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 143177 [DMG] Cluster 2a, Attribute 1 is dirty D: 143182 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 143191 [DMG] Cluster 2a, Attribute 2 is dirty D: 143196 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 143205 [DMG] Cluster 2a, Attribute 3 is dirty D: 143210 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 143220 [DMG] Cluster 2a, Attribute fffc is dirty D: 143225 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 143234 [DMG] Cluster 2a, Attribute fffd is dirty D: 143239 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 143249 [DMG] Cluster 2a, Attribute fff8 is dirty D: 143254 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 143263 [DMG] Cluster 2a, Attribute fff9 is dirty D: 143268 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) E: 143277 [DMG]Error retrieving data from clusterId: 0x0000_002A, err = b D: 143284 [DMG] We cannot put more chunks into this report. Enable chunking. D: 143291 [DMG] Sending report (payload has 983 bytes)... D: 143297 [DMG]IM RH moving to [AwaitingReportResponse] I: 143307 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473414. I: 143321 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473414 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000022FD9 msec D: 143336 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 143454 [IN]Received a duplicate message with MessageCounter:46575690 on exchange 2107r I: 143463 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575690 on exchange 2107r D: 143474 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 143480 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575690 on exchange 2107r D: 143489 [EM]Sending Standalone Ack for MessageCounter:46575690 on exchange 2107r I: 143497 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473415. I: 143512 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473415 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023098 msec I: 143527 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575691 on exchange 2107r D: 143537 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 143543 [EM]Rxd Ack; Removing MessageCounter:132473414 from Retrans Table on exchange 2107r D: 143552 [EM]Removed CHIP MessageCounter:132473414 from RetransTable on exchange 2107r I: 143560 [IM]Received status response, status is 0x00 D: 143565 [DMG] OnReportConfirm: NumReports = 0 D: 143569 [DMG]IM RH moving to [GeneratingReports] D: 143574 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 143583 [DMG] Cluster 2a, Attribute fff9 is dirty D: 143588 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 143597 [DMG] Cluster 2a, Attribute fffb is dirty D: 143603 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 143612 [DMG] Cluster 30, Attribute 0 is dirty D: 143617 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 143626 [DMG] Cluster 30, Attribute 1 is dirty D: 143631 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 143640 [DMG] Cluster 30, Attribute 2 is dirty D: 143646 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 143655 [DMG] Cluster 30, Attribute 3 is dirty D: 143660 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 143669 [DMG] Cluster 30, Attribute 4 is dirty D: 143674 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0004 (expanded=1) D: 143683 [DMG] Cluster 30, Attribute fffc is dirty D: 143688 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 143697 [DMG] Cluster 30, Attribute fffd is dirty D: 143703 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 143712 [DMG] Cluster 30, Attribute fff8 is dirty D: 143717 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 143727 [DMG] Cluster 30, Attribute fff9 is dirty D: 143732 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 143741 [DMG] Cluster 30, Attribute fffb is dirty D: 143746 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) E: 143756 [DMG]Error retrieving data from clusterId: 0x0000_0030, err = b D: 143762 [DMG] We cannot put more chunks into this report. Enable chunking. D: 143770 [DMG] Sending report (payload has 985 bytes)... D: 143775 [DMG]IM RH moving to [AwaitingReportResponse] I: 143785 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473416. I: 143799 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473416 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000231B7 msec D: 143814 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 143887 [IN]Received a duplicate message with MessageCounter:46575691 on exchange 2107r I: 143896 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575691 on exchange 2107r D: 143906 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 143913 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575691 on exchange 2107r D: 143922 [EM]Sending Standalone Ack for MessageCounter:46575691 on exchange 2107r I: 143930 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473417. I: 143945 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473417 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023248 msec I: 143985 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575692 on exchange 2107r D: 143995 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 144001 [EM]Rxd Ack; Removing MessageCounter:132473416 from Retrans Table on exchange 2107r D: 144009 [EM]Removed CHIP MessageCounter:132473416 from RetransTable on exchange 2107r I: 144017 [IM]Received status response, status is 0x00 D: 144022 [DMG] OnReportConfirm: NumReports = 0 D: 144027 [DMG]IM RH moving to [GeneratingReports] D: 144031 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 144040 [DMG] Cluster 30, Attribute fffb is dirty D: 144046 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 144055 [DMG] Cluster 31, Attribute 0 is dirty D: 144060 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 144069 [DMG] Cluster 31, Attribute 1 is dirty D: 144074 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 144085 [DMG] Cluster 31, Attribute 2 is dirty D: 144090 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 144099 [DMG] Cluster 31, Attribute 3 is dirty D: 144104 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 144113 [DMG] Cluster 31, Attribute 4 is dirty D: 144118 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0004 (expanded=1) D: 144127 [DMG] Cluster 31, Attribute 5 is dirty D: 144132 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0005 (expanded=1) D: 144141 [DMG] Cluster 31, Attribute 6 is dirty D: 144147 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0006 (expanded=1) D: 144156 [DMG] Cluster 31, Attribute 7 is dirty D: 144161 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0007 (expanded=1) D: 144170 [DMG] Cluster 31, Attribute fffc is dirty D: 144175 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 144184 [DMG] Cluster 31, Attribute fffd is dirty D: 144190 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 144199 [DMG] Cluster 31, Attribute fff8 is dirty D: 144204 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 144213 [DMG] Cluster 31, Attribute fff9 is dirty D: 144219 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 144228 [DMG] Cluster 31, Attribute fffb is dirty D: 144233 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) E: 144243 [DMG]Error retrieving data from clusterId: 0x0000_0031, err = b D: 144249 [DMG] We cannot put more chunks into this report. Enable chunking. D: 144257 [DMG] Sending report (payload has 990 bytes)... D: 144262 [DMG]IM RH moving to [AwaitingReportResponse] I: 144272 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473418. I: 144286 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473418 to 0x00000000E0A03CBA (1) at monotonic time: 000000000002339E msec D: 144301 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 144405 [IN]Received a duplicate message with MessageCounter:46575692 on exchange 2107r I: 144415 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575692 on exchange 2107r D: 144425 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 144432 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575692 on exchange 2107r D: 144441 [EM]Sending Standalone Ack for MessageCounter:46575692 on exchange 2107r I: 144449 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473419. I: 144464 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473419 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023450 msec I: 144525 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575693 on exchange 2107r D: 144536 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 144542 [EM]Rxd Ack; Removing MessageCounter:132473418 from Retrans Table on exchange 2107r D: 144550 [EM]Removed CHIP MessageCounter:132473418 from RetransTable on exchange 2107r I: 144558 [IM]Received status response, status is 0x00 D: 144563 [DMG] OnReportConfirm: NumReports = 0 D: 144568 [DMG]IM RH moving to [GeneratingReports] D: 144572 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 144581 [DMG] Cluster 31, Attribute fffb is dirty D: 144586 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 144596 [DMG] Cluster 33, Attribute 0 is dirty D: 144601 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 144610 [DMG] Cluster 33, Attribute 1 is dirty D: 144615 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 144625 [DMG] Cluster 33, Attribute 2 is dirty D: 144630 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 144639 [DMG] Cluster 33, Attribute 3 is dirty D: 144645 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 144655 [DMG] Cluster 33, Attribute 4 is dirty D: 144660 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0004 (expanded=1) D: 144669 [DMG] Cluster 33, Attribute 5 is dirty D: 144674 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0005 (expanded=1) D: 144683 [DMG] Cluster 33, Attribute 6 is dirty D: 144688 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0006 (expanded=1) D: 144697 [DMG] Cluster 33, Attribute 7 is dirty D: 144702 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_0007 (expanded=1) D: 144711 [DMG] Cluster 33, Attribute fffc is dirty D: 144717 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 144726 [DMG] Cluster 33, Attribute fffd is dirty D: 144731 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 144740 [DMG] Cluster 33, Attribute fff8 is dirty D: 144746 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 144755 [DMG] Cluster 33, Attribute fff9 is dirty D: 144760 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 144769 [DMG] Cluster 33, Attribute fffb is dirty D: 144775 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) E: 144784 [DMG]Error retrieving data from clusterId: 0x0000_0033, err = b D: 144791 [DMG] We cannot put more chunks into this report. Enable chunking. D: 144798 [DMG] Sending report (payload has 987 bytes)... D: 144804 [DMG]IM RH moving to [AwaitingReportResponse] I: 144814 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473420. I: 144828 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473420 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000235BC msec D: 144842 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 145000 [IN]Received a duplicate message with MessageCounter:46575693 on exchange 2107r I: 145010 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575693 on exchange 2107r D: 145019 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 145025 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575693 on exchange 2107r D: 145034 [EM]Sending Standalone Ack for MessageCounter:46575693 on exchange 2107r I: 145042 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473421. I: 145056 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473421 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000236A0 msec I: 145071 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575694 on exchange 2107r D: 145081 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 145087 [EM]Rxd Ack; Removing MessageCounter:132473420 from Retrans Table on exchange 2107r D: 145096 [EM]Removed CHIP MessageCounter:132473420 from RetransTable on exchange 2107r I: 145103 [IM]Received status response, status is 0x00 D: 145108 [DMG] OnReportConfirm: NumReports = 0 D: 145113 [DMG]IM RH moving to [GeneratingReports] D: 145118 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 145126 [DMG] Cluster 33, Attribute fffb is dirty D: 145132 [DMG]Reading attribute: Cluster=0x0000_0033 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 145141 [DMG] Cluster 34, Attribute 1 is dirty D: 145146 [DMG]Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 145155 [DMG] Cluster 34, Attribute 2 is dirty D: 145160 [DMG]Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 145169 [DMG] Cluster 34, Attribute 3 is dirty D: 145174 [DMG]Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 145183 [DMG] Cluster 34, Attribute fffc is dirty D: 145189 [DMG]Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 145198 [DMG] Cluster 34, Attribute fffd is dirty D: 145203 [DMG]Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 145212 [DMG] Cluster 34, Attribute fff8 is dirty D: 145218 [DMG]Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 145227 [DMG] Cluster 34, Attribute fff9 is dirty D: 145232 [DMG]Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 145241 [DMG] Cluster 34, Attribute fffb is dirty D: 145247 [DMG]Reading attribute: Cluster=0x0000_0034 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 145256 [DMG] Cluster 35, Attribute 0 is dirty D: 145261 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 145270 [DMG] Cluster 35, Attribute 1 is dirty D: 145275 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 145284 [DMG] Cluster 35, Attribute 2 is dirty D: 145290 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 145299 [DMG] Cluster 35, Attribute 3 is dirty D: 145304 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 145313 [DMG] Cluster 35, Attribute 4 is dirty D: 145318 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0004 (expanded=1) D: 145327 [DMG] Cluster 35, Attribute 5 is dirty D: 145332 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0005 (expanded=1) D: 145341 [DMG] Cluster 35, Attribute 6 is dirty D: 145346 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0006 (expanded=1) D: 145355 [DMG] Cluster 35, Attribute 7 is dirty D: 145361 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0007 (expanded=1) D: 145370 [DMG] Cluster 35, Attribute 8 is dirty D: 145375 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0008 (expanded=1) D: 145386 [DMG] Cluster 35, Attribute 9 is dirty D: 145392 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0009 (expanded=1) D: 145401 [DMG] Cluster 35, Attribute a is dirty D: 145406 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000A (expanded=1) D: 145415 [DMG] Cluster 35, Attribute b is dirty D: 145420 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000B (expanded=1) E: 145429 [DL]_WriteThreadNetworkDiagnosticAttributeToTlv failed: Error CHIP:0x0000000B E: 145437 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b D: 145443 [DMG] We cannot put more chunks into this report. Enable chunking. D: 145451 [DMG] Sending report (payload has 987 bytes)... D: 145456 [DMG]IM RH moving to [AwaitingReportResponse] I: 145466 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473422. I: 145480 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473422 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023848 msec D: 145495 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 145504 [IN]Received a duplicate message with MessageCounter:46575694 on exchange 2107r I: 145512 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575694 on exchange 2107r D: 145523 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 145529 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575694 on exchange 2107r D: 145539 [EM]Sending Standalone Ack for MessageCounter:46575694 on exchange 2107r I: 145547 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473423. I: 145562 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473423 to 0x00000000E0A03CBA (1) at monotonic time: 000000000002389A msec I: 145673 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575695 on exchange 2107r D: 145683 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 145689 [EM]Rxd Ack; Removing MessageCounter:132473422 from Retrans Table on exchange 2107r D: 145697 [EM]Removed CHIP MessageCounter:132473422 from RetransTable on exchange 2107r I: 145705 [IM]Received status response, status is 0x00 D: 145710 [DMG] OnReportConfirm: NumReports = 0 D: 145715 [DMG]IM RH moving to [GeneratingReports] D: 145719 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 145728 [DMG] Cluster 35, Attribute b is dirty D: 145733 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000B (expanded=1) D: 145742 [DMG] Cluster 35, Attribute c is dirty D: 145747 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000C (expanded=1) D: 145756 [DMG] Cluster 35, Attribute d is dirty D: 145762 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000D (expanded=1) D: 145771 [DMG] Cluster 35, Attribute e is dirty D: 145776 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000E (expanded=1) D: 145785 [DMG] Cluster 35, Attribute f is dirty D: 145790 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_000F (expanded=1) D: 145799 [DMG] Cluster 35, Attribute 10 is dirty D: 145804 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0010 (expanded=1) D: 145813 [DMG] Cluster 35, Attribute 11 is dirty D: 145819 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0011 (expanded=1) D: 145828 [DMG] Cluster 35, Attribute 12 is dirty D: 145833 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0012 (expanded=1) D: 145842 [DMG] Cluster 35, Attribute 13 is dirty D: 145847 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0013 (expanded=1) D: 145856 [DMG] Cluster 35, Attribute 14 is dirty D: 145862 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0014 (expanded=1) D: 145871 [DMG] Cluster 35, Attribute 15 is dirty D: 145876 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0015 (expanded=1) D: 145885 [DMG] Cluster 35, Attribute 16 is dirty D: 145890 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0016 (expanded=1) D: 145899 [DMG] Cluster 35, Attribute 17 is dirty D: 145905 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0017 (expanded=1) D: 145914 [DMG] Cluster 35, Attribute 18 is dirty D: 145919 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0018 (expanded=1) D: 145928 [DMG] Cluster 35, Attribute 19 is dirty D: 145933 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0019 (expanded=1) D: 145942 [DMG] Cluster 35, Attribute 1a is dirty D: 145948 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001A (expanded=1) D: 145957 [DMG] Cluster 35, Attribute 1b is dirty D: 145962 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001B (expanded=1) D: 145971 [DMG] Cluster 35, Attribute 1c is dirty D: 145976 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001C (expanded=1) D: 145985 [DMG] Cluster 35, Attribute 1d is dirty D: 145990 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001D (expanded=1) D: 146000 [DMG] Cluster 35, Attribute 1e is dirty D: 146005 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001E (expanded=1) D: 146014 [DMG] Cluster 35, Attribute 1f is dirty D: 146019 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_001F (expanded=1) D: 146028 [DMG] Cluster 35, Attribute 20 is dirty D: 146033 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0020 (expanded=1) D: 146043 [DMG] Cluster 35, Attribute 21 is dirty D: 146049 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0021 (expanded=1) D: 146059 [DMG] Cluster 35, Attribute 22 is dirty D: 146064 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0022 (expanded=1) D: 146073 [DMG] Cluster 35, Attribute 23 is dirty D: 146078 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0023 (expanded=1) D: 146087 [DMG] Cluster 35, Attribute 24 is dirty D: 146092 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0024 (expanded=1) D: 146102 [DMG] Cluster 35, Attribute 25 is dirty D: 146107 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0025 (expanded=1) D: 146116 [DMG] Cluster 35, Attribute 26 is dirty D: 146121 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0026 (expanded=1) D: 146130 [DMG] Cluster 35, Attribute 27 is dirty D: 146135 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0027 (expanded=1) D: 146145 [DMG] Cluster 35, Attribute 28 is dirty D: 146150 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0028 (expanded=1) D: 146159 [DMG] Cluster 35, Attribute 29 is dirty D: 146164 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0029 (expanded=1) D: 146173 [DMG] Cluster 35, Attribute 2a is dirty D: 146178 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002A (expanded=1) D: 146188 [DMG] Cluster 35, Attribute 2b is dirty D: 146193 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002B (expanded=1) D: 146202 [DMG] Cluster 35, Attribute 2c is dirty D: 146207 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002C (expanded=1) D: 146216 [DMG] Cluster 35, Attribute 2d is dirty D: 146221 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002D (expanded=1) D: 146230 [DMG] Cluster 35, Attribute 2e is dirty D: 146236 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002E (expanded=1) D: 146245 [DMG] Cluster 35, Attribute 2f is dirty D: 146250 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_002F (expanded=1) D: 146259 [DMG] Cluster 35, Attribute 30 is dirty D: 146264 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0030 (expanded=1) E: 146274 [DL]_WriteThreadNetworkDiagnosticAttributeToTlv failed: Error CHIP:0x0000000B E: 146281 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b D: 146288 [DMG] We cannot put more chunks into this report. Enable chunking. D: 146295 [DMG] Sending report (payload has 980 bytes)... D: 146301 [DMG]IM RH moving to [AwaitingReportResponse] I: 146311 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473424. I: 146325 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473424 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023B95 msec D: 146339 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 146348 [IN]Received a duplicate message with MessageCounter:46575695 on exchange 2107r I: 146356 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575695 on exchange 2107r D: 146366 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 146373 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575695 on exchange 2107r D: 146382 [EM]Sending Standalone Ack for MessageCounter:46575695 on exchange 2107r I: 146390 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473425. I: 146405 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473425 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023BE4 msec D: 146481 [IN]Received a duplicate message with MessageCounter:46575695 on exchange 2107r I: 146489 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575695 on exchange 2107r D: 146499 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 146505 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575695 on exchange 2107r D: 146514 [EM]Sending Standalone Ack for MessageCounter:46575695 on exchange 2107r I: 146522 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473426. I: 146537 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473426 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023C69 msec I: 146648 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575696 on exchange 2107r D: 146658 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 146666 [EM]Rxd Ack; Removing MessageCounter:132473424 from Retrans Table on exchange 2107r D: 146675 [EM]Removed CHIP MessageCounter:132473424 from RetransTable on exchange 2107r I: 146683 [IM]Received status response, status is 0x00 D: 146688 [DMG] OnReportConfirm: NumReports = 0 D: 146694 [DMG]IM RH moving to [GeneratingReports] D: 146699 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 146707 [DMG] Cluster 35, Attribute 30 is dirty D: 146712 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0030 (expanded=1) D: 146721 [DMG] Cluster 35, Attribute 31 is dirty D: 146727 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0031 (expanded=1) D: 146736 [DMG] Cluster 35, Attribute 32 is dirty D: 146741 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0032 (expanded=1) D: 146750 [DMG] Cluster 35, Attribute 33 is dirty D: 146755 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0033 (expanded=1) D: 146764 [DMG] Cluster 35, Attribute 34 is dirty D: 146770 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0034 (expanded=1) D: 146779 [DMG] Cluster 35, Attribute 35 is dirty D: 146784 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0035 (expanded=1) D: 146793 [DMG] Cluster 35, Attribute 36 is dirty D: 146798 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0036 (expanded=1) D: 146807 [DMG] Cluster 35, Attribute 37 is dirty D: 146813 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0037 (expanded=1) D: 146822 [DMG] Cluster 35, Attribute 38 is dirty D: 146827 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0038 (expanded=1) D: 146837 [DMG] Cluster 35, Attribute 39 is dirty D: 146843 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_0039 (expanded=1) D: 146853 [DMG] Cluster 35, Attribute 3a is dirty D: 146858 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003A (expanded=1) D: 146869 [DMG] Cluster 35, Attribute 3b is dirty D: 146874 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003B (expanded=1) D: 146885 [DMG] Cluster 35, Attribute 3c is dirty D: 146890 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003C (expanded=1) D: 146900 [DMG] Cluster 35, Attribute 3d is dirty D: 146905 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003D (expanded=1) D: 146916 [DMG] Cluster 35, Attribute 3e is dirty D: 146921 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_003E (expanded=1) D: 146930 [DMG] Cluster 35, Attribute fffc is dirty D: 146936 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 146945 [DMG] Cluster 35, Attribute fffd is dirty D: 146950 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 146959 [DMG] Cluster 35, Attribute fff8 is dirty D: 146965 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 146974 [DMG] Cluster 35, Attribute fff9 is dirty D: 146979 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 146988 [DMG] Cluster 35, Attribute fffb is dirty D: 146994 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) E: 147003 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b D: 147010 [DMG] We cannot put more chunks into this report. Enable chunking. D: 147017 [DMG] Sending report (payload has 987 bytes)... D: 147023 [DMG]IM RH moving to [AwaitingReportResponse] I: 147034 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473427. I: 147049 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473427 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023E68 msec D: 147063 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 147071 [IN]Received a duplicate message with MessageCounter:46575696 on exchange 2107r I: 147080 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575696 on exchange 2107r D: 147091 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 147097 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575696 on exchange 2107r D: 147106 [EM]Sending Standalone Ack for MessageCounter:46575696 on exchange 2107r I: 147115 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473428. I: 147130 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473428 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023EB9 msec I: 147227 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575697 on exchange 2107r D: 147237 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 147243 [EM]Rxd Ack; Removing MessageCounter:132473427 from Retrans Table on exchange 2107r D: 147251 [EM]Removed CHIP MessageCounter:132473427 from RetransTable on exchange 2107r I: 147259 [IM]Received status response, status is 0x00 D: 147264 [DMG] OnReportConfirm: NumReports = 0 D: 147268 [DMG]IM RH moving to [GeneratingReports] D: 147273 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 147282 [DMG] Cluster 35, Attribute fffb is dirty D: 147287 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) E: 147297 [DMG]Error retrieving data from clusterId: 0x0000_0035, err = b D: 147304 [DMG] We cannot put more chunks into this report. Enable chunking. D: 147311 [DMG] Sending report (payload has 1002 bytes)... D: 147317 [DMG]IM RH moving to [AwaitingReportResponse] I: 147327 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473429. I: 147341 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473429 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000023F8D msec D: 147356 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages I: 147484 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575698 on exchange 2107r D: 147494 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 147500 [EM]Rxd Ack; Removing MessageCounter:132473429 from Retrans Table on exchange 2107r D: 147508 [EM]Removed CHIP MessageCounter:132473429 from RetransTable on exchange 2107r I: 147516 [IM]Received status response, status is 0x00 D: 147521 [DMG] OnReportConfirm: NumReports = 0 D: 147525 [DMG]IM RH moving to [GeneratingReports] D: 147530 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 147539 [DMG] Cluster 35, Attribute fffb is dirty D: 147544 [DMG]Reading attribute: Cluster=0x0000_0035 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 147554 [DMG] Cluster 3c, Attribute 0 is dirty D: 147559 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 147568 [DMG] Cluster 3c, Attribute 1 is dirty D: 147573 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 147582 [DMG] Cluster 3c, Attribute 2 is dirty D: 147588 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 147597 [DMG] Cluster 3c, Attribute fffc is dirty D: 147602 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 147611 [DMG] Cluster 3c, Attribute fffd is dirty D: 147617 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 147626 [DMG] Cluster 3c, Attribute fff8 is dirty D: 147631 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 147640 [DMG] Cluster 3c, Attribute fff9 is dirty D: 147646 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) E: 147655 [DMG]Error retrieving data from clusterId: 0x0000_003C, err = b D: 147661 [DMG] We cannot put more chunks into this report. Enable chunking. D: 147669 [DMG] Sending report (payload has 988 bytes)... D: 147674 [DMG]IM RH moving to [AwaitingReportResponse] I: 147684 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473430. I: 147698 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473430 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000240F2 msec D: 147713 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 147824 [IN]Received a duplicate message with MessageCounter:46575698 on exchange 2107r I: 147832 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575698 on exchange 2107r D: 147842 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 147850 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575698 on exchange 2107r D: 147858 [EM]Sending Standalone Ack for MessageCounter:46575698 on exchange 2107r I: 147866 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473431. I: 147881 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473431 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000241A8 msec I: 147895 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575699 on exchange 2107r D: 147905 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 147912 [EM]Rxd Ack; Removing MessageCounter:132473430 from Retrans Table on exchange 2107r D: 147921 [EM]Removed CHIP MessageCounter:132473430 from RetransTable on exchange 2107r I: 147928 [IM]Received status response, status is 0x00 D: 147933 [DMG] OnReportConfirm: NumReports = 0 D: 147938 [DMG]IM RH moving to [GeneratingReports] D: 147943 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 147951 [DMG] Cluster 3c, Attribute fff9 is dirty D: 147957 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 147966 [DMG] Cluster 3c, Attribute fffb is dirty D: 147971 [DMG]Reading attribute: Cluster=0x0000_003C Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 147980 [DMG] Cluster 3e, Attribute 0 is dirty D: 147986 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 147995 [DMG] Cluster 3e, Attribute 1 is dirty D: 148000 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0001 (expanded=1) E: 148009 [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b D: 148016 [DMG] We cannot put more chunks into this report. Enable chunking. D: 148023 [DMG] Sending report (payload has 1005 bytes)... D: 148029 [DMG]IM RH moving to [AwaitingReportResponse] I: 148039 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473432. I: 148053 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473432 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024255 msec D: 148068 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 148280 [IN]Received a duplicate message with MessageCounter:46575699 on exchange 2107r I: 148289 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575699 on exchange 2107r D: 148299 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 148305 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575699 on exchange 2107r D: 148314 [EM]Sending Standalone Ack for MessageCounter:46575699 on exchange 2107r I: 148322 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473433. I: 148338 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473433 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024371 msec I: 148352 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575700 on exchange 2107r D: 148363 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 148369 [EM]Rxd Ack; Removing MessageCounter:132473432 from Retrans Table on exchange 2107r D: 148377 [EM]Removed CHIP MessageCounter:132473432 from RetransTable on exchange 2107r I: 148385 [IM]Received status response, status is 0x00 D: 148390 [DMG] OnReportConfirm: NumReports = 0 D: 148394 [DMG]IM RH moving to [GeneratingReports] D: 148399 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 148408 [DMG] Cluster 3e, Attribute 1 is dirty D: 148413 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 148422 [DMG] Cluster 3e, Attribute 2 is dirty D: 148427 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 148436 [DMG] Cluster 3e, Attribute 3 is dirty D: 148441 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 148450 [DMG] Cluster 3e, Attribute 4 is dirty D: 148455 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0004 (expanded=1) D: 148465 [DMG] Cluster 3e, Attribute 5 is dirty D: 148470 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_0005 (expanded=1) D: 148479 [DMG] Cluster 3e, Attribute fffc is dirty D: 148484 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 148494 [DMG] Cluster 3e, Attribute fffd is dirty D: 148499 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 148508 [DMG] Cluster 3e, Attribute fff8 is dirty D: 148513 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 148523 [DMG] Cluster 3e, Attribute fff9 is dirty D: 148528 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) E: 148537 [DMG]Error retrieving data from clusterId: 0x0000_003E, err = b D: 148544 [DMG] We cannot put more chunks into this report. Enable chunking. D: 148551 [DMG] Sending report (payload has 1002 bytes)... D: 148557 [DMG]IM RH moving to [AwaitingReportResponse] I: 148567 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473434. I: 148581 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473434 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024465 msec D: 148596 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 148707 [IN]Received a duplicate message with MessageCounter:46575700 on exchange 2107r I: 148716 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575700 on exchange 2107r D: 148726 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 148733 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575700 on exchange 2107r D: 148742 [EM]Sending Standalone Ack for MessageCounter:46575700 on exchange 2107r I: 148750 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473435. I: 148764 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473435 to 0x00000000E0A03CBA (1) at monotonic time: 000000000002451C msec I: 148797 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575701 on exchange 2107r D: 148807 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 148813 [EM]Rxd Ack; Removing MessageCounter:132473434 from Retrans Table on exchange 2107r D: 148821 [EM]Removed CHIP MessageCounter:132473434 from RetransTable on exchange 2107r I: 148829 [IM]Received status response, status is 0x00 D: 148834 [DMG] OnReportConfirm: NumReports = 0 D: 148838 [DMG]IM RH moving to [GeneratingReports] D: 148843 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 148852 [DMG] Cluster 3e, Attribute fff9 is dirty D: 148857 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 148866 [DMG] Cluster 3e, Attribute fffb is dirty D: 148872 [DMG]Reading attribute: Cluster=0x0000_003E Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 148881 [DMG] Cluster 3f, Attribute 0 is dirty D: 148886 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 148896 [DMG] Cluster 3f, Attribute 1 is dirty D: 148901 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_0001 (expanded=1) D: 148911 [DMG] Cluster 3f, Attribute 2 is dirty D: 148916 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 148925 [DMG] Cluster 3f, Attribute 3 is dirty D: 148930 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_0003 (expanded=1) D: 148939 [DMG] Cluster 3f, Attribute fffc is dirty D: 148945 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) D: 148954 [DMG] Cluster 3f, Attribute fffd is dirty D: 148959 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) D: 148968 [DMG] Cluster 3f, Attribute fff8 is dirty D: 148974 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) D: 148983 [DMG] Cluster 3f, Attribute fff9 is dirty D: 148988 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) D: 148998 [DMG] Cluster 3f, Attribute fffb is dirty D: 149003 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) E: 149012 [DMG]Error retrieving data from clusterId: 0x0000_003F, err = b D: 149019 [DMG] We cannot put more chunks into this report. Enable chunking. D: 149026 [DMG] Sending report (payload has 985 bytes)... D: 149032 [DMG]IM RH moving to [AwaitingReportResponse] I: 149042 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473436. I: 149056 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473436 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024640 msec D: 149070 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 149167 [IN]Received a duplicate message with MessageCounter:46575701 on exchange 2107r I: 149175 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575701 on exchange 2107r D: 149186 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 149192 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575701 on exchange 2107r D: 149201 [EM]Sending Standalone Ack for MessageCounter:46575701 on exchange 2107r I: 149210 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473437. I: 149224 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473437 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000246E8 msec I: 149307 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575702 on exchange 2107r D: 149317 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 149323 [EM]Rxd Ack; Removing MessageCounter:132473436 from Retrans Table on exchange 2107r D: 149331 [EM]Removed CHIP MessageCounter:132473436 from RetransTable on exchange 2107r I: 149339 [IM]Received status response, status is 0x00 D: 149344 [DMG] OnReportConfirm: NumReports = 0 D: 149349 [DMG]IM RH moving to [GeneratingReports] D: 149353 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 149362 [DMG] Cluster 3f, Attribute fffb is dirty D: 149367 [DMG]Reading attribute: Cluster=0x0000_003F Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) D: 149376 [DMG] Cluster 3, Attribute 0 is dirty D: 149381 [DMG]Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 149391 [DMG] Cluster 3, Attribute 1 is dirty D: 149396 [DMG]Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_0001 (expanded=1) D: 149405 [DMG] Cluster 3, Attribute fffc is dirty D: 149410 [DMG]Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1) D: 149419 [DMG] Cluster 3, Attribute fffd is dirty D: 149424 [DMG]Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1) D: 149433 [DMG] Cluster 3, Attribute fff8 is dirty D: 149439 [DMG]Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1) D: 149448 [DMG] Cluster 3, Attribute fff9 is dirty D: 149453 [DMG]Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1) D: 149462 [DMG] Cluster 3, Attribute fffb is dirty D: 149467 [DMG]Reading attribute: Cluster=0x0000_0003 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1) D: 149477 [DMG] Cluster 4, Attribute 0 is dirty D: 149482 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 149491 [DMG] Cluster 4, Attribute fffc is dirty D: 149496 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1) D: 149505 [DMG] Cluster 4, Attribute fffd is dirty D: 149511 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1) D: 149520 [DMG] Cluster 4, Attribute fff8 is dirty D: 149525 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1) D: 149534 [DMG] Cluster 4, Attribute fff9 is dirty D: 149540 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1) D: 149549 [DMG] Cluster 4, Attribute fffb is dirty D: 149554 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1) E: 149563 [DMG]Error retrieving data from clusterId: 0x0000_0004, err = b D: 149570 [DMG] We cannot put more chunks into this report. Enable chunking. D: 149577 [DMG] Sending report (payload has 983 bytes)... D: 149583 [DMG]IM RH moving to [AwaitingReportResponse] I: 149593 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473438. I: 149607 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473438 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024866 msec D: 149621 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 149716 [IN]Received a duplicate message with MessageCounter:46575702 on exchange 2107r I: 149725 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575702 on exchange 2107r D: 149735 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 149742 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575702 on exchange 2107r D: 149750 [EM]Sending Standalone Ack for MessageCounter:46575702 on exchange 2107r I: 149759 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473439. I: 149773 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473439 to 0x00000000E0A03CBA (1) at monotonic time: 000000000002490D msec I: 149822 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575703 on exchange 2107r D: 149832 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 149838 [EM]Rxd Ack; Removing MessageCounter:132473438 from Retrans Table on exchange 2107r D: 149846 [EM]Removed CHIP MessageCounter:132473438 from RetransTable on exchange 2107r I: 149854 [IM]Received status response, status is 0x00 D: 149859 [DMG] OnReportConfirm: NumReports = 0 D: 149864 [DMG]IM RH moving to [GeneratingReports] D: 149868 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 149877 [DMG] Cluster 4, Attribute fffb is dirty D: 149882 [DMG]Reading attribute: Cluster=0x0000_0004 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1) D: 149892 [DMG] Cluster 6, Attribute 0 is dirty D: 149897 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 149906 [DMG] Cluster 6, Attribute 4000 is dirty D: 149911 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4000 (expanded=1) D: 149920 [DMG] Cluster 6, Attribute 4001 is dirty D: 149925 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4001 (expanded=1) D: 149934 [DMG] Cluster 6, Attribute 4002 is dirty D: 149940 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4002 (expanded=1) D: 149949 [DMG] Cluster 6, Attribute 4003 is dirty D: 149954 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4003 (expanded=1) D: 149963 [DMG] Cluster 6, Attribute fffc is dirty D: 149969 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1) D: 149978 [DMG] Cluster 6, Attribute fffd is dirty D: 149983 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1) D: 149992 [DMG] Cluster 6, Attribute fff8 is dirty D: 149997 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1) D: 150006 [DMG] Cluster 6, Attribute fff9 is dirty D: 150012 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1) D: 150021 [DMG] Cluster 6, Attribute fffb is dirty D: 150026 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1) D: 150036 [DMG] Cluster 8, Attribute 0 is dirty D: 150041 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 150050 [DMG] Cluster 8, Attribute 1 is dirty D: 150055 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0001 (expanded=1) E: 150064 [DMG]Error retrieving data from clusterId: 0x0000_0008, err = b D: 150070 [DMG] We cannot put more chunks into this report. Enable chunking. D: 150078 [DMG] Sending report (payload has 987 bytes)... D: 150083 [DMG]IM RH moving to [AwaitingReportResponse] I: 150093 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473440. I: 150108 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473440 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024A5B msec D: 150122 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 150219 [IN]Received a duplicate message with MessageCounter:46575703 on exchange 2107r I: 150228 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575703 on exchange 2107r D: 150239 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 150245 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575703 on exchange 2107r D: 150255 [EM]Sending Standalone Ack for MessageCounter:46575703 on exchange 2107r I: 150263 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473441. I: 150278 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473441 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024B06 msec I: 150324 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575704 on exchange 2107r D: 150334 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 150340 [EM]Rxd Ack; Removing MessageCounter:132473440 from Retrans Table on exchange 2107r D: 150348 [EM]Removed CHIP MessageCounter:132473440 from RetransTable on exchange 2107r I: 150356 [IM]Received status response, status is 0x00 D: 150361 [DMG] OnReportConfirm: NumReports = 0 D: 150366 [DMG]IM RH moving to [GeneratingReports] D: 150370 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 150379 [DMG] Cluster 8, Attribute 1 is dirty D: 150384 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0001 (expanded=1) D: 150393 [DMG] Cluster 8, Attribute 2 is dirty D: 150398 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0002 (expanded=1) D: 150407 [DMG] Cluster 8, Attribute 3 is dirty D: 150412 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0003 (expanded=1) D: 150421 [DMG] Cluster 8, Attribute 4 is dirty D: 150426 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0004 (expanded=1) D: 150435 [DMG] Cluster 8, Attribute 5 is dirty D: 150440 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0005 (expanded=1) D: 150449 [DMG] Cluster 8, Attribute 6 is dirty D: 150455 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0006 (expanded=1) D: 150464 [DMG] Cluster 8, Attribute f is dirty D: 150469 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_000F (expanded=1) D: 150478 [DMG] Cluster 8, Attribute 10 is dirty D: 150483 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0010 (expanded=1) D: 150492 [DMG] Cluster 8, Attribute 11 is dirty D: 150497 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0011 (expanded=1) D: 150506 [DMG] Cluster 8, Attribute 12 is dirty D: 150511 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0012 (expanded=1) D: 150520 [DMG] Cluster 8, Attribute 13 is dirty D: 150526 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0013 (expanded=1) D: 150535 [DMG] Cluster 8, Attribute 14 is dirty D: 150540 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0014 (expanded=1) D: 150549 [DMG] Cluster 8, Attribute 4000 is dirty D: 150554 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_4000 (expanded=1) D: 150563 [DMG] Cluster 8, Attribute fffc is dirty D: 150569 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFFC (expanded=1) D: 150578 [DMG] Cluster 8, Attribute fffd is dirty D: 150583 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFFD (expanded=1) D: 150592 [DMG] Cluster 8, Attribute fff8 is dirty D: 150597 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1) D: 150606 [DMG] Cluster 8, Attribute fff9 is dirty D: 150612 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1) D: 150621 [DMG] Cluster 8, Attribute fffb is dirty D: 150626 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1) E: 150636 [DMG]Error retrieving data from clusterId: 0x0000_0008, err = b D: 150642 [DMG] We cannot put more chunks into this report. Enable chunking. D: 150650 [DMG] Sending report (payload has 982 bytes)... D: 150655 [DMG]IM RH moving to [AwaitingReportResponse] I: 150665 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473442. I: 150679 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473442 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024C97 msec D: 150694 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 150738 [IN]Received a duplicate message with MessageCounter:46575704 on exchange 2107r I: 150746 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575704 on exchange 2107r D: 150757 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 150764 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575704 on exchange 2107r D: 150773 [EM]Sending Standalone Ack for MessageCounter:46575704 on exchange 2107r I: 150782 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473443. I: 150796 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473443 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024D0C msec I: 150880 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575705 on exchange 2107r D: 150890 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 150896 [EM]Rxd Ack; Removing MessageCounter:132473442 from Retrans Table on exchange 2107r D: 150904 [EM]Removed CHIP MessageCounter:132473442 from RetransTable on exchange 2107r I: 150912 [IM]Received status response, status is 0x00 D: 150917 [DMG] OnReportConfirm: NumReports = 0 D: 150922 [DMG]IM RH moving to [GeneratingReports] D: 150926 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 150935 [DMG] Cluster 8, Attribute fffb is dirty D: 150940 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_FFFB (expanded=1) D: 150950 [DMG] Cluster 1d, Attribute 0 is dirty D: 150955 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 150964 [DMG] Cluster 1d, Attribute 1 is dirty D: 150969 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0001 (expanded=1) D: 150978 [DMG] Cluster 1d, Attribute 2 is dirty D: 150984 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0002 (expanded=1) D: 150993 [DMG] Cluster 1d, Attribute 3 is dirty D: 150998 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_0003 (expanded=1) D: 151007 [DMG] Cluster 1d, Attribute fffc is dirty D: 151012 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFFC (expanded=1) D: 151021 [DMG] Cluster 1d, Attribute fffd is dirty D: 151027 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFFD (expanded=1) D: 151036 [DMG] Cluster 1d, Attribute fff8 is dirty D: 151041 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFF8 (expanded=1) D: 151050 [DMG] Cluster 1d, Attribute fff9 is dirty D: 151056 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFF9 (expanded=1) D: 151065 [DMG] Cluster 1d, Attribute fffb is dirty D: 151070 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFFB (expanded=1) E: 151080 [DMG]Error retrieving data from clusterId: 0x0000_001D, err = b D: 151086 [DMG] We cannot put more chunks into this report. Enable chunking. D: 151094 [DMG] Sending report (payload has 990 bytes)... D: 151099 [DMG]IM RH moving to [AwaitingReportResponse] I: 151109 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473444. I: 151123 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473444 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024E53 msec D: 151138 [DMG] ReportsInFlight = 1 with readHandler 0, RE has more messages D: 151224 [IN]Received a duplicate message with MessageCounter:46575705 on exchange 2107r I: 151233 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575705 on exchange 2107r D: 151243 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 151250 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575705 on exchange 2107r D: 151258 [EM]Sending Standalone Ack for MessageCounter:46575705 on exchange 2107r I: 151267 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473445. I: 151281 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473445 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024EF1 msec I: 151353 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575706 on exchange 2107r D: 151363 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 151369 [EM]Rxd Ack; Removing MessageCounter:132473444 from Retrans Table on exchange 2107r D: 151378 [EM]Removed CHIP MessageCounter:132473444 from RetransTable on exchange 2107r I: 151385 [IM]Received status response, status is 0x00 D: 151390 [DMG] OnReportConfirm: NumReports = 0 D: 151395 [DMG]IM RH moving to [GeneratingReports] D: 151400 [DMG]Building Reports for ReadHandler with LastReportGeneration = 0 DirtyGeneration = 0 D: 151408 [DMG] Cluster 1d, Attribute fffb is dirty D: 151414 [DMG]Reading attribute: Cluster=0x0000_001D Endpoint=1 AttributeId=0x0000_FFFB (expanded=1) D: 151423 [DMG] Sending report (payload has 44 bytes)... D: 151428 [DMG]IM RH moving to [AwaitingReportResponse] I: 151434 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473446. I: 151449 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473446 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000024F99 msec D: 151463 [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages D: 151470 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 151510 [EM]Received message of type 0x6 with protocolId (0, 1) and MessageCounter:46575707 on exchange 2108r D: 151521 [EM]Handling via exchange: 2108r, Delegate: 0x2000f54c D: 151528 [IM]Received Write request D: 151531 [DMG]IM WH moving to [Initialized] D: 151538 [DMG]Endpoint 0, Cluster 0x0000_002A update version to 3c0442cb D: 151544 [DMG]Cannot merge the new path into any existing path, create one. D: 151551 [DMG]IM WH moving to [AddStatus] D: 151620 [DMG]Endpoint 0, Cluster 0x0000_002A update version to 3c0442cc D: 151627 [DMG]IM WH moving to [AddStatus] I: 151632 [IN]Prepared secure message 0x2000513c to 0x00000000E0A03CBA (1) of type 0x7 and protocolId (0, 1) on exchange 2108r with MessageCounter:132473447. I: 151646 [IN]Sending encrypted msg 0x2000513c with MessageCounter:132473447 to 0x00000000E0A03CBA (1) at monotonic time: 000000000002505E msec D: 151660 [DMG]IM WH moving to [Sending] D: 151664 [DMG]IM WH moving to [Uninitialized] I: 151669 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:46575708 on exchange 2109r D: 151679 [EM]Handling via exchange: 2109r, Delegate: 0x2000f54c D: 151687 [DMG]Received command for Endpoint=0 Cluster=0x0000_002A Command=0x0000_0000 I: 151695 [SWU]OTA Requestor received AnnounceOTAProvider D: 151700 [SWU] FabricIndex: 1 D: 151703 [SWU] ProviderNodeID: 0x00000000E0A03CBA D: 151708 [SWU] VendorID: 0x6006 D: 151711 [SWU] AnnouncementReason: 0 D: 151715 [SWU] Endpoint: 2 D: 151718 [DMG]Command handler moving to [ Preparing] D: 151723 [DMG]Command handler moving to [AddingComm] D: 151728 [DMG]Command handler moving to [AddedComma] D: 151734 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 151743 [IN]Prepared secure message 0x20005154 to 0x00000000E0A03CBA (1) of type 0x9 and protocolId (0, 1) on exchange 2109r with MessageCounter:132473448. I: 151757 [IN]Sending encrypted msg 0x20005154 with MessageCounter:132473448 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000250CD msec D: 151771 [DMG]Command handler moving to [CommandSen] D: 151776 [DMG]Command handler moving to [AwaitingDe] I: 151782 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575709 on exchange 2107r D: 151792 [EM]Found matching exchange: 2107r, Delegate: 0x20010ff4 D: 151799 [EM]Rxd Ack; Removing MessageCounter:132473446 from Retrans Table on exchange 2107r D: 151808 [EM]Removed CHIP MessageCounter:132473446 from RetransTable on exchange 2107r I: 151815 [IM]Received status response, status is 0x00 I: 151820 [DMG]Refresh Subscribe Sync Timer with max 120 seconds I: 151827 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x4 and protocolId (0, 1) on exchange 2107r with MessageCounter:132473449. I: 151841 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473449 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000025121 msec D: 151855 [DMG] OnReportConfirm: NumReports = 0 D: 151860 [DMG]IM RH moving to [GeneratingReports] D: 151865 [DMG]Endpoint 0, Cluster 0x0000_002A update version to 3c0442cd D: 151871 [DMG]Cannot merge the new path into any existing path, create one. D: 151880 [EVL]LogEvent event number: 0x0000000000000003 priority: 1, endpoint id: 0x0 cluster id: 0x0000_002A event id: 0x0 Sys timestamp: 0x0000000000025148 I: 151896 [SWU]Stopping the Periodic Query timer I: 151900 [SWU]Starting the watchdog timer, timeout: 21600 seconds D: 151906 [SWU]Establishing session to provider node ID 0x00000000E0A03CBA on fabric index 1 D: 151915 [CSM]FindOrEstablishSession: PeerId = [1:00000000E0A03CBA] D: 151921 [CSM]FindOrEstablishSession: No existing OperationalSessionSetup instance found I: 151929 [CTL]Found an existing secure session to [1:00000000E0A03CBA]! D: 151935 [CTL]OperationalSessionSetup[1:00000000E0A03CBA]: State change 1 --> 5 D: 151944 [DMG]ICR moving to [AddingComm] D: 151948 [DMG]ICR moving to [AddedComma] I: 151952 [IN]Prepared secure message 0x2000516c to 0x00000000E0A03CBA (1) of type 0x8 and protocolId (0, 1) on exchange 56125i with MessageCounter:132473450. I: 151967 [IN]Sending encrypted msg 0x2000516c with MessageCounter:132473450 to 0x00000000E0A03CBA (1) at monotonic time: 000000000002519E msec D: 151981 [DMG]ICR moving to [CommandSen] E: 151984 [DL]Long dispatch time: 119 ms, for event type 3 D: 151991 [DMG]Unblock report hold after min 0 seconds D: 151996 [DMG]Building Reports for ReadHandler with LastReportGeneration = 21 DirtyGeneration = 24 D: 152006 [DMG] Cluster 2a, Attribute 0 is dirty D: 152011 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0000 (expanded=1) D: 152021 [DMG] Cluster 2a, Attribute 2 is dirty D: 152026 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 152039 [DMG] Sending report (payload has 106 bytes)... D: 152044 [DMG]IM RH moving to [AwaitingReportResponse] I: 152050 [IN]Prepared secure message 0x20005184 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 56126i with MessageCounter:132473451. I: 152064 [IN]Sending encrypted msg 0x20005184 with MessageCounter:132473451 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000025200 msec I: 152078 [DMG]Refresh Subscribe Sync Timer with max 120 seconds D: 152084 [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages D: 152092 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 152099 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:46575710 on exchange 2108r D: 152110 [EM]Found matching exchange: 2108r, Delegate: (nil) D: 152115 [EM]Rxd Ack; Removing MessageCounter:132473447 from Retrans Table on exchange 2108r D: 152124 [EM]Removed CHIP MessageCounter:132473447 from RetransTable on exchange 2108r D: 152132 [DMG]Unblock report hold after min 0 seconds I: 152137 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:46575711 on exchange 2109r D: 152149 [EM]Found matching exchange: 2109r, Delegate: (nil) D: 152155 [EM]Rxd Ack; Removing MessageCounter:132473448 from Retrans Table on exchange 2109r D: 152163 [EM]Removed CHIP MessageCounter:132473448 from RetransTable on exchange 2109r D: 152171 [IN]Received a duplicate message with MessageCounter:46575709 on exchange 2107r I: 152179 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575709 on exchange 2107r D: 152189 [EM]Found matching exchange: 2107r, Delegate: (nil) D: 152195 [EM]Forcing tx of solitary ack for duplicate MessageCounter:46575709 on exchange 2107r D: 152203 [EM]Sending Standalone Ack for MessageCounter:46575709 on exchange 2107r I: 152211 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 2107r with MessageCounter:132473452. I: 152227 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473452 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000252A3 msec D: 152241 [EM]Retransmitting MessageCounter:132473449 on exchange 2107r Send Cnt 1 I: 152249 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473449 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000252B8 msec D: 152263 [CSM]UpdatePeerAddress: No existing OperationalSessionSetup instance found D: 152271 [CTL]OperationalSessionSetup[1:00000000E0A03CBA]: State change 1 --> 2 I: 152278 [DIS]Resolving 3199E9CFDD25901B:00000000E0A03CBA ... I: 152286 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:46575712 on exchange 2107r D: 152298 [EM]Found matching exchange: 2107r, Delegate: (nil) D: 152304 [EM]Rxd Ack; Removing MessageCounter:132473449 from Retrans Table on exchange 2107r D: 152313 [EM]Removed CHIP MessageCounter:132473449 from RetransTable on exchange 2107r I: 152322 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575713 on exchange 56126i D: 152333 [EM]Found matching exchange: 56126i, Delegate: 0x20010ff4 D: 152339 [EM]Rxd Ack; Removing MessageCounter:132473451 from Retrans Table on exchange 56126i D: 152348 [EM]Removed CHIP MessageCounter:132473451 from RetransTable on exchange 56126i I: 152356 [IM]Received status response, status is 0x00 D: 152361 [DMG] OnReportConfirm: NumReports = 0 D: 152366 [DMG]IM RH moving to [GeneratingReports] D: 152371 [EM]Sending Standalone Ack for MessageCounter:46575713 on exchange 56126i I: 152379 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 56126i with MessageCounter:132473453. I: 152394 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473453 to 0x00000000E0A03CBA (1) at monotonic time: 000000000002534A msec D: 152408 [EM]Flushed pending ack for MessageCounter:46575713 on exchange 56126i D: 152415 [EM]Retransmitting MessageCounter:132473450 on exchange 56125i Send Cnt 1 I: 152424 [IN]Sending encrypted msg 0x2000516c with MessageCounter:132473450 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000025368 msec D: 152438 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 152445 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:46575714 on exchange 56125i D: 152455 [EM]Found matching exchange: 56125i, Delegate: 0x20038524 D: 152462 [EM]Rxd Ack; Removing MessageCounter:132473450 from Retrans Table on exchange 56125i D: 152471 [EM]Removed CHIP MessageCounter:132473450 from RetransTable on exchange 56125i I: 152480 [DIS]Checking node lookup status after 202 ms I: 152486 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:46575715 on exchange 2107r I: 152497 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:46575716 on exchange 56125i D: 152507 [EM]Found matching exchange: 56125i, Delegate: 0x20038524 D: 152513 [EM]CHIP MessageCounter:132473450 not in RetransTable on exchange 56125i uart:~$ uart:~$ uart:~$ I: 161085 [EM]Received message of type 0x9 with protocolId (0, 1) and MessageCounter:46575717 on exchange 56125i D: 161096 [EM]Found matching exchange: 56125i, Delegate: 0x20038524 D: 161102 [EM]CHIP MessageCounter:132473450 not in RetransTable on exchange 56125i D: 161109 [DMG]ICR moving to [ResponseRe] I: 161113 [DMG]Received Command Response Data, Endpoint=2 Cluster=0x0000_0029 Command=0x0000_0001 D: 161122 [SWU]QueryImageResponse: D: 161125 [SWU] status: 2 D: 161128 [DMG]Endpoint 0, Cluster 0x0000_002A update version to 3c0442ce D: 161134 [DMG]Cannot merge the new path into any existing path, create one. D: 161141 [EVL]LogEvent event number: 0x0000000000000004 priority: 1, endpoint id: 0x0 cluster id: 0x0000_002A event id: 0x0 Sys timestamp: 0x0000000000027575 I: 161155 [SWU]Stopping the watchdog timer I: 161159 [SWU]Starting the periodic query timer, timeout: 86400 seconds D: 161166 [DMG]ICR moving to [AwaitingDe] D: 161170 [EM]Sending Standalone Ack for MessageCounter:46575717 on exchange 56125i I: 161178 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 56125i with MessageCounter:132473454. I: 161192 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473454 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000275A8 msec D: 161206 [EM]Flushed pending ack for MessageCounter:46575717 on exchange 56125i D: 161213 [DMG]Building Reports for ReadHandler with LastReportGeneration = 24 DirtyGeneration = 25 D: 161223 [DMG] Cluster 2a, Attribute 2 is dirty D: 161228 [DMG]Reading attribute: Cluster=0x0000_002A Endpoint=0 AttributeId=0x0000_0002 (expanded=1) D: 161242 [DMG] Sending report (payload has 40 bytes)... D: 161247 [DMG]IM RH moving to [AwaitingReportResponse] I: 161253 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 56127i with MessageCounter:132473455. I: 161267 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473455 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000275F3 msec I: 161281 [DMG]Refresh Subscribe Sync Timer with max 120 seconds D: 161287 [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages D: 161295 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 161300 [DMG]Unblock report hold after min 0 seconds I: 161310 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575718 on exchange 56127i D: 161320 [EM]Found matching exchange: 56127i, Delegate: 0x20010ff4 D: 161326 [EM]Rxd Ack; Removing MessageCounter:132473455 from Retrans Table on exchange 56127i D: 161334 [EM]Removed CHIP MessageCounter:132473455 from RetransTable on exchange 56127i I: 161342 [IM]Received status response, status is 0x00 D: 161347 [DMG] OnReportConfirm: NumReports = 0 D: 161352 [DMG]IM RH moving to [GeneratingReports] D: 161356 [EM]Sending Standalone Ack for MessageCounter:46575718 on exchange 56127i I: 161364 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 56127i with MessageCounter:132473456. I: 161379 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473456 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000027662 msec D: 161392 [EM]Flushed pending ack for MessageCounter:46575718 on exchange 56127i D: 161400 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 167278 [DIS]Checking node lookup status after 15000 ms E: 167284 [DIS]OperationalSessionSetup[1:00000000E0A03CBA]: operational discovery failed: 32 D: 188960 [DL]OpenThread State Changed (Flags: 0x00000064) D: 188966 [DL] Device Role: ROUTER uart:~$ uart:~$ I: 206163 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:46575719 on exchange 2110r D: 206173 [EM]Handling via exchange: 2110r, Delegate: 0x2000f54c D: 206180 [DMG]Received command for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0001 I: 206188 [ZCL]On/Off set value: 1 1 I: 206192 [ZCL]Toggle on/off from 0 to 1 I: 206196 [ZCL]On Command - OffWaitTime : 0 D: 206200 [DMG]Endpoint 1, Cluster 0x0000_0006 update version to 6944069d D: 206208 [DMG]Cannot merge the new path into any existing path, create one. I: 206214 [ZCL]On/Toggle Command - Stop Timer D: 206218 [DMG]Endpoint 1, Cluster 0x0000_0006 update version to 6944069e D: 206225 [DMG]Cannot merge the new path into any existing path, create one. D: 206248 [DMG]Endpoint 1, Cluster 0x0000_0006 update version to 6944069f D: 206254 [DMG]Cannot merge the new path into any existing path, create one. I: 206262 [ZCL]Cluster OnOff: attribute OnOff set to 1 I: Turn On Action has been initiated I: Turn On Action has been completed D: 206294 [DMG]Endpoint 1, Cluster 0x0000_0008 update version to 4bbed547 D: 206300 [DMG]Cannot merge the new path into any existing path, create one. I: 206307 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1 I: Level Action has been initiated I: Setting brightness level to 1 I: Level Action has been completed D: 206322 [DMG]Command handler moving to [ Preparing] D: 206327 [DMG]Command handler moving to [AddingComm] D: 206332 [DMG]Command handler moving to [AddedComma] D: 206337 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 206344 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x9 and protocolId (0, 1) on exchange 2110r with MessageCounter:132473457. I: 206358 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473457 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000032616 msec D: 206373 [DMG]Command handler moving to [CommandSen] D: 206378 [DMG]Command handler moving to [AwaitingDe] D: 206383 [DMG]Building Reports for ReadHandler with LastReportGeneration = 25 DirtyGeneration = 29 D: 206398 [DMG] Cluster 6, Attribute 0 is dirty D: 206403 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 206412 [DMG] Cluster 6, Attribute 4000 is dirty D: 206417 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4000 (expanded=1) D: 206426 [DMG] Cluster 6, Attribute 4002 is dirty D: 206432 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4002 (expanded=1) D: 206441 [DMG] Cluster 8, Attribute 0 is dirty D: 206446 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 206455 [DMG] Sending report (payload has 118 bytes)... D: 206461 [DMG]IM RH moving to [AwaitingReportResponse] I: 206467 [IN]Prepared secure message 0x2000513c to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 56128i with MessageCounter:132473458. I: 206481 [IN]Sending encrypted msg 0x2000513c with MessageCounter:132473458 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000032691 msec I: 206495 [DMG]Refresh Subscribe Sync Timer with max 120 seconds D: 206501 [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages D: 206509 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 206515 [ZCL]Event: move from 1 I: 206518 [ZCL] to 254 I: 206521 [ZCL](diff +1) D: 206538 [DMG]Endpoint 1, Cluster 0x0000_0008 update version to 4bbed548 D: 206545 [DMG]Cannot merge the new path into any existing path, create one. I: 206553 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 I: Level Action has been initiated I: Setting brightness level to 254 I: Level Action has been completed D: 206569 [DMG]Endpoint 1, Cluster 0x0000_0008 update version to 4bbed549 D: 206575 [DMG]Cannot merge the new path into any existing path, create one. D: 206582 [DMG]Unblock report hold after min 0 seconds I: 206588 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:46575720 on exchange 2110r D: 206598 [EM]Found matching exchange: 2110r, Delegate: (nil) D: 206603 [EM]Rxd Ack; Removing MessageCounter:132473457 from Retrans Table on exchange 2110r D: 206612 [EM]Removed CHIP MessageCounter:132473457 from RetransTable on exchange 2110r I: 206620 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575721 on exchange 56128i D: 206630 [EM]Found matching exchange: 56128i, Delegate: 0x20010ff4 D: 206636 [EM]Rxd Ack; Removing MessageCounter:132473458 from Retrans Table on exchange 56128i D: 206645 [EM]Removed CHIP MessageCounter:132473458 from RetransTable on exchange 56128i I: 206653 [IM]Received status response, status is 0x00 D: 206657 [DMG] OnReportConfirm: NumReports = 0 D: 206662 [DMG]IM RH moving to [GeneratingReports] D: 206667 [EM]Sending Standalone Ack for MessageCounter:46575721 on exchange 56128i I: 206675 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 56128i with MessageCounter:132473459. I: 206689 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473459 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000032761 msec D: 206703 [EM]Flushed pending ack for MessageCounter:46575721 on exchange 56128i D: 206710 [DMG]Building Reports for ReadHandler with LastReportGeneration = 29 DirtyGeneration = 31 D: 206724 [DMG] Cluster 8, Attribute 0 is dirty D: 206730 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 206739 [DMG] Cluster 8, Attribute 1 is dirty D: 206744 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0001 (expanded=1) D: 206754 [DMG] Sending report (payload has 66 bytes)... D: 206759 [DMG]IM RH moving to [AwaitingReportResponse] I: 206765 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 56129i with MessageCounter:132473460. I: 206779 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473460 to 0x00000000E0A03CBA (1) at monotonic time: 00000000000327BB msec I: 206793 [DMG]Refresh Subscribe Sync Timer with max 120 seconds D: 206799 [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages D: 206807 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 206813 [DMG]Unblock report hold after min 0 seconds I: 206830 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575722 on exchange 56129i D: 206840 [EM]Found matching exchange: 56129i, Delegate: 0x20010ff4 D: 206846 [EM]Rxd Ack; Removing MessageCounter:132473460 from Retrans Table on exchange 56129i D: 206855 [EM]Removed CHIP MessageCounter:132473460 from RetransTable on exchange 56129i I: 206863 [IM]Received status response, status is 0x00 D: 206868 [DMG] OnReportConfirm: NumReports = 0 D: 206872 [DMG]IM RH moving to [GeneratingReports] D: 206877 [EM]Sending Standalone Ack for MessageCounter:46575722 on exchange 56129i I: 206885 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 56129i with MessageCounter:132473461. I: 206899 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473461 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000032833 msec D: 206913 [EM]Flushed pending ack for MessageCounter:46575722 on exchange 56129i D: 206920 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet uart:~$ uart:~$ uart:~$ uart:~$ uart:~$ uart:~$ I: 211911 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:46575723 on exchange 2111r D: 211921 [EM]Handling via exchange: 2111r, Delegate: 0x2000f54c D: 211928 [DMG]Received command for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0000 I: 211935 [ZCL]On/Off set value: 1 0 I: 211939 [ZCL]Toggle on/off from 1 to 0 I: 211942 [ZCL]Off Command - OnTime : 0 D: 211946 [DMG]Endpoint 1, Cluster 0x0000_0006 update version to 694406a0 D: 211952 [DMG]Cannot merge the new path into any existing path, create one. D: 211979 [DMG]Endpoint 1, Cluster 0x0000_0006 update version to 694406a1 D: 211986 [DMG]Cannot merge the new path into any existing path, create one. I: 211993 [ZCL]Cluster OnOff: attribute OnOff set to 0 I: Turn Off Action has been initiated I: Turn Off Action has been completed D: 212004 [DMG]Command handler moving to [ Preparing] D: 212009 [DMG]Command handler moving to [AddingComm] D: 212014 [DMG]Command handler moving to [AddedComma] D: 212019 [DMG]Decreasing reference count for CommandHandler, remaining 0 I: 212026 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x9 and protocolId (0, 1) on exchange 2111r with MessageCounter:132473462. I: 212040 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473462 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000033C48 msec D: 212054 [DMG]Command handler moving to [CommandSen] D: 212059 [DMG]Command handler moving to [AwaitingDe] D: 212065 [DMG]Building Reports for ReadHandler with LastReportGeneration = 31 DirtyGeneration = 33 D: 212078 [DMG] Cluster 6, Attribute 0 is dirty D: 212084 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 212093 [DMG] Cluster 6, Attribute 4001 is dirty D: 212099 [DMG]Reading attribute: Cluster=0x0000_0006 Endpoint=1 AttributeId=0x0000_4001 (expanded=1) D: 212108 [DMG] Sending report (payload has 66 bytes)... D: 212114 [DMG]IM RH moving to [AwaitingReportResponse] I: 212120 [IN]Prepared secure message 0x2000513c to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 56130i with MessageCounter:132473463. I: 212134 [IN]Sending encrypted msg 0x2000513c with MessageCounter:132473463 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000033CA6 msec I: 212148 [DMG]Refresh Subscribe Sync Timer with max 120 seconds D: 212154 [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages D: 212162 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet I: 212168 [ZCL]Event: move from 254 I: 212172 [ZCL] to 1 I: 212174 [ZCL](diff -1) D: 212192 [DMG]Endpoint 1, Cluster 0x0000_0008 update version to 4bbed54a D: 212199 [DMG]Cannot merge the new path into any existing path, create one. I: 212206 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1 I: Level Action has been initiated I: Setting brightness level to 1 I: Level Action has been completed D: 212221 [DMG]Endpoint 1, Cluster 0x0000_0008 update version to 4bbed54b D: 212227 [DMG]Cannot merge the new path into any existing path, create one. D: 212234 [DMG]Unblock report hold after min 0 seconds I: 212240 [EM]Received message of type 0x10 with protocolId (0, 0) and MessageCounter:46575724 on exchange 2111r D: 212250 [EM]Found matching exchange: 2111r, Delegate: (nil) D: 212255 [EM]Rxd Ack; Removing MessageCounter:132473462 from Retrans Table on exchange 2111r D: 212264 [EM]Removed CHIP MessageCounter:132473462 from RetransTable on exchange 2111r I: 212272 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575725 on exchange 56130i D: 212282 [EM]Found matching exchange: 56130i, Delegate: 0x20010ff4 D: 212288 [EM]Rxd Ack; Removing MessageCounter:132473463 from Retrans Table on exchange 56130i D: 212297 [EM]Removed CHIP MessageCounter:132473463 from RetransTable on exchange 56130i I: 212305 [IM]Received status response, status is 0x00 D: 212309 [DMG] OnReportConfirm: NumReports = 0 D: 212314 [DMG]IM RH moving to [GeneratingReports] D: 212319 [EM]Sending Standalone Ack for MessageCounter:46575725 on exchange 56130i I: 212327 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 56130i with MessageCounter:132473464. I: 212341 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473464 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000033D75 msec D: 212355 [EM]Flushed pending ack for MessageCounter:46575725 on exchange 56130i D: 212362 [DMG]Building Reports for ReadHandler with LastReportGeneration = 33 DirtyGeneration = 35 D: 212376 [DMG] Cluster 8, Attribute 0 is dirty D: 212381 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0000 (expanded=1) D: 212390 [DMG] Cluster 8, Attribute 1 is dirty D: 212395 [DMG]Reading attribute: Cluster=0x0000_0008 Endpoint=1 AttributeId=0x0000_0001 (expanded=1) D: 212404 [DMG] Sending report (payload has 66 bytes)... D: 212410 [DMG]IM RH moving to [AwaitingReportResponse] I: 212416 [IN]Prepared secure message 0x20005124 to 0x00000000E0A03CBA (1) of type 0x5 and protocolId (0, 1) on exchange 56131i with MessageCounter:132473465. I: 212430 [IN]Sending encrypted msg 0x20005124 with MessageCounter:132473465 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000033DCE msec I: 212444 [DMG]Refresh Subscribe Sync Timer with max 120 seconds D: 212451 [DMG] ReportsInFlight = 1 with readHandler 0, RE has no more messages D: 212458 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet D: 212464 [DMG]Unblock report hold after min 0 seconds I: 212492 [EM]Received message of type 0x1 with protocolId (0, 1) and MessageCounter:46575726 on exchange 56131i D: 212502 [EM]Found matching exchange: 56131i, Delegate: 0x20010ff4 D: 212508 [EM]Rxd Ack; Removing MessageCounter:132473465 from Retrans Table on exchange 56131i D: 212517 [EM]Removed CHIP MessageCounter:132473465 from RetransTable on exchange 56131i I: 212525 [IM]Received status response, status is 0x00 D: 212530 [DMG] OnReportConfirm: NumReports = 0 D: 212534 [DMG]IM RH moving to [GeneratingReports] D: 212539 [EM]Sending Standalone Ack for MessageCounter:46575726 on exchange 56131i I: 212547 [IN]Prepared secure message 0x2002e4d0 to 0x00000000E0A03CBA (1) of type 0x10 and protocolId (0, 0) on exchange 56131i with MessageCounter:132473466. I: 212561 [IN]Sending encrypted msg 0x2002e4d0 with MessageCounter:132473466 to 0x00000000E0A03CBA (1) at monotonic time: 0000000000033E51 msec D: 212575 [EM]Flushed pending ack for MessageCounter:46575726 on exchange 56131i D: 212582 [DMG]All ReadHandler-s are clean, clear GlobalDirtySet uart:~$ uart:~$ uart:~$