SDK 2.5.0 Matter bridge example

Hello,

I have tested the Matter bridge example and I am facing an issue. This is how I proceeded: 

1) Compiling the example with the nRF Connect SDK 2.5.0 works correctly.
2) Uploading to the nRF7002-DK card works correctly.
3) Start of pairing and connection to WIFI works correctly
4) Discovery of bridged accessories fails
5) nRF7002-DK reboots.

Environment
- Controller : iOS 17.0.3

Could you help me identify the root cause?

Regards

eived for CHIPoBLE RX characteristic (ConnId 0x00)
D: 126899 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 126972 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 129432 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 131939 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 132012 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 134472 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 136980 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 137052 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 139513 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 142021 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 142092 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 144552 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 147059 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 147132 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
I: 148932 [DL]BLE GAP connection terminated (reason 0x13)
I: 148938 [DL]Current number of connections: 0/1
E: 162067 [BLE]ack recv timeout, closing ep 0x2000b5a0
D: 162072 [IN]Clearing BLE pending packets.
I: 162076 [BLE]Releasing end point's BLE connection back to application.
I: 162082 [DL]Closing BLE GATT connection (ConnId 00)
I: 199639 [FS]Fail-safe timer expired
E: 199642 [SVR]Failsafe timer expired
D: 199645 [IN]SecureSession[0x20004808]: MarkForEviction Type:1 LSID:46460
I: 199652 [SC]SecureSession[0x20004808]: Moving from state 'kActive' --> 'kPendingEviction'
D: 199660 [IN]SecureSession[0x20004808]: Released - Type:1 LSID:46460
E: 199666 [SVR]Commissioning failed (attempt 1): 32
D: 199671 [IN]SecureSession[0x20004808]: Allocated Type:1 LSID:46463
D: 199677 [DL]CHIPoBLE advertising set to on
I: 199681 [DIS]Updating services using commissioning mode 1
E: 199687 [IN]SO_REUSEPORT failed: 109
I: 199690 [DIS]CHIP minimal mDNS started advertising.
D: 199717 [DL]Using wifi MAC for hostname
I: 199722 [DIS]Advertise operational node 6737064BB76EA725-0000000028C71E1A
D: 199730 [DIS]Responding with _matter._tcp.local
D: 199734 [DIS]Responding with 6737064BB76EA725-0000000028C71E1A._matter._tcp.local
D: 199742 [DIS]Responding with 6737064BB76EA725-0000000028C71E1A._matter._tcp.local
D: 199749 [DIS]Responding with F4CE360013BC.local
D: 199753 [DIS]Responding with F4CE360013BC.local
D: 199758 [DIS]Responding with _I6737064BB76EA725._sub._matter._tcp.local
I: 199764 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 6737064BB76EA725-0000000028C71E1A.
I: 199785 [DIS]mDNS service published: _matter._tcp
D: 199790 [DL]Using wifi MAC for hostname
I: 199794 [DIS]Advertise commission parameter vendorID=65521 productID=32770 discriminator=3840/15 cm=1
D: 199804 [DIS]Responding with _matterc._udp.local
D: 199808 [DIS]Responding with 6D832A75C95C609E._matterc._udp.local
D: 199814 [DIS]Responding with F4CE360013BC.local
D: 199818 [DIS]Responding with F4CE360013BC.local
D: 199823 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 199841 [DIS]Responding with _S15._sub._matterc._udp.local
D: 199847 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 199852 [DIS]Responding with _CM._sub._matterc._udp.local
D: 199858 [DIS]Responding with 6D832A75C95C609E._matterc._udp.local
I: 199864 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 6D832A75C95C609E.
I: 199920 [DIS]mDNS service published: _matterc._udp
E: 199925 [ZCL]OpCreds: Got FailSafeTimerExpired
E: 199931 [ZCL]OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry!
D: 199937 [IN]Expiring all sessions for fabric 0x1!!
E: 199942 [FP]Reverting pending fabric data for fabric 0x1
E: 199947 [FP]Warning: metadata not found during delete of fabric 0x1
I: 199957 [FP]Fabric (0x1) deleted.
I: 199960 [ZCL]OpCreds: Fabric index 0x1 was removed
I: 199964 [DIS]Updating services using commissioning mode 1
E: 199970 [IN]SO_REUSEPORT failed: 109
I: 199974 [DIS]CHIP minimal mDNS started advertising.
D: 200094 [DL]Using wifi MAC for hostname
I: 200098 [DIS]Advertise commission parameter vendorID=65521 productID=32770 discriminator=3840/15 cm=1
D: 200108 [DIS]Responding with _matterc._udp.local
D: 200112 [DIS]Responding with 6D832A75C95C609E._matterc._udp.local
D: 200118 [DIS]Responding with F4CE360013BC.local
D: 200122 [DIS]Responding with F4CE360013BC.local
D: 200127 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 200133 [DIS]Responding with _S15._sub._matterc._udp.local
D: 200139 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 200145 [DIS]Responding with _CM._sub._matterc._udp.local
D: 200150 [DIS]Responding with 6D832A75C95C609E._matterc._udp.local
I: 200156 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 6D832A75C95C609E.
I: 200188 [DIS]mDNS service published: _matterc._udp
I: 200202 [TS]Pending Last Known Good Time: 2023-10-26T16:15:20
I: 200208 [TS]Previous Last Known Good Time: 2023-10-26T16:15:20
I: 200214 [TS]Reverted Last Known Good Time to previous value
E: 200220 [FP]Warning: metadata not found during delete of fabric 0x1
E: 200227 [ZCL]OpCreds: failed to delete fabric at index 1: d8
OK
D: 200258 [DL]Disconnect requested
E: 200266 [DL]Long dispatch time: 620 ms, for event type 32782
I: 200271 [FS]Fail-safe cleanly disarmed
I: 200275 [DL]WiFi station disconnected
E: 200289 [DIS]Failed to reply to query: 2000073
I: 200960 [TS]Pending Last Known Good Time: 2023-10-26T16:15:20
I: 200966 [TS]Previous Last Known Good Time: 2023-10-26T16:15:20
I: 200972 [TS]Reverted Last Known Good Time to previous value
I: 200977 [DL]Performing factory reset


uart:~$ *** Booting nRF Connect SDK v2.5.0 ***
I: Init CHIP stack
I: 151 [DL]BLE address: FF:9C:35:29:37:C9
OK
D: 182 [DL]WiFiManager has been initialized
I: 187 [SVR]Subscription persistence not supported
I: 191 [SVR]Server initializing...
I: 194 [TS]Last Known Good Time: [unknown]
I: 198 [TS]Setting Last Known Good Time to firmware build time 2023-10-26T16:15:20
I: 206 [DMG]AccessControl: initializing
I: 210 [DMG]Examples::AccessControlDelegate::Init
I: 214 [DMG]AccessControl: setting
I: 217 [DMG]DefaultAclStorage: initializing
I: 221 [DMG]DefaultAclStorage: 0 entries loaded
D: 226 [IN]UDP::Init bind&listen port=5540
E: 229 [IN]SO_REUSEPORT failed: 109
D: 233 [IN]UDP::Init bound to port=5540
D: 236 [IN]BLEBase::Init - setting/overriding transport
D: 241 [IN]TransportMgr initialized
I: 248 [ZCL]Using ZAP configuration...
I: 253 [DMG]AccessControlCluster: initializing
D: 257 [DL]Boot reason: 6
I: 259 [ZCL]Initiating Admin Commissioning cluster.
I: 264 [ZCL]ERR: writing SceneCount 0x1
D: 268 [IN]SecureSession[0x20004808]: Allocated Type:1 LSID:52835
D: 274 [DL]CHIPoBLE advertising set to on
I: 278 [DIS]Updating services using commissioning mode 1
I: 283 [DIS]CHIP minimal mDNS started advertising.
D: 288 [DL]Using wifi MAC for hostname
I: 291 [DIS]Advertise commission parameter vendorID=65521 productID=32770 discriminator=3840/15 cm=1
D: 300 [DIS]Responding with _matterc._udp.local
D: 305 [DIS]Responding with DAB2D2F5D27B91A4._matterc._udp.local
D: 310 [DIS]Responding with F4CE360013BC.local
D: 315 [DIS]Responding with F4CE360013BC.local
D: 319 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 324 [DIS]Responding with _S15._sub._matterc._udp.local
D: 329 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 335 [DIS]Responding with _CM._sub._matterc._udp.local
D: 340 [DIS]Responding with DAB2D2F5D27B91A4._matterc._udp.local
I: 346 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: DAB2D2F5D27B91A4.
I: 355 [DIS]mDNS service published: _matterc._udp
I: 359 [DIS]Updating services using commissioning mode 1
I: 364 [DIS]CHIP minimal mDNS started advertising.
D: 369 [DL]Using wifi MAC for hostname
I: 373 [DIS]Advertise commission parameter vendorID=65521 productID=32770 discriminator=3840/15 cm=1
D: 382 [DIS]Responding with _matterc._udp.local
D: 386 [DIS]Responding with DAB2D2F5D27B91A4._matterc._udp.local
D: 392 [DIS]Responding with F4CE360013BC.local
D: 396 [DIS]Responding with F4CE360013BC.local
D: 400 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 406 [DIS]Responding with _S15._sub._matterc._udp.local
D: 411 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 416 [DIS]Responding with _CM._sub._matterc._udp.local
D: 421 [DIS]Responding with DAB2D2F5D27B91A4._matterc._udp.local
I: 427 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: DAB2D2F5D27B91A4.
I: 436 [DIS]mDNS service published: _matterc._udp
I: 441 [IN]CASE Server enabling CASE session setups
D: 446 [IN]SecureSession[0x200048c0]: Allocated Type:2 LSID:52836
I: 451 [SVR]Joining Multicast groups
I: 455 [SVR]Server Listening...
I: 458 [DL]Device Configuration:
I: 461 [DL]  Serial Number: 11223344556677889900
I: 465 [DL]  Vendor Id: 65521 (0xFFF1)
I: 468 [DL]  Product Id: 32770 (0x8002)
I: 472 [DL]  Product Name: not-specified
I: 476 [DL]  Hardware Version: 0
I: 479 [DL]  Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
I: 484 [DL]  Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
I: 490 [DL]  Manufacturing Date: 2022-01-01
I: 494 [DL]  Device Type: 65535 (0xFFFF)
I: 498 [SVR]SetupQRCode: [MT:06PS042C00KA0648G00]
I: 502 [SVR]Copy/paste the below URL in a browser to see the QR Code:
I: 508 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A06PS042C00KA0648G00
I: 517 [SVR]Manual pairing code: [34970112332]
I: 522 [ZCL]Shuting down on/off server cluster on endpoint 2
I: No bridged devices to load from the storage.
I: 532 [DL]CHIP task running
I: 538 [DL]CHIPoBLE advertising started
I: 15957 [DL]BLE connection established (ConnId: 0x00)
I: 15962 [DL]Current number of connections: 1/1
E: 15968 [DL]Failed to start CHIPoBLE advertising: -12
E: 15973 [DL]Disabling CHIPoBLE service due to error: 200000c
I: 30541 [DL]CHIPoBLE advertising mode changed to slow
I: 30546 [DL]CHIPoBLE advertising stopped
D: 32061 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 32068 [BLE]local and remote recv window sizes = 5
I: 32073 [BLE]selected BTP version 4
I: 32076 [BLE]using BTP fragment sizes rx 244 / tx 244.
D: 32301 [DL]ConnId: 0x00, New CCCD value: 0x0002
D: 32306 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6)
D: 32313 [IN]BLE EndPoint 0x2000b5a0 Connection Complete
I: 32318 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 498)
D: 32421 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 32429 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 32436 [EM]>>> [E:24174r S:0 M:181340528] (U) Msg RX from 0:1382DFE11BEB45CB [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I: 32449 [EM]<<< [E:24174r S:0 M:246808799] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I: 32461 [IN](U) Sending msg 246808799 to IP address 'BLE'
D: 32467 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 140)
D: 32541 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 32602 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 32609 [EM]>>> [E:24174r S:0 M:181340529] (U) Msg RX from 0:1382DFE11BEB45CB [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I: 33969 [EM]<<< [E:24174r S:0 M:246808800] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I: 33980 [IN](U) Sending msg 246808800 to IP address 'BLE'
D: 33985 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 132)
E: 33993 [DL]Long dispatch time: 1391 ms, for event type 16388
D: 34101 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 34109 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 34116 [EM]>>> [E:24174r S:0 M:181340530] (U) Msg RX from 0:1382DFE11BEB45CB [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I: 34128 [EM]<<< [E:24174r S:0 M:246808801] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 34140 [IN](U) Sending msg 246808801 to IP address 'BLE'
D: 34145 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 35)
I: 34153 [SC]SecureSession[0x20004808]: Moving from state 'kEstablishing' --> 'kActive'
D: 34161 [IN]SecureSession[0x20004808]: Activated - Type:1 LSID:52835
D: 34168 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:52835 PSID:25573!
I: 34176 [SVR]Commissioning completed session establishment step
I: 34182 [DIS]Updating services using commissioning mode 0
I: 34188 [DIS]CHIP minimal mDNS started advertising.
D: 34193 [DL]CHIPoBLE advertising set to off
I: 34197 [SVR]Device completed Rendezvous process
D: 34221 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 34229 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 34237 [EM]>>> [E:24175r S:52835 M:25251511] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 34248 [IM]Received Read request
I: 34252 [EM]<<< [E:24175r S:52835 M:57895832] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 34263 [IN](S) Sending msg 57895832 on secure session with LSID: 52835
D: 34269 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 73)
D: 34341 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 34349 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 34357 [EM]>>> [E:24176r S:52835 M:25251512] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 34368 [IM]Received Read request
I: 34373 [EM]<<< [E:24176r S:52835 M:57895833] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 34383 [IN](S) Sending msg 57895833 on secure session with LSID: 52835
D: 34390 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 95)
D: 34461 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 34469 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 34477 [EM]>>> [E:24177r S:52835 M:25251513] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 34488 [IM]Received Read request
I: 34493 [EM]<<< [E:24177r S:52835 M:57895834] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 34503 [IN](S) Sending msg 57895834 on secure session with LSID: 52835
D: 34509 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 34581 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 34701 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 34709 [EM]>>> [E:24178r S:52835 M:25251514] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 34720 [IM]Received Read request
I: 34725 [EM]<<< [E:24178r S:52835 M:57895835] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 34735 [IN](S) Sending msg 57895835 on secure session with LSID: 52835
D: 34742 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 34821 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 34829 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 34837 [EM]>>> [E:24179r S:52835 M:25251515] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 34848 [IM]Received Read request
I: 34852 [EM]<<< [E:24179r S:52835 M:57895836] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 34863 [IN](S) Sending msg 57895836 on secure session with LSID: 52835
D: 34869 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 34941 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 34949 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 34957 [EM]>>> [E:24180r S:52835 M:25251516] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
D: 34972 [DL]WiFi scanning started...
D: 37457 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 37581 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
I: 39587 [DL]Wi-Fi scan done (0)
I: 39593 [EM]<<< [E:24180r S:52835 M:57895837] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 39604 [IN](S) Sending msg 57895837 on secure session with LSID: 52835
D: 39611 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 39681 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 39689 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 39801 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 39809 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 33)
D: 39921 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 39929 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 40042 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 40050 [EM]>>> [E:24181r S:52835 M:25251517] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 40060 [IM]Received Read request
I: 40065 [EM]<<< [E:24181r S:52835 M:57895838] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 40076 [IN](S) Sending msg 57895838 on secure session with LSID: 52835
D: 40082 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 40161 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 40282 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 40291 [EM]>>> [E:24182r S:52835 M:25251518] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 40301 [IM]Received Read request
I: 40310 [EM]<<< [E:24182r S:52835 M:57895839] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 40321 [IN](S) Sending msg 57895839 on secure session with LSID: 52835
D: 40327 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 40401 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 40409 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 21)
D: 40521 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 40529 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 40537 [EM]>>> [E:24183r S:52835 M:25251519] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 40549 [FS]GeneralCommissioning: Received ArmFailSafe (60s)
I: 40556 [EM]<<< [E:24183r S:52835 M:57895840] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 40567 [IN](S) Sending msg 57895840 on secure session with LSID: 52835
D: 40573 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 40701 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 40709 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 40717 [EM]>>> [E:24184r S:52835 M:25251520] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 40731 [EM]<<< [E:24184r S:52835 M:57895841] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 40742 [IN](S) Sending msg 57895841 on secure session with LSID: 52835
D: 40749 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 40821 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 40829 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 40837 [EM]>>> [E:24185r S:52835 M:25251521] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 40849 [ZCL]OpCreds: Certificate Chain request received for PAI
I: 40858 [EM]<<< [E:24185r S:52835 M:57895842] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 40869 [IN](S) Sending msg 57895842 on secure session with LSID: 52835
D: 40876 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 41001 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 41009 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 41121 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 41129 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 48)
D: 41241 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 41249 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 41257 [EM]>>> [E:24186r S:52835 M:25251522] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 41269 [ZCL]OpCreds: Certificate Chain request received for DAC
I: 41278 [EM]<<< [E:24186r S:52835 M:57895843] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 41290 [IN](S) Sending msg 57895843 on secure session with LSID: 52835
D: 41296 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 41421 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 41429 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 41541 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 41549 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 77)
D: 41661 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 41669 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 41677 [EM]>>> [E:24187r S:52835 M:25251523] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 41689 [ZCL]OpCreds: Received an AttestationRequest command
I: 41724 [ZCL]OpCreds: AttestationRequest successful.
I: 41733 [EM]<<< [E:24187r S:52835 M:57895844] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 41745 [IN](S) Sending msg 57895844 on secure session with LSID: 52835
D: 41751 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 41841 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 41849 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 41961 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 41969 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 237)
D: 42081 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 42089 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 42097 [EM]>>> [E:24188r S:52835 M:25251524] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 42109 [FS]GeneralCommissioning: Received ArmFailSafe (60s)
I: 42116 [EM]<<< [E:24188r S:52835 M:57895845] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 42127 [IN](S) Sending msg 57895845 on secure session with LSID: 52835
D: 42134 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 42261 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 44721 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 45022 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 45030 [EM]>>> [E:24189r S:52835 M:25251525] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 45042 [ZCL]OpCreds: Received a CSRRequest command
I: 45186 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded
I: 45221 [ZCL]OpCreds: CSRRequest successful.
I: 45228 [EM]<<< [E:24189r S:52835 M:57895846] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 45239 [IN](S) Sending msg 57895846 on secure session with LSID: 52835
D: 45245 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
E: 45253 [DL]Long dispatch time: 231 ms, for event type 16388
D: 45321 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 45329 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 138)
D: 45441 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 45449 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 45562 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 45571 [EM]>>> [E:24190r S:52835 M:25251526] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 45583 [ZCL]OpCreds: Received an AddTrustedRootCertificate command
I: 45642 [ZCL]OpCreds: AddTrustedRootCertificate successful.
I: 45648 [EM]<<< [E:24190r S:52835 M:57895847] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 45660 [IN](S) Sending msg 57895847 on secure session with LSID: 52835
D: 45666 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 68)
E: 45674 [DL]Long dispatch time: 112 ms, for event type 16388
D: 45741 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 45749 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 45862 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 45872 [EM]>>> [E:24191r S:52835 M:25251527] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 45884 [ZCL]OpCreds: Received an AddNOC command
I: 45890 [FP]Validating NOC chain
I: 45947 [FP]NOC chain validation successful
I: 45951 [FP]Added new fabric at index: 0x1
I: 45955 [FP]Assigned compressed fabric ID: 0x016B47F377E5FE0B, node ID: 0x000000003BDE7705
I: 45963 [TS]Last Known Good Time: 2023-10-26T16:15:20
I: 45968 [TS]New proposed Last Known Good Time: 2023-10-26T14:24:33
I: 45974 [TS]Retaining current Last Known Good Time
I: 45986 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x00000000212740BC
D: 45995 [DL]Using wifi MAC for hostname
I: 45999 [DIS]Advertise operational node 016B47F377E5FE0B-000000003BDE7705
D: 46006 [DIS]Responding with _matter._tcp.local
D: 46010 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 46017 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 46025 [DIS]Responding with F4CE360013BC.local
D: 46029 [DIS]Responding with F4CE360013BC.local
D: 46033 [DIS]Responding with _I016B47F377E5FE0B._sub._matter._tcp.local
I: 46040 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 016B47F377E5FE0B-000000003BDE7705.
I: 46050 [DIS]mDNS service published: _matter._tcp
I: 46055 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC
I: 46062 [EM]<<< [E:24191r S:52835 M:57895848] (S) Msg TX to 1:FFFFFFFB00000000 [FE0B] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 46073 [IN](S) Sending msg 57895848 on secure session with LSID: 52835
D: 46080 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
E: 46087 [DL]Long dispatch time: 225 ms, for event type 16388
D: 46161 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 46169 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 46177 [EM]>>> [E:24192r S:52835 M:25251528] (S) Msg RX from 1:FFFFFFFB00000000 [FE0B] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 46190 [EM]<<< [E:24192r S:52835 M:57895849] (S) Msg TX to 1:FFFFFFFB00000000 [FE0B] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 46202 [IN](S) Sending msg 57895849 on secure session with LSID: 52835
D: 46208 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 46281 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 46289 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 46297 [EM]>>> [E:24193r S:52835 M:25251529] (S) Msg RX from 1:FFFFFFFB00000000 [FE0B] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 46309 [FS]GeneralCommissioning: Received ArmFailSafe (76s)
I: 46316 [EM]<<< [E:24193r S:52835 M:57895850] (S) Msg TX to 1:FFFFFFFB00000000 [FE0B] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 46327 [IN](S) Sending msg 57895850 on secure session with LSID: 52835
D: 46334 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 46461 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 46469 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 46477 [EM]>>> [E:24194r S:52835 M:25251530] (S) Msg RX from 1:FFFFFFFB00000000 [FE0B] --- Type 0001:08 (IM:InvokeCommandRequest)
D: 46489 [DL]Connecting to WiFi network: FL-IoT
D: 46495 [DL]WiFi scanning started...
D: 48976 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 49101 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
I: 50949 [DL]Wi-Fi scan done (0)
OK
OK
OK
OK
OK
OK
OK
OK
OK
OK
I: 52506 [DL]Connection to FL-IoT requested [RSSI=-38]
E: 52510 [DL]Long dispatch time: 1561 ms, for event type 3
D: 52516 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 55023 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 55101 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
I: 57258 [DL]Connected to WiFi network
I: 57264 [EM]<<< [E:24194r S:52835 M:57895851] (S) Msg TX to 1:FFFFFFFB00000000 [FE0B] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 57277 [IN](S) Sending msg 57895851 on secure session with LSID: 52835
D: 57283 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 69)
D: 57291 [DL]Using wifi MAC for hostname
I: 57294 [DIS]Advertise operational node 016B47F377E5FE0B-000000003BDE7705
D: 57301 [DIS]Responding with _matter._tcp.local
D: 57306 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 57313 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 57320 [DIS]Responding with F4CE360013BC.local
D: 57325 [DIS]Responding with F4CE360013BC.local
D: 57329 [DIS]Responding with _I016B47F377E5FE0B._sub._matter._tcp.local
I: 57336 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 016B47F377E5FE0B-000000003BDE7705.
I: 57346 [DIS]mDNS service published: _matter._tcp
I: 57351 [SVR]Operational advertising enabled
I: 57356 [SWU]Stopping the watchdog timer
I: 57360 [SWU]Starting the periodic query timer, timeout: 86400 seconds
E: 57366 [SVR]Server initialization complete
I: 57370 [DIS]Updating services using commissioning mode 0
E: 57377 [IN]SO_REUSEPORT failed: 109
I: 57382 [DIS]CHIP minimal mDNS started advertising.
D: 57397 [DL]Using wifi MAC for hostname
I: 57401 [DIS]Advertise operational node 016B47F377E5FE0B-000000003BDE7705
D: 57408 [DIS]Responding with _matter._tcp.local
D: 57413 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 57420 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 57427 [DIS]Responding with F4CE360013BC.local
D: 57432 [DIS]Responding with F4CE360013BC.local
D: 57436 [DIS]Responding with _I016B47F377E5FE0B._sub._matter._tcp.local
I: 57442 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 016B47F377E5FE0B-000000003BDE7705.
I: 57457 [DIS]mDNS service published: _matter._tcp
D: 57463 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
I: 57471 [DIS]Updating services using commissioning mode 0
E: 57490 [IN]SO_REUSEPORT failed: 109
I: 57493 [DIS]CHIP minimal mDNS started advertising.
D: 57508 [DL]Using wifi MAC for hostname
I: 57512 [DIS]Advertise operational node 016B47F377E5FE0B-000000003BDE7705
D: 57519 [DIS]Responding with _matter._tcp.local
D: 57524 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 57531 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 57538 [DIS]Responding with F4CE360013BC.local
D: 57543 [DIS]Responding with F4CE360013BC.local
D: 57547 [DIS]Responding with _I016B47F377E5FE0B._sub._matter._tcp.local
I: 57553 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 016B47F377E5FE0B-000000003BDE7705.
I: 57569 [DIS]mDNS service published: _matter._tcp
E: 57574 [DL]Long dispatch time: 103 ms, for event type 32784
D: 57581 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 60088 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 60201 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 62661 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 65168 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 65241 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 67701 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 70209 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 70281 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 72741 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 74842 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 74850 [EM]>>> [E:24197r S:52835 M:25251531] (S) Msg RX from 1:FFFFFFFB00000000 [FE0B] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 74862 [FS]GeneralCommissioning: Received ArmFailSafe (67s)
I: 74868 [EM]<<< [E:24197r S:52835 M:57895852] (S) Msg TX to 1:FFFFFFFB00000000 [FE0B] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 74880 [IN](S) Sending msg 57895852 on secure session with LSID: 52835
D: 74892 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 74961 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 77421 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 79929 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 80001 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 82461 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 84973 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 85101 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 87561 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 90069 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 90141 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 90922 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 90930 [EM]>>> [E:24198r S:52835 M:25251532] (S) Msg RX from 1:FFFFFFFB00000000 [FE0B] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 90942 [FS]GeneralCommissioning: Received ArmFailSafe (68s)
I: 90948 [EM]<<< [E:24198r S:52835 M:57895853] (S) Msg TX to 1:FFFFFFFB00000000 [FE0B] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 90960 [IN](S) Sending msg 57895853 on secure session with LSID: 52835
D: 90967 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 91041 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 93501 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 96008 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 96081 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 98542 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 101050 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 101121 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 103581 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 106088 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 106161 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 108262 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 108271 [EM]>>> [E:24200r S:52835 M:25251533] (S) Msg RX from 1:FFFFFFFB00000000 [FE0B] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 108283 [FS]GeneralCommissioning: Received ArmFailSafe (103s)
I: 108290 [EM]<<< [E:24200r S:52835 M:57895854] (S) Msg TX to 1:FFFFFFFB00000000 [FE0B] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 108301 [IN](S) Sending msg 57895854 on secure session with LSID: 52835
D: 108308 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 108381 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 110841 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 113348 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 113421 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 115881 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 118388 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 118461 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 120921 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 123429 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 123501 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 125961 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 128470 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 128541 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 131001 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 133515 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 133641 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 136101 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 138610 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 138682 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 141142 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 143650 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 143722 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 146181 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 148689 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 148762 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 151221 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 153729 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 153862 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 156322 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 158829 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 158902 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 160522 [DL]ConnId: 0x00, New CCCD value: 0x0000
D: 160526 [IN]Clearing BLE pending packets.
I: 160530 [BLE]Releasing end point's BLE connection back to application.
I: 160536 [DL]Closing BLE GATT connection (ConnId 00)
I: 160582 [DL]BLE GAP connection terminated (reason 0x13)
I: 160587 [DL]Current number of connections: 0/1
I: 211292 [FS]Fail-safe timer expired
E: 211295 [SVR]Failsafe timer expired
D: 211299 [IN]SecureSession[0x20004808]: MarkForEviction Type:1 LSID:52835
I: 211305 [SC]SecureSession[0x20004808]: Moving from state 'kActive' --> 'kPendingEviction'
D: 211313 [IN]SecureSession[0x20004808]: Released - Type:1 LSID:52835
E: 211319 [SVR]Commissioning failed (attempt 1): 32
D: 211325 [IN]SecureSession[0x20004808]: Allocated Type:1 LSID:52837
D: 211331 [DL]CHIPoBLE advertising set to on
I: 211335 [DIS]Updating services using commissioning mode 1
E: 211340 [IN]SO_REUSEPORT failed: 109
I: 211344 [DIS]CHIP minimal mDNS started advertising.
D: 211370 [DL]Using wifi MAC for hostname
I: 211375 [DIS]Advertise operational node 016B47F377E5FE0B-000000003BDE7705
D: 211382 [DIS]Responding with _matter._tcp.local
D: 211386 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 211395 [DIS]Responding with 016B47F377E5FE0B-000000003BDE7705._matter._tcp.local
D: 211402 [DIS]Responding with F4CE360013BC.local
D: 211407 [DIS]Responding with F4CE360013BC.local
D: 211411 [DIS]Responding with _I016B47F377E5FE0B._sub._matter._tcp.local
I: 211418 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 016B47F377E5FE0B-000000003BDE7705.
I: 211438 [DIS]mDNS service published: _matter._tcp
D: 211444 [DL]Using wifi MAC for hostname
I: 211447 [DIS]Advertise commission parameter vendorID=65521 productID=32770 discriminator=3840/15 cm=1
D: 211457 [DIS]Responding with _matterc._udp.local
D: 211461 [DIS]Responding with DAB2D2F5D27B91A4._matterc._udp.local
D: 211467 [DIS]Responding with F4CE360013BC.local
D: 211472 [DIS]Responding with F4CE360013BC.local
D: 211476 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 211482 [DIS]Responding with _S15._sub._matterc._udp.local
D: 211487 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 211508 [DIS]Responding with _CM._sub._matterc._udp.local
D: 211513 [DIS]Responding with DAB2D2F5D27B91A4._matterc._udp.local
I: 211519 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: DAB2D2F5D27B91A4.
I: 211577 [DIS]mDNS service published: _matterc._udp
E: 211582 [ZCL]OpCreds: Got FailSafeTimerExpired
E: 211586 [ZCL]OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry!
D: 211593 [IN]Expiring all sessions for fabric 0x1!!
E: 211599 [FP]Reverting pending fabric data for fabric 0x1
E: 211604 [FP]Warning: metadata not found during delete of fabric 0x1
I: 211614 [FP]Fabric (0x1) deleted.
I: 211617 [ZCL]OpCreds: Fabric index 0x1 was removed
I: 211621 [DIS]Updating services using commissioning mode 1
E: 211627 [IN]SO_REUSEPORT failed: 109
I: 211631 [DIS]CHIP minimal mDNS started advertising.
D: 211755 [DL]Using wifi MAC for hostname
I: 211758 [DIS]Advertise commission parameter vendorID=65521 productID=32770 discriminator=3840/15 cm=1
D: 211768 [DIS]Responding with _matterc._udp.local
D: 211772 [DIS]Responding with DAB2D2F5D27B91A4._matterc._udp.local
D: 211778 [DIS]Responding with F4CE360013BC.local
D: 211783 [DIS]Responding with F4CE360013BC.local
D: 211787 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 211793 [DIS]Responding with _S15._sub._matterc._udp.local
D: 211799 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 211805 [DIS]Responding with _CM._sub._matterc._udp.local
D: 211810 [DIS]Responding with DAB2D2F5D27B91A4._matterc._udp.local
I: 211816 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: DAB2D2F5D27B91A4.
I: 211848 [DIS]mDNS service published: _matterc._udp
I: 211862 [TS]Pending Last Known Good Time: 2023-10-26T16:15:20
I: 211868 [TS]Previous Last Known Good Time: 2023-10-26T16:15:20
I: 211874 [TS]Reverted Last Known Good Time to previous value
E: 211880 [FP]Warning: metadata not found during delete of fabric 0x1
E: 211887 [ZCL]OpCreds: failed to delete fabric at index 1: d8
OK
D: 211918 [DL]Disconnect requested
E: 211921 [DL]Long dispatch time: 626 ms, for event type 32782
I: 211927 [FS]Fail-safe cleanly disarmed
I: 211930 [DL]WiFi station disconnected
I: 212617 [TS]Pending Last Known Good Time: 2023-10-26T16:15:20
I: 212623 [TS]Previous Last Known Good Time: 2023-10-26T16:15:20
I: 212629 [TS]Reverted Last Known Good Time to previous value
I: 212634 [DL]Performing factory reset


uart:~$ *** Booting nRF Connect SDK v2.5.0 ***
I: Init CHIP stack
I: 152 [DL]BLE address: C1:AD:46:EC:63:A8
OK
D: 182 [DL]WiFiManager has been initialized
I: 187 [SVR]Subscription persistence not supported
I: 191 [SVR]Server initializing...
I: 194 [TS]Last Known Good Time: [unknown]
I: 198 [TS]Setting Last Known Good Time to firmware build time 2023-10-26T16:15:20
I: 207 [DMG]AccessControl: initializing
I: 210 [DMG]Examples::AccessControlDelegate::Init
I: 214 [DMG]AccessControl: setting
I: 218 [DMG]DefaultAclStorage: initializing
I: 221 [DMG]DefaultAclStorage: 0 entries loaded
D: 226 [IN]UDP::Init bind&listen port=5540
E: 230 [IN]SO_REUSEPORT failed: 109
D: 233 [IN]UDP::Init bound to port=5540
D: 236 [IN]BLEBase::Init - setting/overriding transport
D: 241 [IN]TransportMgr initialized
I: 248 [ZCL]Using ZAP configuration...
I: 253 [DMG]AccessControlCluster: initializing
D: 257 [DL]Boot reason: 6
I: 259 [ZCL]Initiating Admin Commissioning cluster.
I: 264 [ZCL]ERR: writing SceneCount 0x1
D: 268 [IN]SecureSession[0x20004808]: Allocated Type:1 LSID:4369
D: 274 [DL]CHIPoBLE advertising set to on
I: 278 [DIS]Updating services using commissioning mode 1
I: 283 [DIS]CHIP minimal mDNS started advertising.
D: 288 [DL]Using wifi MAC for hostname
I: 291 [DIS]Advertise commission parameter vendorID=65521 productID=32770 discriminator=3840/15 cm=1
D: 300 [DIS]Responding with _matterc._udp.local
D: 305 [DIS]Responding with 0A028DE8EC7144F1._matterc._udp.local
D: 310 [DIS]Responding with F4CE360013BC.local
D: 315 [DIS]Responding with F4CE360013BC.local
D: 319 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 324 [DIS]Responding with _S15._sub._matterc._udp.local
D: 329 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 335 [DIS]Responding with _CM._sub._matterc._udp.local
D: 340 [DIS]Responding with 0A028DE8EC7144F1._matterc._udp.local
I: 346 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 0A028DE8EC7144F1.
I: 355 [DIS]mDNS service published: _matterc._udp
I: 359 [DIS]Updating services using commissioning mode 1
I: 364 [DIS]CHIP minimal mDNS started advertising.
D: 369 [DL]Using wifi MAC for hostname
I: 373 [DIS]Advertise commission parameter vendorID=65521 productID=32770 discriminator=3840/15 cm=1
D: 382 [DIS]Responding with _matterc._udp.local
D: 386 [DIS]Responding with 0A028DE8EC7144F1._matterc._udp.local
D: 392 [DIS]Responding with F4CE360013BC.local
D: 396 [DIS]Responding with F4CE360013BC.local
D: 400 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 406 [DIS]Responding with _S15._sub._matterc._udp.local
D: 411 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 416 [DIS]Responding with _CM._sub._matterc._udp.local
D: 421 [DIS]Responding with 0A028DE8EC7144F1._matterc._udp.local
I: 427 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 0A028DE8EC7144F1.
I: 436 [DIS]mDNS service published: _matterc._udp
I: 441 [IN]CASE Server enabling CASE session setups
D: 446 [IN]SecureSession[0x200048c0]: Allocated Type:2 LSID:4370
I: 452 [SVR]Joining Multicast groups
I: 455 [SVR]Server Listening...
I: 458 [DL]Device Configuration:
I: 461 [DL]  Serial Number: 11223344556677889900
I: 465 [DL]  Vendor Id: 65521 (0xFFF1)
I: 469 [DL]  Product Id: 32770 (0x8002)
I: 472 [DL]  Product Name: not-specified
I: 476 [DL]  Hardware Version: 0
I: 479 [DL]  Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
I: 484 [DL]  Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
I: 490 [DL]  Manufacturing Date: 2022-01-01
I: 494 [DL]  Device Type: 65535 (0xFFFF)
I: 498 [SVR]SetupQRCode: [MT:06PS042C00KA0648G00]
I: 502 [SVR]Copy/paste the below URL in a browser to see the QR Code:
I: 509 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A06PS042C00KA0648G00
I: 517 [SVR]Manual pairing code: [34970112332]
I: 522 [ZCL]Shuting down on/off server cluster on endpoint 2
I: No bridged devices to load from the storage.
I: 532 [DL]CHIP task running
I: 538 [DL]CHIPoBLE advertising started

matter_bridge.zip

  • Hi, 

    Do you use the chip tool from https://github.com/nrfconnect/sdk-connectedhomeip/releases v2.5.0? If not, please use it. It requires to use the corresponding version of sdk-connectedhomeip as NCS. 

    Regards,
    Amanda H

  • Hi Amanda,

    I am currently using iOS 17.0.3 as a Matter controller for commissioning. I use the Matter bridge function with other development boards without any problem. 
    I will try with 2.5.0 chip-tool-linux_x64.zip.

    Regards

  • I set up a Linux machine with a Bluetooth adapter. I've tried pairing but it doesn't work.
    Do you know why?


    user@matter-vm:~$ hciconfig
    hci1: Type: Primary Bus: USB
    BD Address: 00:1A:7D:DA:71:15 ACL MTU: 310:10 SCO MTU: 64:8
    UP RUNNING
    RX bytes:33757 acl:0 sco:0 events:196 errors:0
    TX bytes:3270 acl:0 sco:0 commands:58 errors:0
    
    user@matter-vm:~$ ./chip-tool-debug pairing ble-wifi 10 IOT *************** 20202021 3840
    [1699634709.121567][57490:57490] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
    [1699634709.121970][57490:57490] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini
    [1699634709.122111][57490:57490] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini
    [1699634709.122247][57490:57490] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini
    [1699634709.122621][57490:57490] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-of9Ehj)
    [1699634709.123227][57490:57490] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
    [1699634709.123324][57490:57490] CHIP:DL: NVS set: chip-counters/reboot-count = 2 (0x2)
    [1699634709.123771][57490:57490] CHIP:DL: Got Ethernet interface: ens33
    [1699634709.124080][57490:57490] CHIP:DL: Found the primary Ethernet interface:ens33
    [1699634709.124755][57490:57490] CHIP:DL: Failed to get WiFi interface
    [1699634709.124842][57490:57490] CHIP:DL: Failed to reset WiFi statistic counts
    [1699634709.124923][57490:57490] CHIP:IN: UDP::Init bind&listen port=0
    [1699634709.125136][57490:57490] CHIP:IN: UDP::Init bound to port=56266
    [1699634709.125233][57490:57490] CHIP:IN: UDP::Init bind&listen port=0
    [1699634709.125431][57490:57490] CHIP:IN: UDP::Init bound to port=43232
    [1699634709.125546][57490:57490] CHIP:IN: BLEBase::Init - setting/overriding transport
    [1699634709.125649][57490:57490] CHIP:IN: TransportMgr initialized
    [1699634709.125774][57490:57490] CHIP:FP: Initializing FabricTable from persistent storage
    [1699634709.125910][57490:57490] CHIP:TS: Last Known Good Time: 2023-10-26T12:12:01
    [1699634709.126080][57490:57490] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xF4774365E65BE1BC, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1
    [1699634709.127123][57490:57490] CHIP:ZCL: Using ZAP configuration...
    [1699634709.130723][57490:57490] CHIP:DL: Avahi client registered
    [1699634709.132103][57490:57490] CHIP:CTL: System State Initialized...
    [1699634709.132260][57490:57490] CHIP:CTL: Stopping commissioning discovery over DNS-SD
    [1699634709.132387][57490:57490] CHIP:CTL: Setting attestation nonce to random value
    [1699634709.132590][57490:57490] CHIP:CTL: Setting CSR nonce to random value
    [1699634709.132956][57490:57492] CHIP:DL: CHIP task running
    [1699634709.133096][57490:57492] CHIP:DL: HandlePlatformSpecificBLEEvent 32784
    [1699634709.133633][57490:57492] CHIP:CTL: Stopping commissioning discovery over DNS-SD
    [1699634709.133673][57490:57492] CHIP:CTL: Setting attestation nonce to random value
    [1699634709.133698][57490:57492] CHIP:CTL: Setting CSR nonce to random value
    [1699634709.136163][57490:57492] CHIP:CTL: Generating NOC
    [1699634709.138495][57490:57492] CHIP:FP: Validating NOC chain
    [1699634709.147065][57490:57492] CHIP:FP: NOC chain validation successful
    [1699634709.147114][57490:57492] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669
    [1699634709.147387][57490:57492] CHIP:TS: Last Known Good Time: 2023-10-26T12:12:01
    [1699634709.147427][57490:57492] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00
    [1699634709.147449][57490:57492] CHIP:TS: Retaining current Last Known Good Time
    [1699634709.149551][57490:57492] CHIP:FP: Metadata for Fabric 0x1 persisted to storage.
    [1699634709.151225][57490:57492] CHIP:TS: Committing Last Known Good Time to storage: 2023-10-26T12:12:01
    [1699634709.152579][57490:57492] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: F4774365E65BE1BC)
    [1699634709.159313][57490:57492] CHIP:CTL: Setting wifi credentials from parameters
    [1699634709.159352][57490:57492] CHIP:CTL: Setting attestation nonce to random value
    [1699634709.159374][57490:57492] CHIP:CTL: Setting CSR nonce to random value
    [1699634709.159611][57490:57492] CHIP:CTL: Commission called for node ID 0x000000000000000A
    [1699634709.164811][57490:57491] CHIP:DL: TRACE: Bus acquired for name C-e092
    [1699634709.168810][57490:57491] CHIP:DL: FAIL: NULL apEndpoint->mpAdapter in bluezObjectsSetup
    [1699634709.169276][57490:57492] CHIP:DL: PlatformBlueZInit init success
    [1699634709.169341][57490:57492] CHIP:DIS: Closing all BLE connections
    [1699634709.169945][57490:57492] CHIP:BLE: No adapter available for new connection establishment
    [1699634716.465740][57490:57491] CHIP:DL: No wifi interface name. Ignoring IP update event.
    [1699634723.692504][57490:57491] CHIP:DL: No wifi interface name. Ignoring IP update event.
    [1699634729.527956][57490:57491] CHIP:DL: No wifi interface name. Ignoring IP update event.

  • Hi, 

    as you can see logs indicate that:

    [1699634709.168810][57490:57491] CHIP:DL: FAIL: NULL apEndpoint->mpAdapter in bluezObjectsSetup
    [1699634709.169945][57490:57492] CHIP:BLE: No adapter available for new connection establishment

     

    This means something is wrong with the BLE adapter on the Host side.

     

    Judging from the output of hciconfig, you have hci1 not the hci0 interface.

     

    In this case there is a need to add --ble-adapter 1 at the end. All is described in the documentation:

    https://developer.nordicsemi.com/nRF_Connect_SDK/doc/latest/matter/chip_tool_guide.html#choosing-the-bluetooth-adapter

    -Amanda H.

Related