esp-matter: BLE GAP connection terminated (con 0 reason 0x213)

Hello! I’m trying to connect the light-app example to google home, but an unknown error appears during the connection. I have a google nest mini as a hub. I’m also from Ukraine, probably this can also interfere somehow, because there is no Google Home support in Ukraine.

I (2146) chip[DL]: Starting ESP WiFi layer
I (2156) wifi:mode : sta (fc:f5:c4:0e:c4:e0)
I (2156) wifi:enable tsf
W (2156) wifi:Haven't to connect to a suitable AP now!
I (2156) chip[DL]: Done driving station state, nothing else to do...
W (2176) wifi:Haven't to connect to a suitable AP now!
I (2176) chip[DL]: Done driving station state, nothing else to do...
I (2176) chip[SVR]: SetupQRCode: [MT:Y.K9042C00KA0648G00]
I (2186) chip[SVR]: Copy/paste the below URL in a browser to see the QR Code:
I (2186) chip[SVR]: https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3AY.K9042C00KA0648G00
I (2206) chip[SVR]: Manual pairing code: [34970112332]
I (2216) chip[SVR]: Server initializing...
I (2216) chip[TS]: Last Known Good Time: [unknown]
I (2216) chip[TS]: Setting Last Known Good Time to firmware build time 2023-01-03T16:23:00
I (2236) chip[DMG]: AccessControl: initializing
I (2236) chip[DMG]: Examples::AccessControlDelegate::Init
I (2236) chip[DMG]: AccessControl: setting
I (2246) chip[DMG]: DefaultAclStorage: initializing
I (2246) chip[DMG]: DefaultAclStorage: 0 entries loaded
I (2276) chip[ZCL]: Using ZAP configuration...
I (2276) chip[DMG]: AccessControlCluster: initializing
I (2276) chip[ZCL]: Initiating Admin Commissioning cluster.
I (2286) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0030', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (2296) light-app-callbacks: Unhandled cluster ID: 48
I (2306) light-app-callbacks: Current free heap: 94700

I (2306) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (2326) light-app-callbacks: Unhandled cluster ID: 4
I (2326) light-app-callbacks: Current free heap: 94668

I (2336) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x00', Attribute ID: '0xfffc'
I (2346) light-app-callbacks: Unhandled cluster ID: 4
I (2346) light-app-callbacks: Current free heap: 94668

I (2366) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x002b', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (2366) light-app-callbacks: Unhandled cluster ID: 43
I (2376) light-app-callbacks: Current free heap: 94668

I (2386) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (2396) light-app-callbacks: Unhandled cluster ID: 4
I (2406) light-app-callbacks: Current free heap: 94668

I (2406) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x01', Attribute ID: '0xfffc'
I (2416) light-app-callbacks: Unhandled cluster ID: 4
I (2426) light-app-callbacks: Current free heap: 94668

I (2436) light-app-callbacks: emberAfOnOffClusterInitCallback
I (2436) app-task: Writing to OnOff cluster
I (2446) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0006', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (2456) LEDWidget: Setting state to 0
I (2466) light-app-callbacks: Current free heap: 94668

I (2466) app-task: Writing to Current Level cluster
E (2476) app-task: Updating level cluster failed: 87
I (2476) chip[ZCL]: On/Off set value: 1 0
I (2476) chip[ZCL]: On/off already set to new value
I (2496) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0008', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (2496) LEDWidget: Setting brightness to 1
I (2506) LEDWidget: DoSet to GPIO number 5
I (2506) light-app-callbacks: Current free heap: 94536

I (2516) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x0007'
I (2526) light-app-callbacks: Unhandled cluster ID: 768
I (2536) light-app-callbacks: Current free heap: 94536

I (2536) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x0008'
I (2546) light-app-callbacks: Unhandled cluster ID: 768
I (2556) light-app-callbacks: Current free heap: 94536

I (2556) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x4001'
I (2576) light-app-callbacks: Unhandled cluster ID: 768
I (2586) light-app-callbacks: Current free heap: 94536

I (2586) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x01', Attribute ID: '0x0001'
I (2596) light-app-callbacks: Unhandled cluster ID: 1030
I (2596) light-app-callbacks: Current free heap: 94536

I (2616) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x01', Attribute ID: '0x0002'
I (2626) light-app-callbacks: Unhandled cluster ID: 1030
I (2626) light-app-callbacks: Current free heap: 94536

I (2636) chip[DIS]: Updating services using commissioning mode 1
I (2646) chip[DIS]: CHIP minimal mDNS started advertising.
I (2646) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1
I (2656) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A667913808E74C0A.
I (2676) chip[DIS]: mDNS service published: _matterc._udp
I (2676) chip[IN]: CASE Server enabling CASE session setups
I (2686) chip[SVR]: Joining Multicast groups
I (2686) chip[SVR]: Server Listening...
E (2696) chip[DL]: Long dispatch time: 516 ms, for event type 2
I (2696) app-devicecallbacks: Current free heap: 93764

I (2706) chip[DL]: WIFI_EVENT_STA_START
W (2706) wifi:Haven't to connect to a suitable AP now!
I (2706) chip[DL]: Done driving station state, nothing else to do...
I (2726) app-devicecallbacks: Current free heap: 93764

I (2726) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (2736) chip[DL]: Device already advertising, stop active advertisement and restart
I (2736) NimBLE: GAP procedure initiated: stop advertising.

I (2756) NimBLE: GAP procedure initiated: advertise; 
I (2756) NimBLE: disc_mode=2
I (2756) NimBLE:  adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (2766) NimBLE: 

I (32646) chip[DL]: Configuring CHIPoBLE advertising (interval 500 ms, connectable)
I (32646) chip[DL]: Device already advertising, stop active advertisement and restart
I (32656) NimBLE: GAP procedure initiated: stop advertising.

I (32656) NimBLE: GAP procedure initiated: advertise; 
I (32676) NimBLE: disc_mode=2
I (32676) NimBLE:  adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=800 adv_itvl_max=800
I (32686) NimBLE: 

I (158006) chip[DL]: BLE GAP connection established (con 0)
I (158006) chip[DL]: CHIPoBLE advertising stopped
I (158006) app-devicecallbacks: Current free heap: 93764

I (158916) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (158926) chip[BLE]: local and remote recv window sizes = 5
I (158926) chip[BLE]: selected BTP version 4
I (158936) chip[BLE]: using BTP fragment sizes rx 128 / tx 128.
I (158946) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 0 ) indicate = 1
I (158956) chip[DL]: CHIPoBLE subscribe received
I (158956) NimBLE: GATT procedure initiated: indicate; 
I (158956) NimBLE: att_handle=14

I (158976) app-devicecallbacks: CHIPoBLE connection established
I (158976) app-devicecallbacks: Current free heap: 92240

I (158986) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (158996) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (159006) chip[EM]: >>> [E:2413r M:207237095] (U) Msg RX from 0:82D9AA60A621D832 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I (159016) chip[EM]: <<< [E:2413r M:95116545] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I (159026) chip[IN]: (U) Sending msg 95116545 to IP address 'BLE'
I (159036) NimBLE: GATT procedure initiated: indicate; 
I (159046) NimBLE: att_handle=14

I (159076) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (159076) NimBLE: GATT procedure initiated: indicate; 
I (159086) NimBLE: att_handle=14

I (159106) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (159136) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (159136) chip[EM]: >>> [E:2413r M:207237096] (U) Msg RX from 0:82D9AA60A621D832 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (160726) chip[EM]: <<< [E:2413r M:95116546] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I (160736) chip[IN]: (U) Sending msg 95116546 to IP address 'BLE'
I (160746) NimBLE: GATT procedure initiated: indicate; 
I (160746) NimBLE: att_handle=14

E (160766) chip[DL]: Long dispatch time: 1625 ms, for event type 7
I (160856) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (160856) NimBLE: GATT procedure initiated: indicate; 
I (160866) NimBLE: att_handle=14

I (160946) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (160956) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (160966) chip[EM]: >>> [E:2413r M:207237097] (U) Msg RX from 0:82D9AA60A621D832 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I (160976) chip[EM]: <<< [E:2413r M:95116547] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I (160986) chip[IN]: (U) Sending msg 95116547 to IP address 'BLE'
I (160996) NimBLE: GATT procedure initiated: indicate; 
I (161006) NimBLE: att_handle=14

I (161006) chip[SC]: SecureSession[0x3ffc57e0]: Moving from state 'kEstablishing' --> 'kActive'
I (161016) chip[SVR]: Commissioning completed session establishment step
I (161026) chip[DIS]: Updating services using commissioning mode 0
I (161036) chip[DIS]: CHIP minimal mDNS started advertising.
I (161036) chip[SVR]: Device completed Rendezvous process
I (161096) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (161096) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (161116) chip[EM]: >>> [E:2414r M:258385964] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (161126) chip[EM]: <<< [E:2414r M:238133898] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I (161136) chip[IN]: (S) Sending msg 238133898 on secure session with LSID: 23783
I (161136) NimBLE: GATT procedure initiated: indicate; 
I (161156) NimBLE: att_handle=14

I (161246) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (161246) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (161266) chip[EM]: >>> [E:2415r M:258385965] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (161266) chip[EM]: <<< [E:2415r M:238133899] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I (161286) chip[IN]: (S) Sending msg 238133899 on secure session with LSID: 23783
I (161286) NimBLE: GATT procedure initiated: indicate; 
I (161296) NimBLE: att_handle=14

I (161486) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (161486) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (161506) chip[EM]: >>> [E:2416r M:258385966] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (161516) chip[EM]: <<< [E:2416r M:238133900] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I (161526) chip[IN]: (S) Sending msg 238133900 on secure session with LSID: 23783
I (161526) NimBLE: GATT procedure initiated: indicate; 
I (161546) NimBLE: att_handle=14

I (161636) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (161636) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (161656) chip[EM]: >>> [E:2417r M:258385967] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (161656) chip[EM]: <<< [E:2417r M:238133901] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I (161676) chip[IN]: (S) Sending msg 238133901 on secure session with LSID: 23783
I (161676) NimBLE: GATT procedure initiated: indicate; 
I (161686) NimBLE: att_handle=14

I (161826) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (161826) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (161846) chip[EM]: >>> [E:2418r M:258385968] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (161856) chip[EM]: <<< [E:2418r M:238133902] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I (161866) chip[IN]: (S) Sending msg 238133902 on secure session with LSID: 23783
I (161876) NimBLE: GATT procedure initiated: indicate; 
I (161886) NimBLE: att_handle=14

I (161976) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (161976) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (161996) chip[EM]: >>> [E:2419r M:258385969] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (162006) chip[EM]: <<< [E:2419r M:238133903] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I (162016) chip[IN]: (S) Sending msg 238133903 on secure session with LSID: 23783
I (162016) NimBLE: GATT procedure initiated: indicate; 
I (162016) NimBLE: att_handle=14

I (162116) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (162126) NimBLE: GATT procedure initiated: indicate; 
I (162136) NimBLE: att_handle=14

I (162216) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (162216) NimBLE: GATT procedure initiated: indicate; 
I (162236) NimBLE: att_handle=14

I (162366) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (162366) NimBLE: GATT procedure initiated: indicate; 
I (162376) NimBLE: att_handle=14

I (162466) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (162466) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (162476) NimBLE: GATT procedure initiated: indicate; 
I (162476) NimBLE: att_handle=14

I (162556) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (162566) NimBLE: GATT procedure initiated: indicate; 
I (162576) NimBLE: att_handle=14

I (162706) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (163536) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (163536) chip[EM]: >>> [E:2420r M:258385970] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I (163556) chip[EM]: <<< [E:2420r M:238133904] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:01 (IM:StatusResponse)
I (163556) chip[IN]: (S) Sending msg 238133904 on secure session with LSID: 23783
I (163566) NimBLE: GATT procedure initiated: indicate; 
I (163576) NimBLE: att_handle=14

I (163676) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (163686) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (163696) chip[EM]: >>> [E:2420r M:258385971] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (163706) chip[FS]: GeneralCommissioning: Received ArmFailSafe (120s)
I (163716) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0030', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (163726) light-app-callbacks: Unhandled cluster ID: 48
I (163726) light-app-callbacks: Current free heap: 92780

I (163746) chip[EM]: <<< [E:2420r M:238133905] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (163746) chip[IN]: (S) Sending msg 238133905 on secure session with LSID: 23783
I (163756) NimBLE: GATT procedure initiated: indicate; 
I (163766) NimBLE: att_handle=14

I (163826) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (163826) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (163846) chip[EM]: >>> [E:2421r M:258385972] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (163856) chip[EM]: <<< [E:2421r M:238133906] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I (163866) chip[IN]: (S) Sending msg 238133906 on secure session with LSID: 23783
I (163866) NimBLE: GATT procedure initiated: indicate; 
I (163886) NimBLE: att_handle=14

I (163976) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (163976) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (163996) chip[EM]: >>> [E:2422r M:258385973] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I (163996) chip[EM]: <<< [E:2422r M:238133907] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:01 (IM:StatusResponse)
I (164006) chip[IN]: (S) Sending msg 238133907 on secure session with LSID: 23783
I (164026) NimBLE: GATT procedure initiated: indicate; 
I (164026) NimBLE: att_handle=14

I (164116) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (164126) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (164136) chip[EM]: >>> [E:2422r M:258385974] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (164146) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0)
I (164156) chip[DL]: NVS set: chip-config/country-code = "US"
I (164156) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0030', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (164176) light-app-callbacks: Unhandled cluster ID: 48
I (164176) light-app-callbacks: Current free heap: 92780

I (164186) chip[EM]: <<< [E:2422r M:238133908] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (164196) chip[IN]: (S) Sending msg 238133908 on secure session with LSID: 23783
I (164206) NimBLE: GATT procedure initiated: indicate; 
I (164206) NimBLE: att_handle=14

I (164316) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (164316) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (164336) chip[EM]: >>> [E:2423r M:258385975] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (164336) chip[ZCL]: OpCreds: Certificate Chain request received for DAC
I (164356) chip[EM]: <<< [E:2423r M:238133909] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (164356) chip[IN]: (S) Sending msg 238133909 on secure session with LSID: 23783
I (164366) NimBLE: GATT procedure initiated: indicate; 
I (164376) NimBLE: att_handle=14

I (164456) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (164466) NimBLE: GATT procedure initiated: indicate; 
I (164476) NimBLE: att_handle=14

I (164556) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (164556) NimBLE: GATT procedure initiated: indicate; 
I (164576) NimBLE: att_handle=14

I (164656) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (164656) NimBLE: GATT procedure initiated: indicate; 
I (164666) NimBLE: att_handle=14

I (164846) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (164856) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (164866) NimBLE: GATT procedure initiated: indicate; 
I (164866) NimBLE: att_handle=14

I (164946) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (164946) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (164966) chip[EM]: >>> [E:2424r M:258385976] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (164976) chip[ZCL]: OpCreds: Certificate Chain request received for PAI
I (164986) chip[EM]: <<< [E:2424r M:238133910] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (164996) chip[IN]: (S) Sending msg 238133910 on secure session with LSID: 23783
I (165006) NimBLE: GATT procedure initiated: indicate; 
I (165006) NimBLE: att_handle=14

I (165096) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (165096) NimBLE: GATT procedure initiated: indicate; 
I (165106) NimBLE: att_handle=14

I (165196) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (165196) NimBLE: GATT procedure initiated: indicate; 
I (165206) NimBLE: att_handle=14

I (165286) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (165296) NimBLE: GATT procedure initiated: indicate; 
I (165306) NimBLE: att_handle=14

I (165386) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (165386) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (165406) NimBLE: GATT procedure initiated: indicate; 
I (165406) NimBLE: att_handle=14

I (165536) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (165536) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (165556) chip[EM]: >>> [E:2425r M:258385977] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (165556) chip[ZCL]: OpCreds: Received an AttestationRequest command
I (165896) chip[ZCL]: OpCreds: AttestationRequest successful.
I (165896) chip[EM]: <<< [E:2425r M:238133911] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (165906) chip[IN]: (S) Sending msg 238133911 on secure session with LSID: 23783
I (165916) NimBLE: GATT procedure initiated: indicate; 
I (165916) NimBLE: att_handle=14

E (165936) chip[DL]: Long dispatch time: 384 ms, for event type 7
I (166016) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (166026) NimBLE: GATT procedure initiated: indicate; 
I (166036) NimBLE: att_handle=14

I (166166) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (166166) NimBLE: GATT procedure initiated: indicate; 
I (166176) NimBLE: att_handle=14

I (166266) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (166266) NimBLE: GATT procedure initiated: indicate; 
I (166276) NimBLE: att_handle=14

I (166366) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (166366) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (166376) NimBLE: GATT procedure initiated: indicate; 
I (166376) NimBLE: att_handle=14

I (166456) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (166466) NimBLE: GATT procedure initiated: indicate; 
I (166476) NimBLE: att_handle=14

I (166656) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (166656) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (166676) chip[EM]: >>> [E:2426r M:258385978] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (166676) chip[ZCL]: OpCreds: Received a CSRRequest command
I (167326) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded
I (167646) chip[ZCL]: OpCreds: CSRRequest successful.
I (167656) chip[EM]: <<< [E:2426r M:238133912] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (167656) chip[IN]: (S) Sending msg 238133912 on secure session with LSID: 23783
I (167666) NimBLE: GATT procedure initiated: indicate; 
I (167666) NimBLE: att_handle=14

E (167686) chip[DL]: Long dispatch time: 1016 ms, for event type 7
I (167776) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (167776) NimBLE: GATT procedure initiated: indicate; 
I (167786) NimBLE: att_handle=14

I (167876) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (167876) NimBLE: GATT procedure initiated: indicate; 
I (167886) NimBLE: att_handle=14

I (167966) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (170306) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (170556) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (170656) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (170896) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (170896) NimBLE: GATT procedure initiated: indicate; 
I (170896) NimBLE: att_handle=14

I (170916) chip[EM]: >>> [E:2427r M:258385979] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (170926) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command
I (170996) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (171586) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful.
I (171586) chip[EM]: <<< [E:2427r M:238133913] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (171596) chip[IN]: (S) Sending msg 238133913 on secure session with LSID: 23783
E (171606) chip[DL]: Long dispatch time: 705 ms, for event type 7
I (171616) NimBLE: GATT procedure initiated: indicate; 
I (171616) NimBLE: att_handle=14

I (171676) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (171676) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (171776) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (171866) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (171966) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (171976) NimBLE: GATT procedure initiated: indicate; 
I (171976) NimBLE: att_handle=14

I (172066) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (172066) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (172166) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (172166) chip[EM]: >>> [E:2428r M:258385980] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (172176) chip[ZCL]: OpCreds: Received an AddNOC command
I (172196) chip[FP]: Validating NOC chain
I (173466) chip[FP]: NOC chain validation successful
I (173466) chip[FP]: Added new fabric at index: 0x1
I (173466) chip[FP]: Assigned compressed fabric ID: 0xC4027049BE68A851, node ID: 0x00000000692813D9
I (173486) chip[TS]: Last Known Good Time: 2023-01-03T16:23:00
I (173486) chip[TS]: New proposed Last Known Good Time: 2023-01-06T08:34:44
I (173496) chip[TS]: Updating pending Last Known Good Time to 2023-01-06T08:34:44
I (173526) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0xFFFFFFFD00010001
I (173526) chip[DIS]: Advertise operational node C4027049BE68A851-00000000692813D9
I (173536) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: C4027049BE68A851-00000000692813D9.
I (173556) chip[DIS]: mDNS service published: _matter._tcp
I (173556) chip[ZCL]: OpCreds: successfully created fabric index 0x1 via AddNOC
I (173556) chip[EM]: <<< [E:2428r M:238133914] (S) Msg TX to 1:FFFFFFFB00000000 [A851] --- Type 0001:09 (IM:InvokeCommandResponse)
I (173576) chip[IN]: (S) Sending msg 238133914 on secure session with LSID: 23783
I (173586) NimBLE: GATT procedure initiated: indicate; 
I (173586) NimBLE: att_handle=14

E (173606) chip[DL]: Long dispatch time: 1437 ms, for event type 7
I (173676) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (176206) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (178716) NimBLE: GATT procedure initiated: indicate; 
I (178716) NimBLE: att_handle=14

I (178796) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (181326) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (183826) NimBLE: GATT procedure initiated: indicate; 
I (183826) NimBLE: att_handle=14

I (183916) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (186496) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12
I (188996) NimBLE: GATT procedure initiated: indicate; 
I (188996) NimBLE: att_handle=14

I (189076) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 
I (193666) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 0 ) indicate = 0
I (193666) chip[DL]: CHIPoBLE unsubscribe received
I (193676) chip[DL]: BLE GAP connection terminated (con 0 reason 0x213)
I (193666) chip[BLE]: Releasing end point's BLE connection back to application.
I (193686) app-devicecallbacks: CHIPoBLE disconnected
I (193686) app-devicecallbacks: Current free heap: 90768
I (283716) chip[FS]: Fail-safe timer expired
E (283716) chip[SVR]: Failsafe timer expired
I (283716) chip[SC]: SecureSession[0x3ffc57e0]: Moving from state 'kActive' --> 'kPendingEviction'
E (283736) chip[SVR]: Commissioning failed (attempt 1): 32
I (283736) chip[DIS]: Updating services using commissioning mode 1
I (283746) chip[DIS]: CHIP minimal mDNS started advertising.
I (283746) chip[DIS]: Advertise operational node C4027049BE68A851-00000000692813D9
I (283766) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: C4027049BE68A851-00000000692813D9.
I (283766) chip[DIS]: mDNS service published: _matter._tcp
I (283776) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1
I (283796) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A667913808E74C0A.
I (283796) chip[DIS]: mDNS service published: _matterc._udp
E (283806) chip[ZCL]: OpCreds: Got FailSafeTimerExpired
E (283806) chip[ZCL]: OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry!
E (283816) chip[FP]: Reverting pending fabric data for fabric 0x1
E (283826) chip[FP]: Warning: metadata not found during delete of fabric 0x1
I (283866) chip[FP]: Fabric (0x1) deleted.
I (283866) chip[ZCL]: UserLabel: Last Fabric index 0x1 was removed
I (283866) chip[ZCL]: OpCreds: Fabric index 0x1 was removed
I (283876) chip[DIS]: Updating services using commissioning mode 1
I (283876) chip[DIS]: CHIP minimal mDNS started advertising.
I (283896) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1
I (283896) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A667913808E74C0A.
I (283906) chip[DIS]: mDNS service published: _matterc._udp
I (283986) chip[TS]: Pending Last Known Good Time: 2023-01-06T08:34:44
I (283986) chip[TS]: Previous Last Known Good Time: 2023-01-03T16:23:00
I (283986) chip[TS]: Reverted Last Known Good Time to previous value
E (284006) chip[FP]: Warning: metadata not found during delete of fabric 0x1
E (284016) chip[ZCL]: OpCreds: failed to delete fabric at index 1: d8
I (284016) light-app-callbacks: PostAttributeChangeCallback - Cluster ID: '0x0030', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (284026) light-app-callbacks: Unhandled cluster ID: 48
I (284026) light-app-callbacks: Current free heap: 93552

I (284046) app-devicecallbacks: Current free heap: 93552

E (284046) chip[DL]: Long dispatch time: 326 ms, for event type 32782
I (284056) chip[FS]: Fail-safe cleanly disarmed
I (284056) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (284076) NimBLE: GAP procedure initiated: advertise; 
I (284076) NimBLE: disc_mode=2
I (284076) NimBLE:  adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (284086) NimBLE: 

I (284096) chip[DL]: CHIPoBLE advertising started
```
`