I (3073) esp_netif_handlers: sta ip: 192.168.1.156, mask: 255.255.255.0, gw: 192.168.1.1
I (3073) chip[DL]: IP_EVENT_STA_GOT_IP
I (3073) chip[DL]: IPv4 address changed on WiFi station interface: 192.168.1.156/255.255.255.0 gateway 192.168.1.1
I (3083) chip[DL]: IPv4 Internet connectivity ESTABLISHED
I (3093) matter_setup: Interface IP Address changed
I (3093) chip[DIS]: Updating services using commissioning mode 1
I (3103) chip[DIS]: CHIP minimal mDNS started advertising.
I (3113) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 cp=0
I (3113) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1AAE1AD48B069B72.
I (3133) chip[DIS]: mDNS service published: _matterc._udp
I (3323) CTR_TASK: PAUSE
I (3333) chip[DL]: IP_EVENT_GOT_IP6
I (3333) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:3e84:27ff:fec6:aa1c
I (3333) matter_setup: Interface IP Address changed
I (3343) chip[DIS]: Updating services using commissioning mode 1
I (3343) chip[DIS]: CHIP minimal mDNS started advertising.
I (3363) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 cp=0
I (3363) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1AAE1AD48B069B72.
I (3373) chip[DIS]: mDNS service published: _matterc._udp
I (3383) chip[SVR]: Server initialization complete
I (3383) chip[DIS]: Updating services using commissioning mode 1
I (3393) chip[DIS]: CHIP minimal mDNS started advertising.
I (3403) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 cp=0
I (3413) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1AAE1AD48B069B72.
I (3423) chip[DIS]: mDNS service published: _matterc._udp
I (3423) chip[IM]: No subscriptions to resume
I (3623) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (3623) chip[BLE]: local and remote recv window sizes = 5
I (3623) chip[BLE]: selected BTP version 4
I (3633) chip[BLE]: using BTP fragment sizes rx 244 / tx 244.
I (3833) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 1
I (3833) chip[DL]: CHIPoBLE subscribe received
I (3833) NimBLE: GATT procedure initiated: indicate; 
I (3843) NimBLE: att_handle=18

I (3893) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (3893) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (3893) chip[EM]: >>> [E:14320r S:0 M:95828389] (U) Msg RX from 0:56C30DFA593F5DDB [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I (3913) chip[EM]: <<< [E:14320r S:0 M:170409251] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I (3923) NimBLE: GATT procedure initiated: indicate; 
I (3933) NimBLE: att_handle=18

I (3933) chip[SVR]: Commissioning session establishment step started
I (3983) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (3983) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (3983) chip[EM]: >>> [E:14320r S:0 M:95828390] (U) Msg RX from 0:56C30DFA593F5DDB [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (4483) chip[EM]: <<< [E:14320r S:0 M:170409252] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I (4483) NimBLE: GATT procedure initiated: indicate; 
I (4493) NimBLE: att_handle=18

I (4583) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (4583) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (4583) chip[EM]: >>> [E:14320r S:0 M:95828391] (U) Msg RX from 0:56C30DFA593F5DDB [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I (4603) chip[EM]: <<< [E:14320r S:0 M:170409253] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:40 (SecureChannel:StatusReport)
I (4613) NimBLE: GATT procedure initiated: indicate; 
I (4623) NimBLE: att_handle=18

I (4623) chip[SC]: SecureSession[0x3fcb1488, LSID:57824]: State change 'kEstablishing' --> 'kActive'
I (4633) chip[SVR]: Commissioning completed session establishment step
I (4643) chip[DIS]: Updating services using commissioning mode 0
I (4643) chip[DIS]: CHIP minimal mDNS started advertising.
I (4663) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0 cp=0
I (4663) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1AAE1AD48B069B72.
I (4673) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (4683) chip[DIS]: mDNS service published: _matterc._udp
I (4683) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (4693) chip[SVR]: Device completed Rendezvous process
I (4703) matter_setup: Commissioning session started
I (4703) R2r: Unhandled Signal
I (4713) matter_setup: Commissioning window closed
I (4723) chip[EM]: >>> [E:14321r S:57824 M:183819952] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (4733) chip[EM]: <<< [E:14321r S:57824 M:84032579] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (4743) NimBLE: GATT procedure initiated: indicate; 
I (4753) NimBLE: att_handle=18

I (4753) R2r: Unhandled Signal
I (4753) CTR_TASK: PROVISIONING
I (4763) CTR_TASK: LOADING
I (4793) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (4793) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (4803) chip[EM]: >>> [E:14322r S:57824 M:183819953] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (4813) chip[EM]: <<< [E:14322r S:57824 M:84032580] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (4823) NimBLE: GATT procedure initiated: indicate; 
I (4823) NimBLE: att_handle=18

I (4883) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (4883) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (4893) chip[EM]: >>> [E:14323r S:57824 M:183819954] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (4903) chip[EM]: <<< [E:14323r S:57824 M:84032581] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (4913) NimBLE: GATT procedure initiated: indicate; 
I (4913) NimBLE: att_handle=18

I (4973) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (4973) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (4983) chip[EM]: >>> [E:14324r S:57824 M:183819955] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (4993) chip[EM]: <<< [E:14324r S:57824 M:84032582] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (5003) NimBLE: GATT procedure initiated: indicate; 
I (5003) NimBLE: att_handle=18

I (5093) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (5093) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (5103) chip[EM]: >>> [E:14325r S:57824 M:183819956] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (6763) CTR_TASK: PAUSE
I (7593) NimBLE: GATT procedure initiated: indicate; 
I (7593) NimBLE: att_handle=18

I (7673) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (9923) chip[DL]: WIFI_EVENT_SCAN_DONE
I (9923) chip[EM]: <<< [E:14325r S:57824 M:84032583] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (9923) NimBLE: GATT procedure initiated: indicate; 
I (9933) NimBLE: att_handle=18

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

I (10133) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (10193) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (10493) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (10493) chip[EM]: >>> [E:14326r S:57824 M:183819957] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (10503) chip[EM]: <<< [E:14326r S:57824 M:84032584] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (10513) NimBLE: GATT procedure initiated: indicate; 
I (10523) NimBLE: att_handle=18

I (10613) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (10613) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (10623) chip[EM]: >>> [E:14327r S:57824 M:183819958] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (10633) chip[EM]: <<< [E:14327r S:57824 M:84032585] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (10643) NimBLE: GATT procedure initiated: indicate; 
I (10653) NimBLE: att_handle=18

I (10703) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (10703) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (10713) chip[EM]: >>> [E:14328r S:57824 M:183819959] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (10723) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (10733) chip[EM]: <<< [E:14328r S:57824 M:84032586] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (10743) NimBLE: GATT procedure initiated: indicate; 
I (10753) NimBLE: att_handle=18

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

I (10883) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (10883) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (10893) chip[EM]: >>> [E:14329r S:57824 M:183819960] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (10903) chip[EM]: <<< [E:14329r S:57824 M:84032587] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (10913) NimBLE: GATT procedure initiated: indicate; 
I (10923) NimBLE: att_handle=18

I (11003) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (11003) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (11013) chip[EM]: >>> [E:14330r S:57824 M:183819961] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (11023) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (11033) chip[FS]: GeneralCommissioning: Received ArmFailSafe (60s)
I (11043) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 4 **********
I (11053) matter_setup: PRE_UPDATE
I (11053) chip[EM]: <<< [E:14330r S:57824 M:84032588] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (11063) NimBLE: GATT procedure initiated: indicate; 
I (11073) NimBLE: att_handle=18

I (11123) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (11123) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (11133) chip[EM]: >>> [E:14331r S:57824 M:183819962] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (11143) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x00000030
I (11153) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0)
I (11163) chip[DL]: NVS set: chip-config/country-code = "CA"
I (11163) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 5 **********
I (11173) matter_setup: PRE_UPDATE
I (11183) chip[EM]: <<< [E:14331r S:57824 M:84032589] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (11193) NimBLE: GATT procedure initiated: indicate; 
I (11203) NimBLE: att_handle=18

I (11243) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (11243) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (11253) chip[EM]: >>> [E:14332r S:57824 M:183819963] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (11263) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (11273) chip[ZCL]: OpCreds: Certificate Chain request received for PAI
I (11283) chip[EM]: <<< [E:14332r S:57824 M:84032590] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (11293) NimBLE: GATT procedure initiated: indicate; 
I (11293) NimBLE: att_handle=18

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

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

I (11513) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (11543) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (11543) chip[EM]: >>> [E:14333r S:57824 M:183819964] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (11553) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (11563) chip[ZCL]: OpCreds: Certificate Chain request received for DAC
I (11573) chip[EM]: <<< [E:14333r S:57824 M:84032591] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (11583) NimBLE: GATT procedure initiated: indicate; 
I (11593) NimBLE: att_handle=18

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

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

I (11813) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (11813) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (11823) chip[EM]: >>> [E:14334r S:57824 M:183819965] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (11833) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003E
I (11843) chip[ZCL]: OpCreds: Received an AttestationRequest command
I (11893) chip[ZCL]: OpCreds: AttestationRequest successful.
I (11903) chip[EM]: <<< [E:14334r S:57824 M:84032592] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (11903) NimBLE: GATT procedure initiated: indicate; 
I (11913) NimBLE: att_handle=18

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

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

I (12143) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (12143) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (12153) chip[EM]: >>> [E:14335r S:57824 M:183819966] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (12163) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (12173) chip[FS]: GeneralCommissioning: Received ArmFailSafe (60s)
I (12183) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 14 **********
I (12193) matter_setup: PRE_UPDATE
I (12193) chip[EM]: <<< [E:14335r S:57824 M:84032593] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (12203) NimBLE: GATT procedure initiated: indicate; 
I (12213) NimBLE: att_handle=18

I (12263) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (14783) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (15323) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (15323) chip[EM]: >>> [E:14336r S:57824 M:183819967] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (15333) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E
I (15343) chip[ZCL]: OpCreds: Received a CSRRequest command
I (15433) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded
I (15483) chip[ZCL]: OpCreds: CSRRequest successful.
I (15483) chip[EM]: <<< [E:14336r S:57824 M:84032594] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (15483) NimBLE: GATT procedure initiated: indicate; 
I (15493) NimBLE: att_handle=18

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

I (15593) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (15593) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (15653) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (15653) chip[EM]: >>> [E:14337r S:57824 M:183819968] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (15663) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E
I (15673) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command
I (15833) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful.
I (15833) chip[EM]: <<< [E:14337r S:57824 M:84032595] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (15843) NimBLE: GATT procedure initiated: indicate; 
I (15853) NimBLE: att_handle=18

I (15893) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (15893) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (15953) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (15953) chip[EM]: >>> [E:14338r S:57824 M:183819969] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (15963) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E
I (15973) chip[ZCL]: OpCreds: Received an AddNOC command
I (15983) chip[FP]: Validating NOC chain
I (16133) chip[FP]: NOC chain validation successful
I (16133) chip[FP]: Added new fabric at index: 0x2
I (16133) chip[FP]: Assigned compressed fabric ID: 0xBD1FAE37855CF20A, node ID: 0x00000000AFF8992F
I (16143) chip[TS]: Last Known Good Time: 2024-09-27T17:27:12
I (16153) chip[TS]: New proposed Last Known Good Time: 2024-09-27T19:19:46
I (16163) chip[TS]: Updating pending Last Known Good Time to 2024-09-27T19:19:46
I (16213) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x2 CASE Admin Subject 0x000000001C8A2556
I (16213) chip[DIS]: Advertise operational node BD1FAE37855CF20A-00000000AFF8992F
I (16223) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: BD1FAE37855CF20A-00000000AFF8992F.
I (16243) chip[DIS]: mDNS service published: _matter._tcp
I (16243) chip[ZCL]: OpCreds: successfully created fabric index 0x2 via AddNOC
I (16253) chip[EM]: <<< [E:14338r S:57824 M:84032596] (S) Msg TX to 2:FFFFFFFB00000000 [F20A] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (16263) NimBLE: GATT procedure initiated: indicate; 
I (16273) NimBLE: att_handle=18

I (16273) matter_setup: Fabric is updated
I (16343) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (16343) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (16353) chip[EM]: >>> [E:14339r S:57824 M:183819970] (S) Msg RX from 2:FFFFFFFB00000000 [F20A] --- Type 0001:08 (IM:InvokeCommandRequest)
I (16363) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 23 **********
I (16373) matter_setup: PRE_UPDATE
I (16383) chip[EM]: <<< [E:14339r S:57824 M:84032597] (S) Msg TX to 2:FFFFFFFB00000000 [F20A] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (16393) NimBLE: GATT procedure initiated: indicate; 
I (16393) NimBLE: att_handle=18

I (16463) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (16463) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (16473) chip[EM]: >>> [E:14340r S:57824 M:183819971] (S) Msg RX from 2:FFFFFFFB00000000 [F20A] --- Type 0001:08 (IM:InvokeCommandRequest)
I (16483) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (16493) chip[FS]: GeneralCommissioning: Received ArmFailSafe (117s)
I (16503) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 25 **********
I (16513) matter_setup: PRE_UPDATE
I (16513) chip[EM]: <<< [E:14340r S:57824 M:84032598] (S) Msg TX to 2:FFFFFFFB00000000 [F20A] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (16523) NimBLE: GATT procedure initiated: indicate; 
I (16533) NimBLE: att_handle=18

I (16583) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (16583) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (16593) chip[EM]: >>> [E:14341r S:57824 M:183819972] (S) Msg RX from 2:FFFFFFFB00000000 [F20A] --- Type 0001:08 (IM:InvokeCommandRequest)
I (16603) chip[NP]: ESP NetworkCommissioningDelegate: SSID: LowFrequencyGang
I (16613) chip[DL]: Disconnecting WiFi station interface
I (16733) chip[DL]: WiFi station mode change: Enabled -> Disabled
W (16733) wifi:Password length matches WPA2 standards, authmode threshold changes from OPEN to WPA2
I (16833) chip[DL]: WiFi station mode change: Disabled -> Enabled
I (16833) chip[DL]: WIFI_EVENT_STA_DISCONNECTED
W (16833) wifi:Haven't to connect to a suitable AP now!
I (16833) chip[DL]: WiFi station state change: Connected -> NotConnected
W (16843) wifi:Haven't to connect to a suitable AP now!
E (16853) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (16863) chip[DL]: WiFi station interface disconnected
I (16863) chip[ZCL]: WiFiDiagnosticsDelegate: OnDisconnectionDetected
I (16873) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (16883) chip[DL]: IPv4 Internet connectivity LOST
I (16883) chip[DL]: Attempting to connect WiFi station interface
I (16893) chip[DL]: WiFi station state change: NotConnected -> Connecting
W (16903) wifi:Haven't to connect to a suitable AP now!
E (16903) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (16913) chip[DL]: Done driving station state, nothing else to do...
W (16923) wifi:Haven't to connect to a suitable AP now!
I (16923) chip[DL]: Attempting to connect WiFi station interface
E (16933) wifi:sta is connecting, return error
E (16933) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN
W (16943) wifi:Haven't to connect to a suitable AP now!
I (16953) chip[DL]: Attempting to connect WiFi station interface
E (16953) wifi:sta is connecting, return error
E (16963) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN
W (16963) wifi:Haven't to connect to a suitable AP now!
I (16973) chip[DL]: Attempting to connect WiFi station interface
E (16973) wifi:sta is connecting, return error
E (16983) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN
I (16993) chip[DL]: Updating advertising data
I (19093) NimBLE: GATT procedure initiated: indicate; 
I (19093) NimBLE: att_handle=18

I (19163) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (19723) chip[DL]: WIFI_EVENT_STA_CONNECTED
I (19723) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded
I (19723) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected
I (19733) chip[DL]: WiFi station interface connected
I (19733) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 27 **********
I (19753) matter_setup: PRE_UPDATE
I (19753) chip[EM]: <<< [E:14341r S:57824 M:84032599] (S) Msg TX to 2:FFFFFFFB00000000 [F20A] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (19763) NimBLE: GATT procedure initiated: indicate; 
I (19773) NimBLE: att_handle=18

I (19773) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (19783) chip[DL]: Done driving station state, nothing else to do...
I (19793) chip[DIS]: Advertise operational node BD1FAE37855CF20A-00000000AFF8992F
I (19803) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: BD1FAE37855CF20A-00000000AFF8992F.
I (19813) chip[DIS]: mDNS service published: _matter._tcp
I (19813) chip[SVR]: Operational advertising enabled
I (19823) chip[DL]: I (19823) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 Updating advertising data

I (20723) esp_netif_handlers: sta ip: 192.168.1.156, mask: 255.255.255.0, gw: 192.168.1.1
I (20723) chip[DL]: IP_EVENT_STA_GOT_IP
I (20723) chip[DL]: IPv4 address ready on WiFi station interface: 192.168.1.156/255.255.255.0 gateway 192.168.1.1
I (20733) chip[DL]: IPv4 Internet connectivity ESTABLISHED
I (20743) matter_setup: Interface IP Address changed
I (20743) chip[DIS]: Updating services using commissioning mode 0
I (20753) chip[DIS]: CHIP minimal mDNS started advertising.
I (20763) chip[DIS]: Advertise operational node BD1FAE37855CF20A-00000000AFF8992F
I (20763) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: BD1FAE37855CF20A-00000000AFF8992F.
I (20783) chip[DIS]: mDNS service published: _matter._tcp
I (20783) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0 cp=0
I (20793) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1AAE1AD48B069B72.
I (20813) chip[DIS]: mDNS service published: _matterc._udp
I (21333) chip[DL]: IP_EVENT_GOT_IP6
I (21333) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:3e84:27ff:fec6:aa1c
I (21333) ROUTE_HOOK: Hook already installed on netif, skip...
I (21343) matter_setup: Interface IP Address changed
I (21343) chip[DIS]: Updating services using commissioning mode 0
I (21353) chip[DIS]: CHIP minimal mDNS started advertising.
I (21373) chip[DIS]: Advertise operational node BD1FAE37855CF20A-00000000AFF8992F
I (21373) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: BD1FAE37855CF20A-00000000AFF8992F.
I (21383) chip[DIS]: mDNS service published: _matter._tcp
I (21383) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0 cp=0
I (21393) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1AAE1AD48B069B72.
I (21413) chip[DIS]: mDNS service published: _matterc._udp
I (21623) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (22753) chip[EM]: >>> [E:14342r S:0 M:95828392] (U) Msg RX from 0:98191D3868EF45B0 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (22753) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fcb2e38
I (22763) chip[EM]: <<< [E:14342r S:0 M:170409254 (Ack:95828392)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::18B4:E05A:A31E:E4AA%st1]:59693] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (22783) chip[SC]: Received Sigma1 msg
I (22793) chip[SC]: CASE matched destination ID: fabricIndex 2, NodeID 0x00000000AFF8992F
I (22993) chip[EM]: <<< [E:14342r S:0 M:170409255 (Ack:95828392)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::18B4:E05A:A31E:E4AA%st1]:59693] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (23003) chip[SC]: Sent Sigma2 msg
I (23063) ROUTE_HOOK: Received RIO
I (23063) ROUTE_HOOK: prefix FD42:59D:1CCE:1:: lifetime 1800
I (23073) chip[EM]: >>> [E:14342r S:0 M:95828393 (Ack:170409255)] (U) Msg RX from 0:98191D3868EF45B0 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (23073) chip[EM]: <<< [E:14342r S:0 M:170409256 (Ack:95828393)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::18B4:E05A:A31E:E4AA%st1]:59693] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (23093) chip[SC]: Received Sigma3 msg
E (23103) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c.  Falling back to Last Known Good UTC Time
I (23453) chip[EM]: <<< [E:14342r S:0 M:170409257 (Ack:95828393)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::18B4:E05A:A31E:E4AA%st1]:59693] --- Type 0000:40 (SecureChannel:StatusReport)
I (23463) chip[SC]: SecureSession[0x3fcb1558, LSID:57825]: State change 'kEstablishing' --> 'kActive'
I (23473) chip[IN]: CASE Session established to peer: <000000001C8A2556, 2>
I (23493) chip[EM]: >>> [E:14343r S:57825 M:43208917] (S) Msg RX from 2:000000001C8A2556 [F20A] --- Type 0001:08 (IM:InvokeCommandRequest)
I (23503) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x00000030
I (23503) chip[FS]: GeneralCommissioning: Received CommissioningComplete
I (23523) chip[FP]: Metadata for Fabric 0x2 persisted to storage.
I (23533) chip[TS]: Committing Last Known Good Time to storage: 2024-09-27T19:19:46
I (23593) chip[ZCL]: OpCreds: Fabric index 0x2 was committed to storage. Compressed Fabric Id 0xBD1FAE37855CF20A, FabricId 00000000AA3BAD1E, NodeId 00000000AFF8992F, VendorId 0x1384
I (23613) chip[FS]: GeneralCommissioning: Successfully commited pending fabric data
I (23613) chip[FS]: Fail-safe cleanly disarmed
I (23623) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (23633) matter_setup: PRE_UPDATE
I (23633) chip[EM]: <<< [E:14343r S:57825 M:107020286 (Ack:43208917)] (S) Msg TX to 2:000000001C8A2556 [F20A] [UDP:[FE80::18B4:E05A:A31E:E4AA%st1]:59693] --- Type 0001:09 (IM:InvokeCommandResponse)
I (23653) chip[EM]: >>> [E:14342r S:0 M:95828394 (Ack:170409257)] (U) Msg RX from 0:98191D3868EF45B0 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (23673) matter_setup: Fabric is committed
I (23673) chip[SVR]: Commissioning completed successfully
I (23683) chip[DIS]: Updating services using commissioning mode 0
I (23683) chip[DIS]: CHIP minimal mDNS started advertising.
I (23693) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 0
I (23703) chip[DL]: CHIPoBLE unsubscribe received
I (23703) chip[DIS]: Advertise operational node BD1FAE37855CF20A-00000000AFF8992F
I (23713) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: BD1FAE37855CF20A-00000000AFF8992F.
I (23733) chip[DIS]: mDNS service published: _matter._tcp
I (23733) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0 cp=0
I (23743) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1AAE1AD48B069B72.
I (23763) chip[DIS]: mDNS service published: _matterc._udp
I (23763) chip[SC]: SecureSession[0x3fcb1488, LSID:57824]: State change 'kActive' --> 'kPendingEviction'
I (23773) chip[BLE]: Releasing end point's BLE connection back to application.
I (23783) chip[DL]: BLE GAP connection terminated (con 1 reason 0x213)
I (23843) matter_setup: Commissioning complete
I (23843) R2r: Unhandled Signal
I (23843) esp_matter_core: Commissioning Complete
I (23843) chip[EM]: >>> [E:14343r S:57825 M:43208918 (Ack:107020286)] (S) Msg RX from 2:000000001C8A2556 [F20A] --- Type 0000:10 (SecureChannel:StandaloneAck)
E (23853) chip[BLE]: no endpoint for unsub recvd
I (23863) matter_setup: Commissioning window closed
I (23873) esp_matter_core: BLE Disconnected
I (23873) NimBLE: GAP procedure initiated: stop advertising.

I (23883) R2r: Unhandled Signal
I (23883) chip[DL]: BLE deinit successful and memory reclaimed
I (23893) matter_setup: BLE deinitialized and memory reclaimed
I (24333) chip[DL]: IP_EVENT_GOT_IP6
I (24333) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fd3e:308c:b931:e22f:3e84:27ff:fec6:aa1c
I (24333) ROUTE_HOOK: Hook already installed on netif, skip...
I (24343) matter_setup: Interface IP Address changed
I (24343) chip[DIS]: Updating services using commissioning mode 0
I (24353) chip[DIS]: CHIP minimal mDNS started advertising.
I (24373) chip[DIS]: Advertise operational node BD1FAE37855CF20A-00000000AFF8992F
I (24373) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: BD1FAE37855CF20A-00000000AFF8992F.
I (24383) chip[DIS]: mDNS service published: _matter._tcp
I (24383) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0 cp=0
I (24393) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1AAE1AD48B069B72.
I (24413) chip[DIS]: mDNS service published: _matterc._udp