• State Verified Answer
  • Locked Locked
  • Replies 3 replies
  • Subscribers 74 subscribers
  • Views 779 views
  • Users 0 members are here
Attachments (4) Download All
Nordic Case Info
  • Case ID: 288784
Options
This discussion has been locked.
You can no longer post new replies to this discussion. If you have a question you can start a new discussion

Reset CPU lockup error when using USB CDC ACM as backend on Matter Template example (SDK v1.9.99)

Carla31

Hello, I was trying to run the Matter template example on board nRF5340 DK.

By default, console and shell outputs used for this example, come via UART0 of the board. I'm using the prj.conf file. The example works as expected. In fact, using the CHIP-TOOL as Matter controller on Raspberry PI, I'm able to add my device (running the Matter template) to my Thread network. 

However, when I want to use USB CDC ACM as backend for console and shell, instead of UART0, after commissioning, my device resets in a loop. To use USB CDC ACM I added 2 overlay files to the original project Matter template :

- an overlay file containing extra Kconfig options to enable USB CDC ACM

usb-overlay.conf

- an overlay device tree file to active USB CDC ACM node and to link it to console and shell

usb.overlay

Here, you can see the output of my Matter template example which uses USB CDC ACM as backend for console and shell. My program starts resetting at line 944 after getting Thread network credentials (PAN Id, channel, mesh prefix, etc)

Fullscreen Matter_template_output_usb_cdc_acm.txt Download 
uart:~$ 
Board: lx_nrf5340dk_nrf5340_cpuapp
E: cause n 1
I: Init CHIP stack
D: 1947 [DL]Boot reason: 1
I: 2017 [DL]BLE address: DE:94:E1:36:E5:B9                              
I: 2045 [DL]OpenThread started: OK                                      
I: 2051 [DL]Setting OpenThread device type to MINIMAL END DEVICE        
I: Swap type: none                                                      
I: 2069 [ZCL]Using ZAP configuration...                                 
I: 2077 [ZCL]Initiating Admin Commissioning cluster.
I: 2087 [ZCL]OpCreds: Initiating OpCreds cluster by writing fabrics list from fabric table.
D: 2104 [DIS]Add fabric pairing table delegate
D: 2113 [DIS]Init fabric pairing table with server storage
I: 2124 [IN]Loading from storage for fabric index 1
I: 2134 [IN]Loading from storage for fabric index 2
I: 2143 [IN]Loading from storage for fabric index 3
I: 2153 [IN]Loading from storage for fabric index 4
I: 2163 [IN]Loading from storage for fabric index 5
I: 2173 [DMG]AccessControl: initializing
I: 2180 [DMG]Examples::AccessControlDelegate::Init
I: 2190 [DMG]AccessControl: unable to load stored ACL entries; using empty list instead
I: 2206 [DMG]AccessControl: setting
D: 2213 [IN]UDP::Init bind&listen port=5540
E: 2221 [IN]SO_REUSEPORT failed: 109
D: 2229 [IN]UDP::Init bound to port=5540
D: 2236 [IN]BLEBase::Init - setting/overriding transport
D: 2247 [IN]TransportMgr initialized
D: 2257 [DIS]Add fabric pairing table delegate
D: 2266 [DL]Using Thread extended MAC for hostname.
I: 2278 [IN]Loading from storage for fabric index 1
I: 2289 [IN]Loading from storage for fabric index 2
I: 2301 [IN]Loading from storage for fabric index 3
I: 2313 [IN]Loading from storage for fabric index 4
I: 2325 [IN]Loading from storage for fabric index 5
D: 2338 [SC]Assigned local session key ID 1
D: 2347 [SC]Waiting for PBKDF param request
D: 2355 [DL]CHIPoBLE advertising set to on
I: 2363 [DIS]Updating services using commissioning mode 1
D: 2374 [DL]Using Thread extended MAC for hostname.
D: 2384 [DL]Using Thread extended MAC for hostname.
I: 2394 [DIS]Advertise commission parameter vendorID=65521 productID=32768 discriminator=38405
E: 2413 [DIS]Failed to advertise commissionable node: Error CHIP:0x00000003
D: 2427 [DIS]Scheduling discovery timeout in 900s
E: 2436 [DIS]Failed to finalize service update: Error CHIP:0x0000001C
I: 2449 [DIS]Updating services using commissioning mode 1
D: 2460 [DL]Using Thread extended MAC for hostname.
D: 2470 [DL]Using Thread extended MAC for hostname.
I: 2480 [DIS]Advertise commission parameter vendorID=65521 productID=32768 discriminator=38405
E: 2498 [DIS]Failed to advertise commissionable node: Error CHIP:0x00000003
D: 2512 [DIS]Scheduling discovery timeout in 900s
E: 2522 [DIS]Failed to finalize service update: Error CHIP:0x0000001C
I: 2535 [IN]CASE Server enabling CASE session setups
I: 2545 [DIS]Delaying proxy of operational discovery: missing delegate
I: 2558 [SVR]Joining Multicast groups
D: 2565 [ZCL]Emitting StartUp event
D: 2572 [EVL]LogEvent event number: 0x0000000000000000 priority: 2, endpoint id:  0x0 clusterC
D: 2601 [ZCL]GeneralDiagnosticsDelegate: OnDeviceRebooted
D: 2611 [DMG]Endpoint 0, Cluster 0x0000_0033 update version to 794a13
D: 2624 [EVL]LogEvent event number: 0x0000000000000001 priority: 2, endpoint id:  0x0 cluster0
I: 2653 [SVR]Server Listening...
I: 2659 [DL]Device Configuration:
I: 2666 [DL]  Serial Number: TEST_SN
I: 2674 [DL]  Vendor Id: 65521 (0xFFF1)
I: 2681 [DL]  Product Id: 32768 (0x8000)
I: 2689 [DL]  Hardware Version: 0
I: 2696 [DL]  Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
I: 2708 [DL]  Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
I: 2722 [DL]  Manufacturing Date: (not set)
I: 2730 [DL]  Device Type: 65535 (0xFFFF)
I: 2740 [SVR]SetupQRCode: [MT:Y.K9042C00KA0648G00]
I: 2749 [SVR]Copy/paste the below URL in a browser to see the QR Code:
I: 2762 [SVR]https://dhrishi.github.io/connectedhomeip/qrcode.html?data=MT%3AY.K9042C00KA06480
I: 2780 [SVR]Manual pairing code: [34970112332]
I: 2789 [DL]CHIP task running
I: 2800 [DL]CHIPoBLE advertising started
D: 2810 [DMG]Endpoint 0, Cluster 0x0000_002A update version to 1f09d4ef
D: 2823 [DMG]Endpoint 0, Cluster 0x0000_002A update version to 1f09d4f0
D: 2837 [EVL]LogEvent event number: 0x0000000000000002 priority: 1, endpoint id:  0x0 cluster4
I: 2866 [SWU]Starting the Default Provider timer, timeout: 86400 seconds
I: 32810 [DL]CHIPoBLE advertising mode changed to slow
I: 51570 [DL]BLE connection established (ConnId: 0x00)
I: 51580 [DL]Current number of connections: 1/1
I: 51589 [DL]CHIPoBLE advertising stopped
D: 53499 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 53512 [BLE]local and remote recv window sizes = 5
I: 53521 [BLE]selected BTP version 4
I: 53528 [BLE]using BTP fragment sizes rx 20 / tx 20.
D: 53597 [DL]ConnId: 0x00, New CCCD value: 0x0001
D: 53606 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 6)
D: 53619 [IN]BLE EndPoint 0x20009668 Connection Complete
I: 53629 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 23)
D: 53694 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 53706 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 53792 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 53889 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 53987 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 53999 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 54084 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 54096 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 54108 [EM]Received message of type 0x20 with protocolId (0, 0) and MessageCounter:10340223r
D: 54129 [EM]Handling via exchange: 40435r, Delegate: 0x20006198
D: 54141 [SC]Received PBKDF param request
D: 54149 [SC]Peer assigned session ID 1
D: 54157 [SC]Found MRP parameters in the message
D: 54166 [SC]Including MRP parameters in PBKDF param response
I: 54178 [IN]Prepared unauthenticated message 0x2002cc20 to 0x0000000000000000 (0)  of type 0.
I: 54209 [IN]Sending unauthenticated msg 0x2002cc20 with MessageCounter:1462936239 to 0x00000c
D: 54236 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 54249 [SC]Sent PBKDF param response
E: 54257 [DL]Long dispatch time: 160 ms, for event type 16388
D: 54328 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 54339 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 54425 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 54436 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 54523 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 54534 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 54547 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 54620 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 54631 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 54718 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 54729 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 54815 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 54826 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 54913 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 54924 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 54937 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 14)
D: 55011 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 55022 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 55108 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 55206 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 55218 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 55303 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 55314 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 55401 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 55498 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 55511 [EM]Received message of type 0x22 with protocolId (0, 0) and MessageCounter:10340223r
D: 55532 [EM]Found matching exchange: 40435r, Delegate: 0x20006198
D: 55544 [SC]Received spake2p msg1
I: 57130 [IN]Prepared unauthenticated message 0x2002cbb0 to 0x0000000000000000 (0)  of type 0.
I: 57161 [IN]Sending unauthenticated msg 0x2002cbb0 with MessageCounter:1462936240 to 0x00000c
D: 57187 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 57200 [SC]Sent spake2p msg2
E: 57207 [DL]Long dispatch time: 1709 ms, for event type 16388
D: 57255 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 57266 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 57345 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 57356 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 57435 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 57446 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 57526 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 57537 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 57549 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 57615 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 57626 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 57705 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 57716 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 57795 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 57807 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 7)
D: 57886 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 57897 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 57976 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 58066 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 58156 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 58168 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 58246 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 58257 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 58269 [EM]Received message of type 0x24 with protocolId (0, 0) and MessageCounter:10340223r
D: 58291 [EM]Found matching exchange: 40435r, Delegate: 0x20006198
D: 58303 [SC]Received spake2p msg3
D: 58310 [SC]Sending status report. Protocol code 0, exchange 40435
I: 58323 [IN]Prepared unauthenticated message 0x2002cc38 to 0x0000000000000000 (0)  of type 0.
I: 58354 [IN]Sending unauthenticated msg 0x2002cc38 with MessageCounter:1462936241 to 0x00000c
D: 58381 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 58394 [IN]New secure session created for device 0xFFFFFFFB00000000, LSID:1 PSID:1!
I: 58411 [SVR]Commissioning completed session establishment step
I: 58424 [DIS]Updating services using commissioning mode 0
D: 58435 [DL]Using Thread extended MAC for hostname.
D: 58445 [DL]Using Thread extended MAC for hostname.
I: 58456 [DIS]Advertise commission parameter vendorID=65521 productID=32768 discriminator=3845
E: 58474 [DIS]Failed to advertise extended commissionable node: Error CHIP:0x00000003
D: 58491 [DIS]Scheduling extended discovery timeout in 900s
E: 58502 [DIS]Failed to finalize service update: Error CHIP:0x0000001C
D: 58515 [DL]CHIPoBLE advertising set to off
I: 58524 [SVR]Device completed Rendezvous process
E: 58533 [DL]Long dispatch time: 276 ms, for event type 16388
D: 58545 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 58556 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 17)
D: 58651 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 58662 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 58741 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 58831 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 58921 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 58933 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 59011 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 59022 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 59101 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 59191 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 59281 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 59293 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
I: 59307 [EM]Received message of type 0x2 with protocolId (0, 1) and MessageCounter:6441139 or
D: 59327 [EM]Handling via exchange: 40436r, Delegate: 0x20008b00
D: 59339 [IM]Received Read request
D: 59348 [DMG]IM RH moving to [GeneratingReports]
D: 59358 [DMG]<RE:Run> Cluster 31, Attribute 3 is dirty
D: 59368 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0003 (expa)
I: 59387 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=v
I: 59404 [DMG]AccessControl: implicit admin (PASE)
D: 59413 [DMG]<RE:Run> Cluster 28, Attribute 4 is dirty
D: 59424 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0004 (expa)
I: 59442 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0028 e=0 p=v
I: 59459 [DMG]AccessControl: implicit admin (PASE)
D: 59469 [DMG]<RE:Run> Cluster 28, Attribute 2 is dirty
D: 59479 [DMG]Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0002 (expa)
I: 59498 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0028 e=0 p=v
I: 59515 [DMG]AccessControl: implicit admin (PASE)
D: 59524 [DMG]<RE:Run> Cluster 30, Attribute 3 is dirty
D: 59535 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0003 (expa)
I: 59553 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v
I: 59570 [DMG]AccessControl: implicit admin (PASE)
D: 59580 [DMG]<RE:Run> Cluster 30, Attribute 2 is dirty
D: 59590 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0002 (expa)
I: 59609 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v
I: 59626 [DMG]AccessControl: implicit admin (PASE)
D: 59636 [DMG]<RE:Run> Cluster 30, Attribute 1 is dirty
D: 59646 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0001 (expa)
I: 59664 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v
I: 59681 [DMG]AccessControl: implicit admin (PASE)
D: 59691 [DMG]<RE:Run> Cluster 30, Attribute 0 is dirty
D: 59701 [DMG]Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0000 (expa)
I: 59720 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v
I: 59737 [DMG]AccessControl: implicit admin (PASE)
D: 59747 [DMG]<RE:Run> Cluster 31, Attribute fffc is dirty
D: 59758 [DMG]Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFC (expa)
I: 59776 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=v
I: 59793 [DMG]AccessControl: implicit admin (PASE)
D: 59803 [DMG]<RE> Sending report (payload has 224 bytes)...
I: 59816 [IN]Prepared secure message 0x2002ce98 to 0xFFFFFFFB00000000 (0)  of type 0x5 and pr.
I: 59844 [IN]Sending encrypted msg 0x2002ce98 with MessageCounter:1139004 to 0xFFFFFFFB000000c
D: 59870 [DMG]<RE> OnReportConfirm: NumReports = 0
D: 59879 [DMG]<RE> ReportsInFlight = 0 with readHandler 0, RE has no more messages
D: 59895 [DMG]IM RH moving to [AwaitingDestruction]
D: 59904 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 59916 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60000 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60012 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60090 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60101 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60181 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60192 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 60204 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60270 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60281 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60360 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60372 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60450 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60462 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60541 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60552 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 60564 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60630 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60641 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60720 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60732 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60810 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60822 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60901 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 60912 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 60924 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 60990 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 61001 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 61080 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 61091 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 61170 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 61181 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 9)
D: 61261 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 61272 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 61351 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 61441 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 61531 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 61543 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 61621 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 61632 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 61645 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441140 or
D: 61665 [EM]Handling via exchange: 40437r, Delegate: 0x20008b00
I: 61677 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=o
I: 61694 [DMG]AccessControl: implicit admin (PASE)
D: 61704 [DMG]Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0000
D: 61720 [DMG]ICR moving to [AddingComm]
D: 61728 [DMG]ICR moving to [AddedComma]
D: 61735 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 61750 [IN]Prepared secure message 0x2002ccc8 to 0xFFFFFFFB00000000 (0)  of type 0x9 and pr.
I: 61778 [IN]Sending encrypted msg 0x2002ccc8 with MessageCounter:1139005 to 0xFFFFFFFB000000c
D: 61804 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 61817 [DMG]ICR moving to [CommandSen]
D: 61825 [DMG]ICR moving to [AwaitingDe]
E: 61833 [DL]Long dispatch time: 201 ms, for event type 16388
D: 61890 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 61901 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 61980 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 61992 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 62071 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 62082 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 62094 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 17)
D: 62161 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 62172 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 62251 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 62341 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 62353 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 62431 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 62442 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 62521 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 62534 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441141 or
D: 62554 [EM]Handling via exchange: 40438r, Delegate: 0x20008b00
I: 62566 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=o
I: 62583 [DMG]AccessControl: implicit admin (PASE)
D: 62593 [DMG]Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0002
D: 62613 [DMG]ICR moving to [AddingComm]
D: 62621 [DMG]ICR moving to [AddedComma]
D: 62629 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 62643 [IN]Prepared secure message 0x2002ccc8 to 0xFFFFFFFB00000000 (0)  of type 0x9 and pr.
I: 62672 [IN]Sending encrypted msg 0x2002ccc8 with MessageCounter:1139006 to 0xFFFFFFFB000000c
D: 62698 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 62711 [DMG]ICR moving to [CommandSen]
D: 62719 [DMG]ICR moving to [AwaitingDe]
E: 62726 [DL]Long dispatch time: 205 ms, for event type 16388
D: 62790 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 62801 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 62880 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 62892 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 62970 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 62982 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 17)
D: 63061 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 63072 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 63151 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 63241 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 63331 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 63343 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 63421 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 63432 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 63445 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441142 or
D: 63465 [EM]Handling via exchange: 40439r, Delegate: 0x20008b00
I: 63478 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o
I: 63495 [DMG]AccessControl: implicit admin (PASE)
D: 63504 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002
I: 63520 [ZCL]OpCreds: Certificate Chain request received for PAI
D: 63532 [DMG]ICR moving to [AddingComm]
D: 63540 [DMG]ICR moving to [AddedComma]
D: 63548 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 63564 [IN]Prepared secure message 0x2002ccc8 to 0xFFFFFFFB00000000 (0)  of type 0x9 and pr.
I: 63593 [IN]Sending encrypted msg 0x2002ccc8 with MessageCounter:1139007 to 0xFFFFFFFB000000c
D: 63619 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 63632 [DMG]ICR moving to [CommandSen]
D: 63640 [DMG]ICR moving to [AwaitingDe]
E: 63648 [DL]Long dispatch time: 216 ms, for event type 16388
D: 63690 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 63702 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 63780 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 63792 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 63871 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 63882 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 63894 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 63960 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 63972 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64050 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64062 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64140 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64152 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64231 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64242 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 64254 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64320 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64331 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64410 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64422 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64500 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64512 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64591 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64602 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 64614 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64680 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64691 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64770 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64781 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64860 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64872 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 64951 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 64962 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 64974 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65040 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65052 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65130 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65141 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65220 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65232 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65311 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65322 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 65334 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65400 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65412 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65490 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65502 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65580 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65592 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65671 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65682 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 65694 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65760 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65772 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65850 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65862 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 65940 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 65952 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 66031 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 66042 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 66054 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 66120 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 66131 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 66210 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 66222 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 17)
D: 66301 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 66312 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 66391 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 66481 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 66571 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 66583 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
I: 66597 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441143 or
D: 66617 [EM]Handling via exchange: 40440r, Delegate: 0x20008b00
I: 66630 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o
I: 66647 [DMG]AccessControl: implicit admin (PASE)
D: 66656 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002
I: 66672 [ZCL]OpCreds: Certificate Chain request received for DAC
D: 66684 [DMG]ICR moving to [AddingComm]
D: 66692 [DMG]ICR moving to [AddedComma]
D: 66700 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 66717 [IN]Prepared secure message 0x2002ccc8 to 0xFFFFFFFB00000000 (0)  of type 0x9 and pr.
I: 66745 [IN]Sending encrypted msg 0x2002ccc8 with MessageCounter:1139008 to 0xFFFFFFFB000000c
D: 66771 [DMG]ICR moving to [CommandSen]
D: 66778 [DMG]ICR moving to [AwaitingDe]
E: 66786 [DL]Long dispatch time: 215 ms, for event type 16388
D: 66798 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 66809 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 66885 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 66896 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 66975 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 66987 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67066 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67077 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 67089 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67155 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67166 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67245 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67256 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67335 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67347 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67426 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67437 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 67450 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67515 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67527 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67605 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67617 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67695 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67707 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67786 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67797 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 67809 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67875 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67886 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 67965 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 67977 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68055 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68067 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68146 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68157 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 68169 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68235 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68247 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68325 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68336 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68415 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68426 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68506 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68517 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 68529 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68595 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68607 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68685 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68697 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68775 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68787 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68866 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68877 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 68889 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 68955 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 68967 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 69045 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 69057 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 69135 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 69147 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 69226 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 69237 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 69249 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 69315 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 69327 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 69405 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 69417 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 69495 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 69507 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 69586 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 69597 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 69609 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 10)
D: 69676 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 69687 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 69766 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 69856 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 69868 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 69946 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 69957 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 70036 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 70126 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 70139 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441144 or
D: 70159 [EM]Handling via exchange: 40441r, Delegate: 0x20008b00
I: 70172 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o
I: 70189 [DMG]AccessControl: implicit admin (PASE)
D: 70198 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0000
I: 70214 [ZCL]OpCreds: received an AttestationRequest
D: 70254 [DMG]ICR moving to [AddingComm]
D: 70262 [DMG]ICR moving to [AddedComma]
D: 70270 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 70287 [IN]Prepared secure message 0x2002ccc8 to 0xFFFFFFFB00000000 (0)  of type 0x9 and pr.
I: 70316 [IN]Sending encrypted msg 0x2002ccc8 with MessageCounter:1139009 to 0xFFFFFFFB000000c
D: 70342 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 70355 [DMG]ICR moving to [CommandSen]
D: 70363 [DMG]ICR moving to [AwaitingDe]
E: 70370 [DL]Long dispatch time: 244 ms, for event type 16388
D: 70440 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 70452 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 70530 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 70542 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 70620 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 70631 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 70711 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 70722 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 70734 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 70800 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 70812 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 70890 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 70902 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 70980 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 70992 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71071 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71082 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 71094 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71160 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71172 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71250 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71262 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71340 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71352 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71431 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71442 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 71454 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71520 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71532 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71610 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71622 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71700 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71712 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71791 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71802 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 71814 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71880 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71892 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 71970 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71981 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72060 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72072 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72151 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72162 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 72174 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72240 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72252 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72330 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72342 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72420 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72432 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72511 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72522 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 72535 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72600 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72612 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72690 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72702 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72825 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72837 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 72916 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 72927 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 72940 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73005 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73017 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73095 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73107 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73185 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73197 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73276 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73287 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 73299 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73365 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73376 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73455 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73467 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73545 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73557 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73636 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73647 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 73659 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73725 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73737 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73815 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73827 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73905 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 73917 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 73996 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 74007 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 74019 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 10)
D: 74086 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 74097 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 74176 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 74266 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 74278 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 74356 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 74367 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 74491 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 74581 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 74594 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441145 or
D: 74614 [EM]Handling via exchange: 40442r, Delegate: 0x20008b00
I: 74627 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o
I: 74644 [DMG]AccessControl: implicit admin (PASE)
D: 74653 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0004
I: 74669 [ZCL]OpCreds: commissioner has requested a CSR
I: 74973 [ZCL]OpCreds: NewCertificateSigningRequest returned 0
D: 75014 [DMG]ICR moving to [AddingComm]
D: 75022 [DMG]ICR moving to [AddedComma]
D: 75030 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 75046 [IN]Prepared secure message 0x2002ccc8 to 0xFFFFFFFB00000000 (0)  of type 0x9 and pr.
I: 75074 [IN]Sending encrypted msg 0x2002ccc8 with MessageCounter:1139010 to 0xFFFFFFFB000000c
D: 75100 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75113 [DMG]ICR moving to [CommandSen]
D: 75121 [DMG]ICR moving to [AwaitingDe]
E: 75128 [DL]Long dispatch time: 547 ms, for event type 16388
D: 75165 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75177 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75255 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75267 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75345 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75357 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75436 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75447 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 75459 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75525 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75537 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75615 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75627 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75705 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75717 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75796 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75807 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 75819 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75885 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75897 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 75975 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 75987 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76065 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76077 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76156 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76167 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 76180 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76245 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76257 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76335 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76347 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76425 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76437 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76516 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76527 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 76540 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76605 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76617 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76695 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76707 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76785 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76797 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76876 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76887 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 76899 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 76965 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 76977 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 8)
D: 77056 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 77067 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77146 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77236 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77326 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77338 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 77416 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 77428 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77506 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77596 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77686 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77698 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 77776 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 77787 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77866 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 77956 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 78046 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 78058 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 78136 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 78147 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 78226 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 78316 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 78406 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 78418 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 78496 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 78507 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 78522 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441146 or
D: 78542 [EM]Handling via exchange: 40443r, Delegate: 0x20008b00
I: 78554 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o
I: 78571 [DMG]AccessControl: implicit admin (PASE)
D: 78581 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B
I: 78597 [ZCL]OpCreds: commissioner has added a trusted root Cert
D: 78609 [DMG]ICR moving to [AddingComm]
D: 78617 [DMG]ICR moving to [AddedComma]
D: 78624 [DMG]Endpoint 0, Cluster 0x0000_003E update version to f68bfa6a
D: 78638 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 78652 [IN]Prepared secure message 0x2002ccc8 to 0xFFFFFFFB00000000 (0)  of type 0x9 and pr.
I: 78681 [IN]Sending encrypted msg 0x2002ccc8 with MessageCounter:1139011 to 0xFFFFFFFB000000c
D: 78706 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 78719 [DMG]ICR moving to [CommandSen]
D: 78727 [DMG]ICR moving to [AwaitingDe]
E: 78735 [DL]Long dispatch time: 228 ms, for event type 16388
D: 78810 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 78822 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 78900 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 78912 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 78991 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 79002 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79015 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 14)
D: 79081 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 79092 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79171 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79261 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79273 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 79351 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 79362 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79441 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79531 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79621 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79633 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 79711 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 79723 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79801 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79891 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79981 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 79993 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 80071 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 80082 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80161 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80251 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80341 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80353 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 80431 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 80442 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80521 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80611 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80701 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80713 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 80791 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 80802 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80881 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 80971 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81061 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81073 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 81151 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 81162 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81241 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81331 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81466 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81478 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 81556 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 81567 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81646 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81736 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81826 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 81838 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 81916 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 81927 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 81943 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441147 or
D: 81963 [EM]Handling via exchange: 40444r, Delegate: 0x20008b00
I: 81976 [DMG]AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=o
I: 81992 [DMG]AccessControl: implicit admin (PASE)
D: 82002 [DMG]Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0006
I: 82018 [ZCL]OpCreds: commissioner has added a NOC
I: 82028 [IN]Loading from storage for fabric index 1
I: 82039 [DIS]Verifying the received credentials
D: 82158 [IN]Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000001 y
D: 82178 [IN]0x04, 0xa6, 0x3c, 0x8e, 0xc8, 0x03, 0x65, 0x4d, 
D: 82190 [IN]0x22, 0x18, 0x70, 0x30, 0xdc, 0xb7, 0x57, 0x0e, 
D: 82202 [IN]0x7f, 0x42, 0xa6, 0x22, 0xa8, 0x52, 0xd4, 0xa8, 
D: 82213 [IN]0x29, 0xe2, 0x6d, 0x2c, 0x1a, 0x09, 0xf6, 0x6d, 
D: 82225 [IN]0x13, 0x0b, 0x0a, 0x59, 0x24, 0x38, 0x15, 0x25, 
D: 82236 [IN]0xec, 0xd9, 0xc3, 0xf2, 0xac, 0x0d, 0x0b, 0xe3, 
D: 82248 [IN]0xc1, 0x11, 0x64, 0x2d, 0xea, 0x7a, 0xdc, 0x49, 
D: 82259 [IN]0x0e, 0x60, 0xd6, 0xf7, 0x4e, 0xd8, 0xe2, 0x5f, 
D: 82271 [IN]0x76, 
D: 82276 [IN]Generated compressed fabric ID
D: 82284 [IN]0xfd, 0x74, 0x4a, 0x7d, 0x5e, 0x7c, 0x34, 0xc8, 
I: 82295 [DIS]Added new fabric at index: 1, Initialized: 1
I: 82306 [DIS]Assigned compressed fabric ID: 0xFD744A7D5E7C34C8, node ID: 0x0000000000000001
I: 82323 [DIS]Fabric (1) loaded from storage. Calling OnFabricRetrievedFromStorage
I: 82339 [DIS]Fabric (1) loaded from storage. Calling OnFabricRetrievedFromStorage
I: 82354 [ZCL]OpCreds: Fabric 0x1 was retrieved from storage. FabricId 0x0000000000000001, No1
I: 82378 [ZCL]OpCreds: Call to fabricListChanged
D: 82387 [DMG]Endpoint 0, Cluster 0x0000_003E update version to f68bfa6b
D: 82401 [DMG]Endpoint 0, Cluster 0x0000_003E update version to f68bfa6c
I: 82432 [DIS]Fabric (1) persisted to storage. Calling OnFabricPersistedToStorage
I: 82447 [ZCL]OpCreds: Fabric 1 was persisted to storage. FabricId 0000000000000001, NodeId 01
I: 82470 [ZCL]OpCreds: Call to fabricListChanged
D: 82479 [DMG]Endpoint 0, Cluster 0x0000_003E update version to f68bfa6d
D: 82492 [DMG]Endpoint 0, Cluster 0x0000_003E update version to f68bfa6e
I: 82506 [DIS]Fabric (1) loaded from storage. Calling OnFabricRetrievedFromStorage
I: 82521 [DIS]Fabric (1) loaded from storage. Calling OnFabricRetrievedFromStorage
I: 82537 [ZCL]OpCreds: Fabric 0x1 was retrieved from storage. FabricId 0x0000000000000001, No1
I: 82561 [ZCL]OpCreds: Call to fabricListChanged
D: 82570 [DMG]Endpoint 0, Cluster 0x0000_003E update version to f68bfa6f
D: 82584 [DMG]Endpoint 0, Cluster 0x0000_003E update version to f68bfa70
I: 82598 [DIS]Fabric (1) persisted to storage. Calling OnFabricPersistedToStorage
I: 82613 [ZCL]OpCreds: Fabric 1 was persisted to storage. FabricId 0000000000000001, NodeId 01
I: 82637 [ZCL]OpCreds: Call to fabricListChanged
D: 82646 [DMG]Endpoint 0, Cluster 0x0000_003E update version to f68bfa71
D: 82659 [DMG]Endpoint 0, Cluster 0x0000_003E update version to f68bfa72
I: 82673 [DMG]AccessControl: validating f=1 p=a a=c s=1 t=0
I: 82684 [DMG]  validating subject 0x000000000001B669
I: 82765 [ZCL]OpCreds: ACL entry created for Fabric 1 CASE Admin NodeId 0x000000000001B669
D: 82824 [DL]Using Thread extended MAC for hostname.
I: 82834 [DIS]Advertise operational node FD744A7D5E7C34C8-0000000000000001
D: 82848 [DMG]ICR moving to [AddingComm]
D: 82856 [DMG]ICR moving to [AddedComma]
I: 82864 [ZCL]OpCreds: successfully added a NOC
D: 82873 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 82887 [IN]Prepared secure message 0x2002ccc8 to 0xFFFFFFFB00000000 (1)  of type 0x9 and pr.
I: 82916 [IN]Sending encrypted msg 0x2002ccc8 with MessageCounter:1139012 to 0xFFFFFFFB000000c
D: 82942 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 82955 [DMG]ICR moving to [CommandSen]
D: 82963 [DMG]ICR moving to [AwaitingDe]
E: 82970 [DL]Long dispatch time: 1043 ms, for event type 16388
D: 83040 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 83052 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 83130 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 83142 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 83221 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 83232 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 83245 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 17)
D: 83311 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 83322 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 83401 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 83491 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 83503 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 83581 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 83592 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 83671 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 83761 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 83851 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 83863 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 83941 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 83953 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 84031 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 84121 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 84135 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441148 or
D: 84155 [EM]Handling via exchange: 40445r, Delegate: 0x20008b00
I: 84168 [DMG]AccessControl: checking f=1 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=o
I: 84185 [DMG]AccessControl: implicit admin (PASE)
D: 84194 [DMG]Received command for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0003
D: 84210 [DMG]ICR moving to [AddingComm]
D: 84218 [DMG]ICR moving to [AddedComma]
D: 84226 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 84240 [IN]Prepared secure message 0x2002ccc8 to 0xFFFFFFFB00000000 (1)  of type 0x9 and pr.
I: 84269 [IN]Sending encrypted msg 0x2002ccc8 with MessageCounter:1139013 to 0xFFFFFFFB000000c
D: 84295 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 84308 [DMG]ICR moving to [CommandSen]
D: 84316 [DMG]ICR moving to [AwaitingDe]
E: 84324 [DL]Long dispatch time: 203 ms, for event type 16388
D: 84390 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 84402 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 84480 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 84492 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 20)
D: 84570 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 84582 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 17)
D: 84661 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 84672 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 84751 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 84841 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 84931 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 84943 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 85021 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)   
D: 85032 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 85111 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
I: 85124 [EM]Received message of type 0x8 with protocolId (0, 1) and MessageCounter:6441149 or
D: 85144 [EM]Handling via exchange: 40446r, Delegate: 0x20008b00
I: 85157 [DMG]AccessControl: checking f=1 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=o
I: 85174 [DMG]AccessControl: implicit admin (PASE)
D: 85183 [DMG]Received command for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0006
D: 85237 [DMG]Decreasing reference count for CommandHandler, remaining 1
E: 85250 [DL]Long dispatch time: 139 ms, for event type 16388  
D: 85262 [DL]OpenThread State Changed (Flags: 0x1017c100)      
D: 85273 [DL]   Network Name: OpenThreadDemo                   
D: 85281 [DL]   PAN Id: 0x1234                                 
D: 85287 [DL]   Extended PAN Id: 0x1111111122222222
D: 85297 [DL]   Channel: 15
D: 85302 [DL]   Mesh Prefix: fd7c:2f41:9d29:5736::/64
I: 85313 [DL]CHIPoBLE advertising disabled because device is fully provisioned
D: 85328 [DL]OpenThread State Changed (Flags: 0x1100101d)
D: 85338 [DL]   Device Role: DETACHED
D: 85345 [DL]   Thread Unicast Addresses:                      
D: 85353 [DL]        fd7c:2f41:9d29:5736:fa90:4cbc:359c:1ba5/64 valid
D: 85367 [DL]        fe80::207e:c687:8831:6586/64 valid preferred
I: 86456 [DL]SRP Client was started, detected server: fd7c:2f41:9d29:5736:1e60:ad27:16e9:5110
D: 86457 [DL]OpenThread State Changed (Flags: 0x200002a4)      
D: 86466 [DL]   Device Role: CHILD
D: 86466 [DL]   Partition Id: 0x32F6360F
E: 86687 [DL]Long dispatch time: 230 ms, for event type 32780
D: 86705 [DL]OpenThread State Changed (Flags: 0x00000001)
D: 86706 [DL]   Thread Unicast Addresses:
D: 86706 [DL]        fd11:22::85fd:adf4:8899:50aa/64 valid preferred
D: 86707 [DL]        fd7c:2f41:9d29:5736:0:ff:fe00:bc01/64 valid rloc
D: 86707 [DL]        fd7c:2f41:9d29:5736:fa90:4cbc:359c:1ba5/64 valid
D: 86708 [DL]        fe80::207e:c687:8831:6586/64 valid preferred
D: 86711 [DMG]ICR moving to [AddingComm]                       
D: 86722 [DMG]ICR moving to [AddedComma]                       
D: 86731 [DL]Using Thread extended MAC for hostname.
I: 86741 [DIS]Advertise operational node FD744A7D5E7C34C8-0000000000000001
E: 86755 [SVR]Operational advertising enabled
D: 86764 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 86778 [IN]Prepared secure message 0x2002cf38 to 0xFFFFFFFB00000000 (1)  of type 0x9 and pr.
I: 86806 [IN]Sending encrypted msg 0x2002cf38 with MessageCounter:1139014 to 0xFFFFFFFB000000m
                                                               
uart:~$                                                        
Board: lx_nrf5340dk_nrf5340_cpuapp                             
E: cause n 256                                                 
I: Init CHIP stack                                             
D: 1268 [DL]Boot reason: 1
I: 1405 [DL]BLE address: D4:54:7D:1F:6F:09
I: 1494 [DL]OpenThread ifconfig up and thread start
I: 1506 [DL]OpenThread started: OK
I: 1515 [DL]Setting OpenThread device type to MINIMAL END DEVICE
I: Swa
                                                               
uart:~$                                                        
Board: lx_nrf5340dk_nrf5340_cpuapp                             
E: cause n 256                                                 
I: Init CHIP stack                                             
D: 1266 [DL]Boot reason: 1
I: 1407 [DL]BLE address: E7:8A:3C:FB:8A:FD
I: 1500 [DL]OpenThread ifconfig up and thread start
I: 1512 [DL]OpenThread started: OK
I: 1520 [DL]Setting OpenThread device type to MINIMAL END DEVICE
I: Swap
                                                               
uart:~$                                                        
Board: lx_nrf5340dk_nrf5340_cpuapp                             
E: cause n 256                                                 
I: Init CHIP stack                                             
D: 1270 [DL]Boot reason: 1
I: 1415 [DL]BLE address: F3:2D:9D:93:CE:F5
I: 1513 [DL]OpenThread ifconfig up and thread start
I: 1525 [DL]OpenThread started: OK
I: 1535 [DL]Setting OpenThread device type to MINIMAL END DEVICE
I: Swap
                                                               
uart:~$                                                        
Board: lx_nrf5340dk_nrf5340_cpuapp                             
E: cause n 256                                                 
I: Init CHIP stack                                             
D: 1275 [DL]Boot reason: 1
I: 1426 [DL]BLE address: F7:B6:24:41:AA:84
I: 1615 [DL]OpenThread ifconfig up and thread start
I: 1627 [DL]OpenThread started: OK
I: 1637 [DL]Setting OpenThread device type to MINIMAL END DEVICE
I: Swap

I added as well to the main application the function  called hwinfo_get_reset_cause(uint32_t *cause) to get the type of error that causes this reset. When I print the value 'cause', I got 256 which means that the error is a RESET_CPU_LOCKUP error https://docs.zephyrproject.org/apidoc/latest/group__hwinfo__interface.html#gad6721dc841941ccdca349999ce655e83.

8640.main.cpp

Could you give some ideas to understand why I get this kind of behavior please?  Why replacing UART0 by USB CDC ACM can lead to this kind of error?

Parents
  • 0

    Hi,

    Is there any specific reason you are running the latest dev tag and not the latest official release of nRF Connect SDK (v1.9.1)? Have you checked if you get the same error with v1.9.1?

    Not sure what could cause a CPU lockup. This could be triggered by a hardfault in the hardfault handler, but that would indicate something very wrong in the application.

    Can you read out the RESETREAS register directly from code (NRF_RESET->RESETREAS), or using a debugger, to verify that the bit on LOCKUP is set, or if LLOCKUP bit is set? Both can cause the RESET_CPU_LOCKUP return from hwinfo_get_reset_cause().

    Best regards,
    Jørgen

  • 0 in reply to Jørgen Holmefjord

    I'm using the version 1.9.99-dev because I know that it is compatible with the CHIP Tool controller. I read on Nordic website https://developer.nordicsemi.com/nRF_Connect_SDK/doc/latest/nrf/ug_matter_configuring_controller.html#configuring-matter-controller that CHIP Tool is the recommended Matter controller.

    I didn't want to use the Python CHIP controller because I thought that later when the SDK version v1.9.99 will be released I should migrate from Python CHIP controller to CHIP Tool controller. That's why I decided to start working with the CHIP Tool controller and SDK v1.9.99-dev.

    I tried to run the Matter example template (SDK v1.9.1) with CHIP Tool controller but it does not work.

    I don't know if it is a good idea to work with Matter example template (SDK v1.9.1) and the Python CHIP controller.

    I will try to read out the RESETREAS register. Thanks for your help

    Do you know when the latest dev tag v1.9.99-dev will be released ?

Reply
  • 0 in reply to Jørgen Holmefjord

    I'm using the version 1.9.99-dev because I know that it is compatible with the CHIP Tool controller. I read on Nordic website https://developer.nordicsemi.com/nRF_Connect_SDK/doc/latest/nrf/ug_matter_configuring_controller.html#configuring-matter-controller that CHIP Tool is the recommended Matter controller.

    I didn't want to use the Python CHIP controller because I thought that later when the SDK version v1.9.99 will be released I should migrate from Python CHIP controller to CHIP Tool controller. That's why I decided to start working with the CHIP Tool controller and SDK v1.9.99-dev.

    I tried to run the Matter example template (SDK v1.9.1) with CHIP Tool controller but it does not work.

    I don't know if it is a good idea to work with Matter example template (SDK v1.9.1) and the Python CHIP controller.

    I will try to read out the RESETREAS register. Thanks for your help

    Do you know when the latest dev tag v1.9.99-dev will be released ?

Children
No Data
Related
Terms of service