Hello,
Recently I was trying test how matter light bulb example works with google nest hub. To make it possible I followed tutorial specified in this link: https://developers.home.google.com/matter/integration/pair#nordic-semiconductor
I added integration for light bulb devboard device into google developer console:
When I try to pair devboard with light bulb via google home application on my android phone, application finds my device without problems. iIt's showing information, that google home app found "Matter lb nrf example" device ready to pair, then I scan QR code with credentials. After that there is attempt with connection, but it fails and application is showing error: "Cannot connect to the thread network NEST PAN-1938"
light bulb example works on nrf5340dk, build with nrf sdk 2.4.2.
Google nest hub gen2 - software version: 53.110.0.549404370; chromecast firmware version: 1.70.377722
Android phone: motorola g30, developer options turned on, Android 12
Google Home application: ver 3.5.1.4
log from light bulb device:
uart:~$ *** Booting Zephyr OS build v3.3.99-ncs1-1 *** I: Init CHIP stack I: 168 [DL]BLE address: D1:A4:A8:B2:25:66 I: 193 [DL]OpenThread started: OK I: 196 [DL]Setting OpenThread device type to ROUTER I: 200 [SVR]Subscription persistence not supported I: 205 [SVR]Server initializing... I: 209 [TS]Last Known Good Time: 2023-10-06T09:50:45 I: 215 [DMG]AccessControl: initializing I: 218 [DMG]Examples::AccessControlDelegate::Init I: 223 [DMG]AccessControl: setting I: 226 [DMG]DefaultAclStorage: initializing I: 230 [DMG]DefaultAclStorage: 0 entries loaded D: 234 [IN]UDP::Init bind&listen port=5540 E: 238 [IN]SO_REUSEPORT failed: 109 D: 241 [IN]UDP::Init bound to port=5540 D: 245 [IN]BLEBase::Init - setting/overriding transport D: 249 [IN]TransportMgr initialized D: 256 [DL]Using Thread extended MAC for hostname. I: 262 [ZCL]Using ZAP configuration... I: 270 [DMG]AccessControlCluster: initializing D: 274 [DL]Boot reason: 1 I: 276 [ZCL]Initiating Admin Commissioning cluster. I: 281 [ZCL]Endpoint 1 On/off already set to new value I: 287 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 D: 295 [IN]SecureSession[0x200042a0]: Allocated Type:1 LSID:14140 D: 301 [DL]CHIPoBLE advertising set to on I: 305 [DIS]Updating services using commissioning mode 1 E: 310 [DIS]Failed to remove advertised services: 3 D: 315 [DL]Using Thread extended MAC for hostname. I: 320 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 329 [DIS]Failed to advertise commissionable node: 3 E: 334 [DIS]Failed to finalize service update: 3 I: 338 [DIS]Updating services using commissioning mode 1 E: 343 [DIS]Failed to remove advertised services: 3 D: 348 [DL]Using Thread extended MAC for hostname. I: 353 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 362 [DIS]Failed to advertise commissionable node: 3 E: 367 [DIS]Failed to finalize service update: 3 I: 371 [DIS]Delaying proxy of operational discovery: missing delegate I: 377 [IN]CASE Server enabling CASE session setups D: 382 [IN]SecureSession[0x20004358]: Allocated Type:2 LSID:14141 I: 388 [SVR]Joining Multicast groups I: 391 [SVR]Server Listening... I: 394 [DL]Device Configuration: I: 398 [DL] Serial Number: 11223344556677889900 I: 402 [DL] Vendor Id: 65521 (0xFFF1) I: 405 [DL] Product Id: 32773 (0x8005) I: 409 [DL] Product Name: not-specified I: 413 [DL] Hardware Version: 0 I: 417 [DL] Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 I: 423 [DL] Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00) I: 430 [DL] Manufacturing Date: (not set) I: 434 [DL] Device Type: 65535 (0xFFFF) I: 439 [SVR]SetupQRCode: [MT:6FCJ142C00KA0648G00] I: 443 [SVR]Copy/paste the below URL in a browser to see the QR Code: I: 449 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ142C00KA0648G00 I: 458 [SVR]Manual pairing code: [34970112332] I: 464 [DL]CHIP task running I: 470 [DL]CHIPoBLE advertising started I: 474 [ZCL]Cluster OnOff: attribute OnOff set to 0 I: 479 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 I: 486 [DL]NFC Tag emulation started I: BLE advertising is already enabled I: 30475 [DL]CHIPoBLE advertising mode changed to slow I: 30483 [DL]CHIPoBLE advertising started I: 31098 [DL]BLE connection established (ConnId: 0x00) I: 31103 [DL]Current number of connections: 1/1 I: 31107 [DL]CHIPoBLE advertising stopped I: 31111 [DL]NFC Tag emulation stopped D: 32042 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 32049 [BLE]local and remote recv window sizes = 5 I: 32054 [BLE]selected BTP version 4 I: 32057 [BLE]using BTP fragment sizes rx 128 / tx 128. D: 32087 [DL]ConnId: 0x00, New CCCD value: 0x0002 D: 32092 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6) D: 32099 [IN]BLE EndPoint 0x20012588 Connection Complete I: 32104 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 131) D: 32357 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 32403 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 32410 [EM]>>> [E:8779r S:0 M:41731867] (U) Msg RX from 0:2A8CC0332B7E04D1 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) I: 32423 [EM]<<< [E:8779r S:0 M:115455898] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) I: 32435 [IN](U) Sending msg 115455898 to IP address 'BLE' D: 32440 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 32537 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 32545 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 14) D: 32627 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 32635 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 32643 [EM]>>> [E:8779r S:0 M:41731868] (U) Msg RX from 0:2A8CC0332B7E04D1 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) I: 34010 [EM]<<< [E:8779r S:0 M:115455899] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) I: 34021 [IN](U) Sending msg 115455899 to IP address 'BLE' D: 34026 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) E: 34034 [DL]Long dispatch time: 1399 ms, for event type 16388 D: 34157 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 34165 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6) D: 34247 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 34255 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 34263 [EM]>>> [E:8779r S:0 M:41731869] (U) Msg RX from 0:2A8CC0332B7E04D1 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) I: 34274 [EM]<<< [E:8779r S:0 M:115455900] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 34286 [IN](U) Sending msg 115455900 to IP address 'BLE' D: 34291 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 35) I: 34299 [SC]SecureSession[0x200042a0]: Moving from state 'kEstablishing' --> 'kActive' D: 34307 [IN]SecureSession[0x200042a0]: Activated - Type:1 LSID:14140 D: 34313 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:14140 PSID:34194! I: 34322 [SVR]Commissioning completed session establishment step I: 34328 [DIS]Updating services using commissioning mode 0 E: 34333 [DIS]Failed to remove advertised services: 3 D: 34338 [DL]Using Thread extended MAC for hostname. I: 34344 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 E: 34353 [DIS]Failed to advertise extended commissionable node: 3 D: 34359 [DIS]Scheduling extended discovery timeout in 900s E: 34364 [DIS]Failed to finalize service update: 3 D: 34368 [DL]CHIPoBLE advertising set to off I: 34372 [SVR]Device completed Rendezvous process E: 34377 [DL]Long dispatch time: 122 ms, for event type 16388 D: 34383 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 34391 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 34399 [EM]>>> [E:8780r S:14140 M:263019246] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 34409 [IM]Received Read request I: 34414 [EM]<<< [E:8780r S:14140 M:183428950] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 34425 [IN](S) Sending msg 183428950 on secure session with LSID: 14140 D: 34431 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 34562 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 34570 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 34578 [EM]>>> [E:8781r S:14140 M:263019247] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 34589 [IM]Received Read request I: 34594 [EM]<<< [E:8781r S:14140 M:183428951] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 34604 [IN](S) Sending msg 183428951 on secure session with LSID: 14140 D: 34611 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 34697 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 34705 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 34713 [EM]>>> [E:8782r S:14140 M:263019248] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 34724 [IM]Received Read request I: 34729 [EM]<<< [E:8782r S:14140 M:183428952] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 34739 [IN](S) Sending msg 183428952 on secure session with LSID: 14140 D: 34746 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 34832 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 34840 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 34848 [EM]>>> [E:8783r S:14140 M:263019249] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 34859 [IM]Received Read request I: 34864 [EM]<<< [E:8783r S:14140 M:183428953] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 34874 [IN](S) Sending msg 183428953 on secure session with LSID: 14140 D: 34881 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 34967 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 34975 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 34983 [EM]>>> [E:8784r S:14140 M:263019250] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 34994 [IM]Received Read request I: 34999 [EM]<<< [E:8784r S:14140 M:183428954] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 35009 [IN](S) Sending msg 183428954 on secure session with LSID: 14140 D: 35016 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 35102 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 35110 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 35118 [EM]>>> [E:8785r S:14140 M:263019251] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 35129 [IM]Received Read request I: 35134 [EM]<<< [E:8785r S:14140 M:183428955] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 35144 [IN](S) Sending msg 183428955 on secure session with LSID: 14140 D: 35151 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 97) D: 35237 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 35245 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 35253 [EM]>>> [E:8786r S:14140 M:263019252] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 35264 [IM]Received Read request I: 35269 [EM]<<< [E:8786r S:14140 M:183428956] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 35279 [IN](S) Sending msg 183428956 on secure session with LSID: 14140 D: 35286 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72) D: 35372 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 37892 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 40400 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 40502 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 43022 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 45530 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 45632 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 46353 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 46360 [EM]>>> [E:8787r S:14140 M:263019253] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest) I: 46372 [EM]<<< [E:8787r S:14140 M:183428957] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:01 (IM:StatusResponse) I: 46383 [IN](S) Sending msg 183428957 on secure session with LSID: 14140 D: 46389 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43) D: 46487 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 46495 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 46503 [EM]>>> [E:8787r S:14140 M:263019254] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 46515 [FS]GeneralCommissioning: Received ArmFailSafe (120s) I: 46522 [EM]<<< [E:8787r S:14140 M:183428958] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 46533 [IN](S) Sending msg 183428958 on secure session with LSID: 14140 D: 46540 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 46622 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 46630 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 46638 [EM]>>> [E:8788r S:14140 M:263019255] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 46649 [IM]Received Read request I: 46654 [EM]<<< [E:8788r S:14140 M:183428959] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 46664 [IN](S) Sending msg 183428959 on secure session with LSID: 14140 D: 46671 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 46757 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 46765 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 46773 [EM]>>> [E:8789r S:14140 M:263019256] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest) I: 46785 [EM]<<< [E:8789r S:14140 M:183428960] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:01 (IM:StatusResponse) I: 46795 [IN](S) Sending msg 183428960 on secure session with LSID: 14140 D: 46802 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43) D: 46892 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 46900 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 46908 [EM]>>> [E:8789r S:14140 M:263019257] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 46922 [EM]<<< [E:8789r S:14140 M:183428961] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 46933 [IN](S) Sending msg 183428961 on secure session with LSID: 14140 D: 46940 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 47027 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47035 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 47043 [EM]>>> [E:8790r S:14140 M:263019258] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 47055 [ZCL]OpCreds: Certificate Chain request received for DAC I: 47064 [EM]<<< [E:8790r S:14140 M:183428962] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 47076 [IN](S) Sending msg 183428962 on secure session with LSID: 14140 D: 47082 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 47162 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47170 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 47252 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47260 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 47342 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47350 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 47433 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47440 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 47447 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 57) D: 47522 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47530 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 47538 [EM]>>> [E:8791r S:14140 M:263019259] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 47550 [ZCL]OpCreds: Certificate Chain request received for PAI I: 47559 [EM]<<< [E:8791r S:14140 M:183428963] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 47571 [IN](S) Sending msg 183428963 on secure session with LSID: 14140 D: 47577 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 47657 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47665 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 47747 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47755 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 47837 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47845 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 47927 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 47935 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 47942 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 28) D: 48017 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 48025 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 48033 [EM]>>> [E:8792r S:14140 M:263019260] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 48045 [ZCL]OpCreds: Received an AttestationRequest command I: 48081 [ZCL]OpCreds: AttestationRequest successful. I: 48090 [EM]<<< [E:8792r S:14140 M:183428964] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 48101 [IN](S) Sending msg 183428964 on secure session with LSID: 14140 D: 48108 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 48197 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 48205 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 48287 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 48295 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 48377 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 48385 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 48468 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 48475 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 48482 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 48557 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 48565 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 90) D: 48647 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 48655 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 48663 [EM]>>> [E:8793r S:14140 M:263019261] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 48675 [ZCL]OpCreds: Received a CSRRequest command I: 48822 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded I: 48857 [ZCL]OpCreds: CSRRequest successful. I: 48864 [EM]<<< [E:8793r S:14140 M:183428965] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 48876 [IN](S) Sending msg 183428965 on secure session with LSID: 14140 D: 48882 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) E: 48890 [DL]Long dispatch time: 235 ms, for event type 16388 D: 48962 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 48970 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 128) D: 49052 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 49060 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 127) D: 49142 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 51482 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 51618 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 51709 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 51798 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 51806 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) I: 51815 [EM]>>> [E:8794r S:14140 M:263019262] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 51827 [ZCL]OpCreds: Received an AddTrustedRootCertificate command I: 51891 [ZCL]OpCreds: AddTrustedRootCertificate successful. I: 51897 [EM]<<< [E:8794r S:14140 M:183428966] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 51909 [IN](S) Sending msg 183428966 on secure session with LSID: 14140 E: 51915 [DL]Long dispatch time: 117 ms, for event type 16388 D: 51921 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 51928 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 67) D: 52022 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 52030 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 52114 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 52204 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 52294 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 52301 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 52382 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 52390 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 52473 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 52484 [EM]>>> [E:8795r S:14140 M:263019263] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 52496 [ZCL]OpCreds: Received an AddNOC command I: 52504 [FP]Validating NOC chain I: 52621 [FP]NOC chain validation successful I: 52625 [FP]Added new fabric at index: 0x1 I: 52629 [FP]Assigned compressed fabric ID: 0x5A18199ECE7B0023, node ID: 0x000000006ACC9D32 I: 52637 [TS]Last Known Good Time: 2023-10-06T09:50:45 I: 52642 [TS]New proposed Last Known Good Time: 2023-10-06T08:17:48 I: 52648 [TS]Retaining current Last Known Good Time I: 52661 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0xFFFFFFFD00010001 D: 52670 [DL]Using Thread extended MAC for hostname. I: 52675 [DIS]Advertise operational node 5A18199ECE7B0023-000000006ACC9D32 I: 52681 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC I: 52689 [EM]<<< [E:8795r S:14140 M:183428967] (S) Msg TX to 1:FFFFFFFB00000000 [0023] --- Type 0001:09 (IM:InvokeCommandResponse) I: 52700 [IN](S) Sending msg 183428967 on secure session with LSID: 14140 D: 52707 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) E: 52714 [DL]Long dispatch time: 241 ms, for event type 16388 D: 52787 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 52795 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 52878 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 52886 [EM]>>> [E:8796r S:14140 M:263019264] (S) Msg RX from 1:FFFFFFFB00000000 [0023] --- Type 0001:08 (IM:InvokeCommandRequest) I: 52901 [EM]<<< [E:8796r S:14140 M:183428968] (S) Msg TX to 1:FFFFFFFB00000000 [0023] --- Type 0001:09 (IM:InvokeCommandResponse) I: 52913 [IN](S) Sending msg 183428968 on secure session with LSID: 14140 D: 52919 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 53012 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53020 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 53028 [EM]>>> [E:8797r S:14140 M:263019265] (S) Msg RX from 1:FFFFFFFB00000000 [0023] --- Type 0001:02 (IM:ReadRequest) D: 53039 [IM]Received Read request I: 53044 [EM]<<< [E:8797r S:14140 M:183428969] (S) Msg TX to 1:FFFFFFFB00000000 [0023] --- Type 0001:05 (IM:ReportData) I: 53054 [IN](S) Sending msg 183428969 on secure session with LSID: 14140 D: 53061 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 53147 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53155 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 53163 [EM]>>> [E:8798r S:14140 M:263019266] (S) Msg RX from 1:FFFFFFFB00000000 [0023] --- Type 0001:08 (IM:InvokeCommandRequest) D: 53188 [DL]OpenThread State Changed (Flags: 0x101fc100) D: 53193 [DL] Network Name: NEST-PAN-1938 D: 53197 [DL] PAN Id: 0x1938 D: 53202 [DL] Extended PAN Id: 0xA70EAE4399E8A532 D: 53208 [DL] Channel: 19 D: 53210 [DL] Mesh Prefix: fd00:14a1:556e::/64 D: 53216 [DL]OpenThread State Changed (Flags: 0x1100101d) D: 53221 [DL] Device Role: DETACHED D: 53225 [DL] Thread Unicast Addresses: D: 53229 [DL] fd00:14a1:556e:0:b5a5:f712:ac93:6ffe/64 valid D: 53235 [DL] fe80::c85d:8aa7:a107:a802/64 valid preferred D: 55662 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 55802 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 58322 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 60829 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 60932 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 63497 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 66004 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 66107 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 68627 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 71134 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 71237 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 73757 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 76265 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 76368 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 78888 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 81395 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 81497 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 83163 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 83171 [EM]>>> [E:8799r S:14140 M:263019267] (S) Msg RX from 1:FFFFFFFB00000000 [0023] --- Type 0001:0a (IM:TimedRequest) I: 83182 [EM]<<< [E:8799r S:14140 M:183428970] (S) Msg TX to 1:FFFFFFFB00000000 [0023] --- Type 0001:01 (IM:StatusResponse) I: 83193 [IN](S) Sending msg 183428970 on secure session with LSID: 14140 D: 83200 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43) D: 83297 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 83305 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 83313 [EM]>>> [E:8799r S:14140 M:263019268] (S) Msg RX from 1:FFFFFFFB00000000 [0023] --- Type 0001:08 (IM:InvokeCommandRequest) I: 83325 [FS]GeneralCommissioning: Received ArmFailSafe (1s) I: 83332 [EM]<<< [E:8799r S:14140 M:183428971] (S) Msg TX to 1:FFFFFFFB00000000 [0023] --- Type 0001:09 (IM:InvokeCommandResponse) I: 83343 [IN](S) Sending msg 183428971 on secure session with LSID: 14140 D: 83350 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 83433 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) I: 84331 [FS]Fail-safe timer expired E: 84335 [SVR]Failsafe timer expired D: 84338 [IN]SecureSession[0x200042a0]: MarkForEviction Type:1 LSID:14140 I: 84344 [SC]SecureSession[0x200042a0]: Moving from state 'kActive' --> 'kPendingEviction' D: 84352 [IN]SecureSession[0x200042a0]: Released - Type:1 LSID:14140 E: 84359 [SVR]Commissioning failed (attempt 1): 32 D: 84363 [IN]Clearing BLE pending packets. I: 84367 [BLE]Releasing end point's BLE connection back to application. I: 84373 [DL]Closing BLE GATT connection (ConnId 00) D: 84381 [IN]SecureSession[0x200042a0]: Allocated Type:1 LSID:14142 D: 84387 [DL]CHIPoBLE advertising set to on I: 84391 [DIS]Updating services using commissioning mode 1 E: 84396 [DIS]Failed to remove advertised services: 3 D: 84401 [DL]Using Thread extended MAC for hostname. I: 84406 [DIS]Advertise operational node 5A18199ECE7B0023-000000006ACC9D32 E: 84412 [DIS]Failed to advertise operational node: 3 D: 84417 [DL]Using Thread extended MAC for hostname. I: 84423 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 84432 [DIS]Failed to advertise commissionable node: 3 E: 84437 [DIS]Failed to finalize service update: 3 E: 84441 [ZCL]OpCreds: Got FailSafeTimerExpired E: 84446 [ZCL]OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry! D: 84452 [IN]Expiring all sessions for fabric 0x1!! E: 84457 [FP]Reverting pending fabric data for fabric 0x1 E: 84463 [FP]Warning: metadata not found during delete of fabric 0x1 I: 84474 [FP]Fabric (0x1) deleted. I: 84477 [ZCL]OpCreds: Fabric index 0x1 was removed I: 84482 [DIS]Updating services using commissioning mode 1 E: 84487 [DIS]Failed to remove advertised services: 3 D: 84492 [DL]Using Thread extended MAC for hostname. I: 84498 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 84507 [DIS]Failed to advertise commissionable node: 3 E: 84512 [DIS]Failed to finalize service update: 3 I: 84531 [TS]Pending Last Known Good Time: 2023-10-06T09:50:45 I: 84538 [TS]Previous Last Known Good Time: 2023-10-06T09:50:45 I: 84543 [TS]Reverted Last Known Good Time to previous value E: 84550 [FP]Warning: metadata not found during delete of fabric 0x1 E: 84559 [ZCL]OpCreds: failed to delete fabric at index 1: d8 I: 84564 [NP]Reverting Thread operational dataset E: 84575 [DMG]Failed to send command response: 3 E: 84580 [DL]Long dispatch time: 245 ms, for event type 32782 I: 84585 [FS]Fail-safe cleanly disarmed D: 84589 [DL]OpenThread State Changed (Flags: 0x11002006) D: 84594 [DL] Device Role: DISABLED D: 84597 [DL] Thread Unicast Addresses: D: 84602 [DL]OpenThread State Changed (Flags: 0x10000000) I: 84608 [DL]BLE GAP connection terminated (reason 0x13) I: 84614 [DL]Current number of connections: 0/1 I: 84621 [DL]CHIPoBLE advertising started I: 84627 [DL]NFC Tag emulation started I: 114625 [DL]CHIPoBLE advertising mode changed to slow I: 114634 [DL]CHIPoBLE advertising started
EDIT: in google home app, the progress passes stages: 1. connecting to the device 2. Generating matter credentials. It fails on stage: 3. checking network connection
