[NCS] [matter] SmartThings 앱에서 light-bulb 제어하기

: nRF7002 DK 보드에 matter light bulb 이미지와 SmartThings 앱을 연동해 봤습니다.

 

▶ 기본 준비사항

 → Nordic nRF7002-DK 보드

 → 삼성 SmartThings Station

 

 → Visual Studio Code 에서  matter light_bulb 프로젝트 이미지 빌드 && 플래시

 

 → 스마트폰에 SmartThings 앱 설치

 

 → QR 코드 ( DK 보드 시리얼 부팅로그 다음처럼 나옴.)

     >> https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ142C00KA0648G00

 

 

▶  SmartThings 앱을 통한  matter 장치 연결 과정

   → SmartThings Station 이 연결된 상태에서 아래 그림의 우측상단의 "기기 추가 아이콘"를 눌러줍니다.

 

   → 기기추가 화면에서 "QR 코드 스캔" 선택

 

 

→   아래 사이트의 QR 코드를 스캔 해줍니다.

    https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ142C00KA0648G00

 

→   자동 진행 화면

→   Matter 인증기기가  아니라는 창이 나오면  계속 눌러줍니다.

 

→   WIFI 네트워크 확인

 

→  삼성계정에 MATTER 장치 등록을 하네요.

 

→  기기이름 설정후 완료 버튼 클릭

 

▶  commissioning 중 light bulb 장치 로그

I: 30849 [DL]CHIPoBLE advertising mode changed to slow
I: 30857 [DL]CHIPoBLE advertising started
I: 70832 [DL]BLE connection established (ConnId: 0x00)
I: 70837 [DL]Current number of connections: 1/1
I: 70841 [DL]CHIPoBLE advertising stopped
I: 70845 [DL]NFC Tag emulation stopped
D: 71584 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 71591 [BLE]local and remote recv window sizes = 5
I: 71596 [BLE]selected BTP version 4
I: 71600 [BLE]using BTP fragment sizes rx 244 / tx 244.
D: 71605 [DL]ConnId: 0x00, New CCCD value: 0x0002
D: 71610 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6)
D: 71617 [IN]BLE EndPoint 0x20008690 Connection Complete
I: 71622 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 247)
D: 71720 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 71867 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 71874 [EM]>>> [E:26663r S:0 M:56892554] (U) Msg RX from 0:C9F9AD68CCF6BB0A [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I: 71887 [EM]<<< [E:26663r S:0 M:5644595] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I: 71899 [IN](U) Sending msg 5644595 to IP address 'BLE'
D: 71904 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 140)
D: 72013 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 72021 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 72029 [EM]>>> [E:26663r S:0 M:56892555] (U) Msg RX from 0:C9F9AD68CCF6BB0A [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I: 73399 [EM]<<< [E:26663r S:0 M:5644596] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I: 73411 [IN](U) Sending msg 5644596 to IP address 'BLE'
D: 73416 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 132)
E: 73423 [DL]Long dispatch time: 1402 ms, for event type 16388
D: 73505 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 73513 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 73521 [EM]>>> [E:26663r S:0 M:56892556] (U) Msg RX from 0:C9F9AD68CCF6BB0A [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I: 73533 [EM]<<< [E:26663r S:0 M:5644597] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 73544 [IN](U) Sending msg 5644597 to IP address 'BLE'
D: 73549 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 35)
I: 73558 [SC]SecureSession[0x20001a08]: Moving from state 'kEstablishing' --> 'kActive'
D: 73565 [IN]SecureSession[0x20001a08]: Activated - Type:1 LSID:13240
D: 73572 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:13240 PSID:61883!
I: 73580 [SVR]Commissioning completed session establishment step
I: 73586 [DIS]Updating services using commissioning mode 0
I: 73591 [DIS]CHIP minimal mDNS started advertising.
D: 73597 [DL]Using wifi MAC for hostname
I: 73601 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0
D: 73610 [DIS]Responding with _matterc._udp.local
D: 73614 [DIS]Responding with BDC12CA345F1A345._matterc._udp.local
D: 73620 [DIS]Responding with F4CE360024C2.local
D: 73625 [DIS]Responding with F4CE360024C2.local
D: 73629 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 73635 [DIS]Responding with _S15._sub._matterc._udp.local
D: 73640 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 73646 [DIS]Responding with BDC12CA345F1A345._matterc._udp.local
I: 73652 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: BDC12CA345F1A345.
I: 73662 [DIS]mDNS service published: _matterc._udp
D: 73667 [DIS]Scheduling extended discovery timeout in 900s
D: 73672 [DL]CHIPoBLE advertising set to off
I: 73676 [SVR]Device completed Rendezvous process
E: 73680 [DL]Long dispatch time: 167 ms, for event type 16388
D: 73686 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 73693 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 73701 [EM]>>> [E:26664r S:13240 M:218274504] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 73712 [IM]Received Read request
I: 73717 [EM]<<< [E:26664r S:13240 M:81362889] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 73727 [IN](S) Sending msg 81362889 on secure session with LSID: 13240
D: 73734 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 73820 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 73828 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 73836 [EM]>>> [E:26665r S:13240 M:218274505] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 73847 [IM]Received Read request
I: 73852 [EM]<<< [E:26665r S:13240 M:81362890] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 73863 [IN](S) Sending msg 81362890 on secure session with LSID: 13240
D: 73869 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 73955 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 73964 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 73971 [EM]>>> [E:26666r S:13240 M:218274506] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 73982 [IM]Received Read request
I: 73987 [EM]<<< [E:26666r S:13240 M:81362891] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 73998 [IN](S) Sending msg 81362891 on secure session with LSID: 13240
D: 74004 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 87)
D: 74090 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 74098 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 74106 [EM]>>> [E:26667r S:13240 M:218274507] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 74117 [IM]Received Read request
I: 74122 [EM]<<< [E:26667r S:13240 M:81362892] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 74132 [IN](S) Sending msg 81362892 on secure session with LSID: 13240
D: 74139 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 74225 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 74233 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 74241 [EM]>>> [E:26668r S:13240 M:218274508] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 74252 [IM]Received Read request
I: 74257 [EM]<<< [E:26668r S:13240 M:81362893] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 74268 [IN](S) Sending msg 81362893 on secure session with LSID: 13240
D: 74274 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 74360 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 74451 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 74459 [EM]>>> [E:26669r S:13240 M:218274509] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 74469 [IM]Received Read request
I: 74474 [EM]<<< [E:26669r S:13240 M:81362894] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 74485 [IN](S) Sending msg 81362894 on secure session with LSID: 13240
D: 74491 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 74585 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 74593 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 74601 [EM]>>> [E:26670r S:13240 M:218274510] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 74612 [IM]Received Read request
I: 74617 [EM]<<< [E:26670r S:13240 M:81362895] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 74627 [IN](S) Sending msg 81362895 on secure session with LSID: 13240
D: 74634 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 74720 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 74728 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 74736 [EM]>>> [E:26671r S:13240 M:218274511] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 74747 [IM]Received Read request
I: 74752 [EM]<<< [E:26671r S:13240 M:81362896] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 74762 [IN](S) Sending msg 81362896 on secure session with LSID: 13240
D: 74769 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 70)
D: 74855 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 74863 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 74871 [EM]>>> [E:26672r S:13240 M:218274512] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I: 74883 [EM]<<< [E:26672r S:13240 M:81362897] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:01 (IM:StatusResponse)
I: 74894 [IN](S) Sending msg 81362897 on secure session with LSID: 13240
D: 74900 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43)
D: 74990 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 74998 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 75006 [EM]>>> [E:26672r S:13240 M:218274513] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 75019 [FS]GeneralCommissioning: Received ArmFailSafe (90s)
I: 75025 [EM]<<< [E:26672r S:13240 M:81362898] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 75037 [IN](S) Sending msg 81362898 on secure session with LSID: 13240
D: 75043 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 75125 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 75133 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 75141 [EM]>>> [E:26673r S:13240 M:218274514] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 75153 [ZCL]OpCreds: Certificate Chain request received for PAI
I: 75163 [EM]<<< [E:26673r S:13240 M:81362899] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 75174 [IN](S) Sending msg 81362899 on secure session with LSID: 13240
D: 75180 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 75260 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 75268 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 75351 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 75358 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 48)
D: 75440 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 75448 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 75456 [EM]>>> [E:26674r S:13240 M:218274515] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 75468 [ZCL]OpCreds: Certificate Chain request received for DAC
I: 75478 [EM]<<< [E:26674r S:13240 M:81362900] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 75489 [IN](S) Sending msg 81362900 on secure session with LSID: 13240
D: 75496 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 75575 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 75583 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 75666 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 75673 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 77)
D: 75756 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 77826 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 77834 [EM]>>> [E:26675r S:13240 M:218274516] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 77846 [ZCL]OpCreds: Received a CSRRequest command
I: 77992 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded
I: 78026 [ZCL]OpCreds: CSRRequest successful.
I: 78033 [EM]<<< [E:26675r S:13240 M:81362901] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 78044 [IN](S) Sending msg 81362901 on secure session with LSID: 13240
D: 78051 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
E: 78058 [DL]Long dispatch time: 232 ms, for event type 16388
D: 78141 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 78148 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 138)
D: 78231 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 80615 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 82102 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 82191 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 82200 [EM]>>> [E:26676r S:13240 M:218274517] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 82212 [ZCL]OpCreds: Received an AddTrustedRootCertificate command
I: 82272 [ZCL]OpCreds: AddTrustedRootCertificate successful.
I: 82278 [EM]<<< [E:26676r S:13240 M:81362902] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 82290 [IN](S) Sending msg 81362902 on secure session with LSID: 13240
D: 82296 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 68)
E: 82304 [DL]Long dispatch time: 113 ms, for event type 16388
D: 82370 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 82379 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 82462 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 82552 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 82562 [EM]>>> [E:26677r S:13240 M:218274518] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 82574 [ZCL]OpCreds: Received an AddNOC command
I: 82580 [FP]Validating NOC chain
I: 82691 [FP]NOC chain validation successful
I: 82695 [FP]Added new fabric at index: 0x1
I: 82699 [FP]Assigned compressed fabric ID: 0xEE309033DCA5085D, node ID: 0x4D689E084A806B90
I: 82707 [TS]Last Known Good Time: 2023-10-23T12:29:22
I: 82712 [TS]New proposed Last Known Good Time: 2024-07-17T01:55:33
I: 82718 [TS]Updating pending Last Known Good Time to 2024-07-17T01:55:33
I: 82732 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0xFFFFFFFD00000001
D: 82741 [DL]Using wifi MAC for hostname
I: 82745 [DIS]Advertise operational node EE309033DCA5085D-4D689E084A806B90
D: 82751 [DIS]Responding with _matter._tcp.local
D: 82756 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 82763 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 82770 [DIS]Responding with F4CE360024C2.local
D: 82775 [DIS]Responding with F4CE360024C2.local
D: 82779 [DIS]Responding with _IEE309033DCA5085D._sub._matter._tcp.local
I: 82786 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: EE309033DCA5085D-4D689E084A806B90.
I: 82796 [DIS]mDNS service published: _matter._tcp
I: 82801 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC
I: 82808 [EM]<<< [E:26677r S:13240 M:81362903] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 82819 [IN](S) Sending msg 81362903 on secure session with LSID: 13240
D: 82826 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
E: 82833 [DL]Long dispatch time: 282 ms, for event type 16388
D: 82910 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 82919 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 82926 [EM]>>> [E:26678r S:13240 M:218274519] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:02 (IM:ReadRequest)
D: 82937 [IM]Received Read request
I: 82942 [EM]<<< [E:26678r S:13240 M:81362904] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:05 (IM:ReportData)
I: 82953 [IN](S) Sending msg 81362904 on secure session with LSID: 13240
D: 82959 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 79)
D: 83045 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 83054 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 83061 [EM]>>> [E:26679r S:13240 M:218274520] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:02 (IM:ReadRequest)
D: 83072 [IM]Received Read request
I: 83077 [EM]<<< [E:26679r S:13240 M:81362905] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:05 (IM:ReportData)
I: 83088 [IN](S) Sending msg 81362905 on secure session with LSID: 13240
D: 83094 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 73)
D: 83180 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 83189 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 83196 [EM]>>> [E:26680r S:13240 M:218274521] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:02 (IM:ReadRequest)
D: 83207 [IM]Received Read request
I: 83212 [EM]<<< [E:26680r S:13240 M:81362906] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:05 (IM:ReportData)
I: 83223 [IN](S) Sending msg 81362906 on secure session with LSID: 13240
D: 83229 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 80)
D: 83315 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 83324 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 83331 [EM]>>> [E:26681r S:13240 M:218274522] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:02 (IM:ReadRequest)
D: 83342 [IM]Received Read request
I: 83347 [EM]<<< [E:26681r S:13240 M:81362907] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:05 (IM:ReportData)
I: 83358 [IN](S) Sending msg 81362907 on secure session with LSID: 13240
D: 83364 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 73)
D: 83450 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 83459 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 83466 [EM]>>> [E:26682r S:13240 M:218274523] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:02 (IM:ReadRequest)
D: 83477 [IM]Received Read request
I: 83482 [EM]<<< [E:26682r S:13240 M:81362908] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:05 (IM:ReportData)
I: 83493 [IN](S) Sending msg 81362908 on secure session with LSID: 13240
D: 83499 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 80)
D: 83585 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 83594 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 83601 [EM]>>> [E:26683r S:13240 M:218274524] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:02 (IM:ReadRequest)
D: 83612 [IM]Received Read request
I: 83617 [EM]<<< [E:26683r S:13240 M:81362909] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:05 (IM:ReportData)
I: 83628 [IN](S) Sending msg 81362909 on secure session with LSID: 13240
D: 83634 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 72)
D: 83720 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 83729 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 83736 [EM]>>> [E:26684r S:13240 M:218274525] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:02 (IM:ReadRequest)
D: 83747 [IM]Received Read request
I: 83752 [EM]<<< [E:26684r S:13240 M:81362910] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:05 (IM:ReportData)
I: 83763 [IN](S) Sending msg 81362910 on secure session with LSID: 13240
D: 83769 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 83855 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 83864 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 83871 [EM]>>> [E:26685r S:13240 M:218274526] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:0a (IM:TimedRequest)
I: 83883 [EM]<<< [E:26685r S:13240 M:81362911] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:01 (IM:StatusResponse)
I: 83894 [IN](S) Sending msg 81362911 on secure session with LSID: 13240
D: 83900 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43)
D: 83990 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 83999 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 84007 [EM]>>> [E:26685r S:13240 M:218274527] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:08 (IM:InvokeCommandRequest)
D: 84021 [DL]WiFi scanning started...
D: 86506 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 86601 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
I: 88753 [DL]Wi-Fi scan done (0)
I: 88760 [EM]<<< [E:26685r S:13240 M:81362912] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 88772 [IN](S) Sending msg 81362912 on secure session with LSID: 13240
D: 88778 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 88851 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 88858 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244)
D: 88941 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 88948 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 111)
D: 89031 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 89039 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 89121 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 89129 [EM]>>> [E:26686r S:13240 M:218274528] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:0a (IM:TimedRequest)
I: 89141 [EM]<<< [E:26686r S:13240 M:81362913] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:01 (IM:StatusResponse)
I: 89152 [IN](S) Sending msg 81362913 on secure session with LSID: 13240
D: 89158 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43)
D: 89256 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 89264 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 89272 [EM]>>> [E:26686r S:13240 M:218274529] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 89284 [FS]GeneralCommissioning: Received ArmFailSafe (270s)
I: 89290 [EM]<<< [E:26686r S:13240 M:81362914] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 89302 [IN](S) Sending msg 81362914 on secure session with LSID: 13240
D: 89308 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 89391 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 89399 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 89407 [EM]>>> [E:26687r S:13240 M:218274530] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:02 (IM:ReadRequest)
D: 89417 [IM]Received Read request
I: 89422 [EM]<<< [E:26687r S:13240 M:81362915] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:05 (IM:ReportData)
I: 89433 [IN](S) Sending msg 81362915 on secure session with LSID: 13240
D: 89439 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 89526 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 89534 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 89542 [EM]>>> [E:26688r S:13240 M:218274531] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:0a (IM:TimedRequest)
I: 89553 [EM]<<< [E:26688r S:13240 M:81362916] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:01 (IM:StatusResponse)
I: 89564 [IN](S) Sending msg 81362916 on secure session with LSID: 13240
D: 89571 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43)
D: 89661 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 89669 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 89677 [EM]>>> [E:26688r S:13240 M:218274532] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 89690 [EM]<<< [E:26688r S:13240 M:81362917] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 89701 [IN](S) Sending msg 81362917 on secure session with LSID: 13240
D: 89708 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 89796 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 89804 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 89812 [EM]>>> [E:26689r S:13240 M:218274533] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:02 (IM:ReadRequest)
D: 89822 [IM]Received Read request
I: 89827 [EM]<<< [E:26689r S:13240 M:81362918] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:05 (IM:ReportData)
I: 89838 [IN](S) Sending msg 81362918 on secure session with LSID: 13240
D: 89844 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71)
D: 89931 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 89939 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 89947 [EM]>>> [E:26690r S:13240 M:218274534] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:0a (IM:TimedRequest)
I: 89958 [EM]<<< [E:26690r S:13240 M:81362919] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:01 (IM:StatusResponse)
I: 89969 [IN](S) Sending msg 81362919 on secure session with LSID: 13240
D: 89976 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 43)
D: 90066 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 90074 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 90082 [EM]>>> [E:26690r S:13240 M:218274535] (S) Msg RX from 1:FFFFFFFB00000000 [085D] --- Type 0001:08 (IM:InvokeCommandRequest)
D: 90094 [DL]Connecting to WiFi network: NETGEAR_5G
D: 90100 [DL]WiFi scanning started...
D: 92582 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 92676 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
I: 94582 [DL]Wi-Fi scan done (0)
I: 96139 [DL]Connection to NETGEAR_5G requested [RSSI=-57]
E: 96144 [DL]Long dispatch time: 1562 ms, for event type 3
D: 96151 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
D: 98658 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3)
D: 98751 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
I: 100926 [DL]Connected to WiFi network
I: 100932 [EM]<<< [E:26690r S:13240 M:81362920] (S) Msg TX to 1:FFFFFFFB00000000 [085D] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 100945 [IN](S) Sending msg 81362920 on secure session with LSID: 13240
D: 100951 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 69)
D: 100959 [DL]Using wifi MAC for hostname
I: 100963 [DIS]Advertise operational node EE309033DCA5085D-4D689E084A806B90
D: 100970 [DIS]Responding with _matter._tcp.local
D: 100974 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 100982 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 100990 [DIS]Responding with F4CE360024C2.local
D: 100996 [DIS]Responding with F4CE360024C2.local
D: 101000 [DIS]Responding with _IEE309033DCA5085D._sub._matter._tcp.local
I: 101007 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: EE309033DCA5085D-4D689E084A806B90.
I: 101017 [DIS]mDNS service published: _matter._tcp
I: 101022 [SVR]Operational advertising enabled
E: 101026 [SVR]Server initialization complete
I: 101030 [DIS]Updating services using commissioning mode 0
E: 101036 [IN]SO_REUSEPORT failed: 109
I: 101042 [DIS]CHIP minimal mDNS started advertising.
D: 101082 [DL]Using wifi MAC for hostname
I: 101087 [DIS]Advertise operational node EE309033DCA5085D-4D689E084A806B90
D: 101098 [DIS]Responding with _matter._tcp.local
D: 101103 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 101110 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 101118 [DIS]Responding with F4CE360024C2.local
D: 101122 [DIS]Responding with F4CE360024C2.local
D: 101126 [DIS]Responding with _IEE309033DCA5085D._sub._matter._tcp.local
I: 101133 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: EE309033DCA5085D-4D689E084A806B90.
I: 101150 [DIS]mDNS service published: _matter._tcp
D: 101155 [DL]Using wifi MAC for hostname
I: 101159 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0
D: 101168 [DIS]Responding with _matterc._udp.local
D: 101173 [DIS]Responding with BDC12CA345F1A345._matterc._udp.local
D: 101179 [DIS]Responding with F4CE360024C2.local
D: 101183 [DIS]Responding with F4CE360024C2.local
D: 101188 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 101201 [DIS]Responding with _S15._sub._matterc._udp.local
D: 101206 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 101212 [DIS]Responding with BDC12CA345F1A345._matterc._udp.local
I: 101218 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: BDC12CA345F1A345.
I: 101245 [DIS]mDNS service published: _matterc._udp
E: 101250 [DL]Long dispatch time: 224 ms, for event type 32784
I: 101256 [DIS]Updating services using commissioning mode 0
E: 101261 [IN]SO_REUSEPORT failed: 109
I: 101265 [DIS]CHIP minimal mDNS started advertising.
D: 101305 [DL]Using wifi MAC for hostname
I: 101309 [DIS]Advertise operational node EE309033DCA5085D-4D689E084A806B90
D: 101317 [DIS]Responding with _matter._tcp.local
D: 101323 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 101331 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 101338 [DIS]Responding with F4CE360024C2.local
D: 101342 [DIS]Responding with F4CE360024C2.local
D: 101347 [DIS]Responding with _IEE309033DCA5085D._sub._matter._tcp.local
I: 101353 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: EE309033DCA5085D-4D689E084A806B90.
I: 101368 [DIS]mDNS service published: _matter._tcp
D: 101374 [DL]Using wifi MAC for hostname
I: 101378 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0
D: 101387 [DIS]Responding with _matterc._udp.local
D: 101391 [DIS]Responding with BDC12CA345F1A345._matterc._udp.local
D: 101399 [DIS]Responding with F4CE360024C2.local
D: 101403 [DIS]Responding with F4CE360024C2.local
D: 101408 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 101413 [DIS]Responding with _S15._sub._matterc._udp.local
D: 101419 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 101424 [DIS]Responding with BDC12CA345F1A345._matterc._udp.local
I: 101430 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: BDC12CA345F1A345.
I: 101469 [DIS]mDNS service published: _matterc._udp
E: 101475 [DL]Long dispatch time: 219 ms, for event type 32784
D: 101480 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00)
D: 101488 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00)
I: 101496 [EM]>>> [E:40905r S:0 M:206622179] (U) Msg RX from 0:6EE3C1D0DF5A187F [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I: 101512 [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x200033c8
I: 101519 [IN]CASE Server disabling CASE session setups
I: 101524 [EM]<<< [E:40905r S:0 M:5644598 (Ack:206622179)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 101537 [IN](U) Sending msg 5644598 to IP address 'UDP:[fe80::2eba:baff:fe96:c057]:42943'
I: 101546 [SC]Received Sigma1 msg
I: 101553 [SC]CASE matched destination ID: fabricIndex 1, NodeID 0x4D689E084A806B90
I: 101733 [EM]<<< [E:40905r S:0 M:5644599 (Ack:206622179)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I: 101746 [IN](U) Sending msg 5644599 to IP address 'UDP:[fe80::2eba:baff:fe96:c057]:42943'
I: 101755 [SC]Sent Sigma2 msg
I: 101878 [EM]>>> [E:40905r S:0 M:206622180 (Ack:5644599)] (U) Msg RX from 0:6EE3C1D0DF5A187F [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I: 101892 [EM]<<< [E:40905r S:0 M:5644600 (Ack:206622180)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 101904 [IN](U) Sending msg 5644600 to IP address 'UDP:[fe80::2eba:baff:fe96:c057]:42943'
I: 101915 [SC]Received Sigma3 msg
E: 101923 [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
E: 102108 [DL]Long dispatch time: 174 ms, for event type 2
I: 102125 [EM]<<< [E:40905r S:0 M:5644601 (Ack:206622180)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 102137 [IN](U) Sending msg 5644601 to IP address 'UDP:[fe80::2eba:baff:fe96:c057]:42943'
I: 102148 [SC]SecureSession[0x20001ac0]: Moving from state 'kEstablishing' --> 'kActive'
D: 102157 [IN]SecureSession[0x20001ac0]: Activated - Type:2 LSID:13241
D: 102164 [IN]New secure session activated for device <1BC15B739A5D27FF, 1>, LSID:13241 PSID:51837!
I: 102173 [IN]CASE Session established to peer: <1BC15B739A5D27FF, 1>
I: 102182 [IN]CASE Server enabling CASE session setups
D: 102188 [IN]SecureSession[0x20001b78]: Allocated Type:2 LSID:13242
I: 102196 [EM]>>> [E:40905r S:0 M:206622181 (Ack:5644601)] (U) Msg RX from 0:6EE3C1D0DF5A187F [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 102210 [EM]>>> [E:40906r S:13241 M:257134193] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 102222 [IM]Received Read request
I: 102229 [EM]<<< [E:40906r S:13241 M:38031904 (Ack:257134193)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 102241 [IN](S) Sending msg 38031904 on secure session with LSID: 13241
I: 102269 [EM]>>> [E:40906r S:13241 M:257134194 (Ack:38031904)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 102283 [EM]>>> [E:40907r S:13241 M:257134195] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:03 (IM:SubscribeRequest)
D: 102295 [IM]Received Subscribe request
I: 102299 [DMG]Final negotiated min/max parameters: Min = 1s, Max = 60s
I: 102307 [EM]<<< [E:40907r S:13241 M:38031905 (Ack:257134195)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 102320 [IN](S) Sending msg 38031905 on secure session with LSID: 13241
I: 102344 [EM]>>> [E:40907r S:13241 M:257134196 (Ack:38031905)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:01 (IM:StatusResponse)
I: 102357 [IM]Received status response, status is 0x00
I: 102361 [DMG]Refresh Subscribe Sync Timer with min 1 seconds and max 60 seconds
I: 102369 [EM]<<< [E:40907r S:13241 M:38031906 (Ack:257134196)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:04 (IM:SubscribeResponse)
I: 102382 [IN](S) Sending msg 38031906 on secure session with LSID: 13241
I: 102412 [EM]>>> [E:40907r S:13241 M:257134197 (Ack:38031906)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 102430 [EM]>>> [E:40908r S:13241 M:257134198] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 102442 [FS]GeneralCommissioning: Received CommissioningComplete
I: 102450 [FP]Metadata for Fabric 0x1 persisted to storage.
I: 102582 [TS]Committing Last Known Good Time to storage: 2024-07-17T01:55:33
I: 102591 [ZCL]OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0xEE309033DCA5085D, FabricId F6CAB18533F22355, NodeId 4D689E084A806B90, VendorId 0x110A
I: 102607 [FS]GeneralCommissioning: Successfully commited pending fabric data
I: 102613 [FS]Fail-safe cleanly disarmed
I: 102618 [EM]<<< [E:40908r S:13241 M:38031907 (Ack:257134198)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 102632 [IN](S) Sending msg 38031907 on secure session with LSID: 13241
I: 102640 [SVR]Commissioning completed successfully
I: 102645 [DIS]Updating services using commissioning mode 0
E: 102651 [IN]SO_REUSEPORT failed: 109
I: 102654 [DIS]CHIP minimal mDNS started advertising.
D: 102741 [DL]Using wifi MAC for hostname
I: 102746 [DIS]Advertise operational node EE309033DCA5085D-4D689E084A806B90
D: 102755 [DIS]Responding with _matter._tcp.local
D: 102761 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 102769 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 102776 [DIS]Responding with F4CE360024C2.local
D: 102780 [DIS]Responding with F4CE360024C2.local
D: 102785 [DIS]Responding with _IEE309033DCA5085D._sub._matter._tcp.local
I: 102791 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: EE309033DCA5085D-4D689E084A806B90.
I: 102811 [DIS]mDNS service published: _matter._tcp
D: 102817 [DL]Using wifi MAC for hostname
I: 102821 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0
D: 102832 [DIS]Responding with _matterc._udp.local
D: 102836 [DIS]Responding with BDC12CA345F1A345._matterc._udp.local
D: 102842 [DIS]Responding with F4CE360024C2.local
D: 102847 [DIS]Responding with F4CE360024C2.local
D: 102851 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 102858 [DIS]Responding with _S15._sub._matterc._udp.local
D: 102864 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 102870 [DIS]Responding with BDC12CA345F1A345._matterc._udp.local
I: 102876 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: BDC12CA345F1A345.
I: 102921 [DIS]mDNS service published: _matterc._udp
D: 102927 [IN]Expiring all PASE sessions
D: 102937 [IN]SecureSession[0x20001a08]: MarkForEviction Type:1 LSID:13240
I: 102944 [SC]SecureSession[0x20001a08]: Moving from state 'kActive' --> 'kPendingEviction'
D: 102952 [IN]SecureSession[0x20001a08]: Released - Type:1 LSID:13240
D: 102958 [IN]Clearing BLE pending packets.
I: 102962 [BLE]Releasing end point's BLE connection back to application.
I: 102968 [DL]Closing BLE GATT connection (ConnId 00)
E: 102975 [DL]Long dispatch time: 335 ms, for event type 32781
I: 102982 [EM]>>> [E:40908r S:13241 M:257134199 (Ack:38031907)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 102997 [EM]>>> [E:40909r S:13241 M:257134200] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 103007 [IM]Received Read request
I: 103014 [EM]<<< [E:40909r S:13241 M:38031908 (Ack:257134200)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 103026 [IN](S) Sending msg 38031908 on secure session with LSID: 13241
I: 103040 [DL]BLE GAP connection terminated (reason 0x16)
I: 103047 [DL]Current number of connections: 0/1
I: 103052 [EM]>>> [E:40909r S:13241 M:257134201 (Ack:38031908)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 103244 [EM]>>> [E:40910r S:13241 M:257134202] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 103255 [IM]Received Read request
I: 103268 [EM]<<< [E:40910r S:13241 M:38031909 (Ack:257134202)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 103280 [IN](S) Sending msg 38031909 on secure session with LSID: 13241
I: 103626 [IN](S) Sending msg 38031909 on secure session with LSID: 13241
I: 103693 [EM]>>> [E:40910r S:13241 M:257134203 (Ack:38031909)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 103707 [EM]>>> [E:40911r S:13241 M:257134204] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 103718 [IM]Received Read request
I: 103724 [EM]<<< [E:40911r S:13241 M:38031910 (Ack:257134204)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 103736 [IN](S) Sending msg 38031910 on secure session with LSID: 13241
I: 103762 [EM]>>> [E:40911r S:13241 M:257134205 (Ack:38031910)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 103778 [EM]>>> [E:40912r S:13241 M:257134206] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 103789 [IM]Received Read request
I: 103795 [EM]<<< [E:40912r S:13241 M:38031911 (Ack:257134206)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 103807 [IN](S) Sending msg 38031911 on secure session with LSID: 13241
I: 103831 [EM]>>> [E:40912r S:13241 M:257134207 (Ack:38031911)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 103847 [EM]>>> [E:40913r S:13241 M:257134208] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 103861 [IM]Received Read request
I: 103866 [EM]<<< [E:40913r S:13241 M:38031912 (Ack:257134208)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 103878 [IN](S) Sending msg 38031912 on secure session with LSID: 13241
I: 103903 [EM]>>> [E:40913r S:13241 M:257134209 (Ack:38031912)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 103920 [EM]>>> [E:40914r S:13241 M:257134210] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 103931 [IM]Received Read request
I: 103940 [EM]<<< [E:40914r S:13241 M:38031913 (Ack:257134210)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 103951 [IN](S) Sending msg 38031913 on secure session with LSID: 13241
I: 104000 [EM]>>> [E:40914r S:13241 M:257134211 (Ack:38031913)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 104015 [EM]>>> [E:40915r S:13241 M:257134212] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 104026 [IM]Received Read request
I: 104035 [EM]<<< [E:40915r S:13241 M:38031914 (Ack:257134212)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 104046 [IN](S) Sending msg 38031914 on secure session with LSID: 13241
I: 104090 [EM]>>> [E:40915r S:13241 M:257134213 (Ack:38031914)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 104104 [EM]>>> [E:40916r S:13241 M:257134214] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 104115 [IM]Received Read request
E: 104122 [DMG]Error retrieving data from clusterId: 0x0000_0008, err = b
I: 104134 [EM]<<< [E:40916r S:13241 M:38031915 (Ack:257134214)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 104146 [IN](S) Sending msg 38031915 on secure session with LSID: 13241
I: 104203 [EM]>>> [E:40916r S:13241 M:257134215 (Ack:38031915)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:01 (IM:StatusResponse)
I: 104215 [IM]Received status response, status is 0x00
I: 104224 [EM]<<< [E:40916r S:13241 M:38031916 (Ack:257134215)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 104236 [IN](S) Sending msg 38031916 on secure session with LSID: 13241
I: 104276 [EM]>>> [E:40916r S:13241 M:257134216 (Ack:38031916)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 104290 [EM]>>> [E:40917r S:13241 M:257134217] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 104301 [IM]Received Read request
I: 104310 [EM]<<< [E:40917r S:13241 M:38031917 (Ack:257134217)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 104322 [IN](S) Sending msg 38031917 on secure session with LSID: 13241
I: 104364 [EM]>>> [E:40917r S:13241 M:257134218 (Ack:38031917)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 104379 [EM]>>> [E:40918r S:13241 M:257134219] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 104390 [IM]Received Read request
I: 104399 [EM]<<< [E:40918r S:13241 M:38031918 (Ack:257134219)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 104411 [IN](S) Sending msg 38031918 on secure session with LSID: 13241
I: 104458 [EM]>>> [E:40918r S:13241 M:257134220 (Ack:38031918)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 104473 [EM]>>> [E:40919r S:13241 M:257134221] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 104484 [IM]Received Read request
I: 104490 [EM]<<< [E:40919r S:13241 M:38031919 (Ack:257134221)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 104502 [IN](S) Sending msg 38031919 on secure session with LSID: 13241
I: 104532 [EM]>>> [E:40919r S:13241 M:257134222 (Ack:38031919)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 104553 [EM]>>> [E:40920r S:13241 M:257134223] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 104565 [ZCL]OpCreds: Received an UpdateFabricLabel command
I: 104575 [FP]Metadata for Fabric 0x1 persisted to storage.
I: 104581 [EM]<<< [E:40920r S:13241 M:38031920 (Ack:257134223)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 104594 [IN](S) Sending msg 38031920 on secure session with LSID: 13241
I: 104619 [EM]>>> [E:40920r S:13241 M:257134224 (Ack:38031920)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 106938 [EM]>>> [E:40921r S:13241 M:257134225] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:06 (IM:WriteRequest)
D: 106949 [IM]Received Write request
I: 106955 [EM]<<< [E:40921r S:13241 M:38031921 (Ack:257134225)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:07 (IM:WriteResponse)
I: 106967 [IN](S) Sending msg 38031921 on secure session with LSID: 13241
I: 106984 [EM]>>> [E:40921r S:13241 M:257134226 (Ack:38031921)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 107306 [EM]>>> [E:40922r S:13241 M:257134227] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:03 (IM:SubscribeRequest)
D: 107317 [IM]Received Subscribe request
I: 107321 [IM]Deleting previous subscription from NodeId: 1BC15B739A5D27FF, FabricIndex: 1
I: 107331 [DMG]Final negotiated min/max parameters: Min = 1s, Max = 30s
I: 107352 [EM]<<< [E:40922r S:13241 M:38031922 (Ack:257134227)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 107364 [IN](S) Sending msg 38031922 on secure session with LSID: 13241
I: 107374 [EM]>>> [E:40923r S:13241 M:257134228] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 107386 [IM]Received Read request
I: 107393 [EM]<<< [E:40923r S:13241 M:38031923 (Ack:257134228)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 107405 [IN](S) Sending msg 38031923 on secure session with LSID: 13241
I: 107414 [EM]>>> [E:40922r S:13241 M:257134229 (Ack:38031922)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:01 (IM:StatusResponse)
I: 107429 [IM]Received status response, status is 0x00
I: 107434 [DMG]Refresh Subscribe Sync Timer with min 1 seconds and max 30 seconds
I: 107443 [EM]<<< [E:40922r S:13241 M:38031924 (Ack:257134229)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:04 (IM:SubscribeResponse)
I: 107455 [IN](S) Sending msg 38031924 on secure session with LSID: 13241
I: 107465 [EM]>>> [E:40923r S:13241 M:257134230 (Ack:38031923)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 107481 [EM]>>> [E:40922r S:13241 M:257134231 (Ack:38031924)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 107572 [EM]>>> [E:40924r S:13241 M:257134232] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:02 (IM:ReadRequest)
D: 107583 [IM]Received Read request
I: 107588 [EM]<<< [E:40924r S:13241 M:38031925 (Ack:257134232)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 107600 [IN](S) Sending msg 38031925 on secure session with LSID: 13241
I: 107616 [EM]>>> [E:40924r S:13241 M:257134233 (Ack:38031925)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 108613 [EM]>>> [E:40925r S:13241 M:257134234] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:0a (IM:TimedRequest)
I: 108625 [EM]<<< [E:40925r S:13241 M:38031926 (Ack:257134234)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:01 (IM:StatusResponse)
I: 108637 [IN](S) Sending msg 38031926 on secure session with LSID: 13241
I: 108655 [EM]>>> [E:40925r S:13241 M:257134235 (Ack:38031926)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 108670 [ZCL]Received command to open commissioning window
D: 108676 [IN]SecureSession[0x20001a08]: Allocated Type:1 LSID:13243
I: 108683 [DIS]Updating services using commissioning mode 2
E: 108688 [IN]SO_REUSEPORT failed: 109
I: 108692 [DIS]CHIP minimal mDNS started advertising.
D: 108773 [DL]Using wifi MAC for hostname
I: 108777 [DIS]Advertise operational node EE309033DCA5085D-4D689E084A806B90
D: 108784 [DIS]Responding with _matter._tcp.local
D: 108789 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 108796 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 108804 [DIS]Responding with F4CE360024C2.local
D: 108808 [DIS]Responding with F4CE360024C2.local
D: 108813 [DIS]Responding with _IEE309033DCA5085D._sub._matter._tcp.local
I: 108819 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: EE309033DCA5085D-4D689E084A806B90.
I: 108840 [DIS]mDNS service published: _matter._tcp
D: 108845 [DL]Using wifi MAC for hostname
I: 108850 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=1320/05 cm=2
D: 108859 [DIS]Responding with _matterc._udp.local
D: 108863 [DIS]Responding with E387030DFED0E531._matterc._udp.local
D: 108874 [DIS]Responding with F4CE360024C2.local
D: 108878 [DIS]Responding with F4CE360024C2.local
D: 108883 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 108888 [DIS]Responding with _S5._sub._matterc._udp.local
D: 108894 [DIS]Responding with _L1320._sub._matterc._udp.local
D: 108899 [DIS]Responding with _CM._sub._matterc._udp.local
D: 108905 [DIS]Responding with E387030DFED0E531._matterc._udp.local
I: 108911 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: E387030DFED0E531.
I: 108964 [DIS]mDNS service published: _matterc._udp
I: 108969 [ZCL]Commissioning window is now open
I: 108976 [EM]<<< [E:40925r S:13241 M:38031927 (Ack:257134235)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 108989 [IN](S) Sending msg 38031927 on secure session with LSID: 13241
I: 109004 [EM]>>> [E:40925r S:13241 M:257134236 (Ack:38031927)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 111823 [EM]>>> [E:65317r S:0 M:144893780] (U) Msg RX from 0:AD7ED940D39A04B6 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I: 111835 [EM]<<< [E:65317r S:0 M:5644602 (Ack:144893780)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 111850 [IN](U) Sending msg 5644602 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 111860 [EM]<<< [E:65317r S:0 M:5644603 (Ack:144893780)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I: 111874 [IN](U) Sending msg 5644603 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 111900 [EM]>>> [E:65317r S:0 M:144893781 (Ack:5644603)] (U) Msg RX from 0:AD7ED940D39A04B6 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I: 111913 [EM]<<< [E:65317r S:0 M:5644604 (Ack:144893781)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 111926 [IN](U) Sending msg 5644604 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 113353 [EM]<<< [E:65317r S:0 M:5644605 (Ack:144893781)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I: 113366 [IN](U) Sending msg 5644605 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 113389 [EM]>>> [E:65317r S:0 M:144893782 (Ack:5644605)] (U) Msg RX from 0:AD7ED940D39A04B6 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I: 113402 [EM]<<< [E:65317r S:0 M:5644606 (Ack:144893782)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 113415 [IN](U) Sending msg 5644606 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 113425 [EM]<<< [E:65317r S:0 M:5644607 (Ack:144893782)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 113438 [IN](U) Sending msg 5644607 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 113449 [SC]SecureSession[0x20001a08]: Moving from state 'kEstablishing' --> 'kActive'
D: 113459 [IN]SecureSession[0x20001a08]: Activated - Type:1 LSID:13243
D: 113465 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:13243 PSID:27346!
I: 113474 [SVR]Commissioning completed session establishment step
I: 113486 [DIS]Updating services using commissioning mode 0
E: 113492 [IN]SO_REUSEPORT failed: 109
I: 113496 [DIS]CHIP minimal mDNS started advertising.
D: 113593 [DL]Using wifi MAC for hostname
I: 113597 [DIS]Advertise operational node EE309033DCA5085D-4D689E084A806B90
D: 113604 [DIS]Responding with _matter._tcp.local
D: 113608 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 113616 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 113623 [DIS]Responding with F4CE360024C2.local
D: 113628 [DIS]Responding with F4CE360024C2.local
D: 113632 [DIS]Responding with _IEE309033DCA5085D._sub._matter._tcp.local
I: 113639 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: EE309033DCA5085D-4D689E084A806B90.
I: 113659 [DIS]mDNS service published: _matter._tcp
D: 113665 [DL]Using wifi MAC for hostname
I: 113669 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0
D: 113678 [DIS]Responding with _matterc._udp.local
D: 113684 [DIS]Responding with E387030DFED0E531._matterc._udp.local
D: 113690 [DIS]Responding with F4CE360024C2.local
D: 113695 [DIS]Responding with F4CE360024C2.local
D: 113699 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 113705 [DIS]Responding with _S15._sub._matterc._udp.local
D: 113710 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 113716 [DIS]Responding with E387030DFED0E531._matterc._udp.local
I: 113722 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: E387030DFED0E531.
I: 113768 [DIS]mDNS service published: _matterc._udp
D: 113773 [DIS]Scheduling extended discovery timeout in 900s
I: 113778 [SVR]Device completed Rendezvous process
I: 113783 [EM]>>> [E:65317r S:0 M:144893783 (Ack:5644607)] (U) Msg RX from 0:AD7ED940D39A04B6 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 113800 [EM]>>> [E:65318r S:13243 M:138086606] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 113811 [IM]Received Read request
I: 113816 [EM]<<< [E:65318r S:13243 M:40495503 (Ack:138086606)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 113828 [IN](S) Sending msg 40495503 on secure session with LSID: 13243
I: 113858 [EM]>>> [E:65318r S:13243 M:138086607 (Ack:40495503)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 113874 [EM]>>> [E:65319r S:13243 M:138086608] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 113885 [IM]Received Read request
I: 113891 [EM]<<< [E:65319r S:13243 M:40495504 (Ack:138086608)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 113903 [IN](S) Sending msg 40495504 on secure session with LSID: 13243
I: 113925 [EM]>>> [E:65319r S:13243 M:138086609 (Ack:40495504)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 113942 [EM]>>> [E:65320r S:13243 M:138086610] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 113953 [IM]Received Read request
I: 113958 [EM]<<< [E:65320r S:13243 M:40495505 (Ack:138086610)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 113970 [IN](S) Sending msg 40495505 on secure session with LSID: 13243
I: 113989 [EM]>>> [E:65320r S:13243 M:138086611 (Ack:40495505)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 114006 [EM]>>> [E:65321r S:13243 M:138086612] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 114017 [IM]Received Read request
I: 114021 [EM]<<< [E:65321r S:13243 M:40495506 (Ack:138086612)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 114033 [IN](S) Sending msg 40495506 on secure session with LSID: 13243
I: 114052 [EM]>>> [E:65321r S:13243 M:138086613 (Ack:40495506)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 114068 [EM]>>> [E:65322r S:13243 M:138086614] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 114079 [IM]Received Read request
I: 114084 [EM]<<< [E:65322r S:13243 M:40495507 (Ack:138086614)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 114103 [IN](S) Sending msg 40495507 on secure session with LSID: 13243
I: 114120 [EM]>>> [E:65322r S:13243 M:138086615 (Ack:40495507)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 114137 [EM]>>> [E:65323r S:13243 M:138086616] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 114148 [IM]Received Read request
I: 114152 [EM]<<< [E:65323r S:13243 M:40495508 (Ack:138086616)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 114164 [IN](S) Sending msg 40495508 on secure session with LSID: 13243
I: 114186 [EM]>>> [E:65323r S:13243 M:138086617 (Ack:40495508)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 114202 [EM]>>> [E:65324r S:13243 M:138086618] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 114213 [IM]Received Read request
I: 114218 [EM]<<< [E:65324r S:13243 M:40495509 (Ack:138086618)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 114230 [IN](S) Sending msg 40495509 on secure session with LSID: 13243
I: 114250 [EM]>>> [E:65324r S:13243 M:138086619 (Ack:40495509)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 115038 [EM]>>> [E:65325r S:13243 M:138086620] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 115050 [ZCL]OpCreds: Certificate Chain request received for DAC
I: 115064 [EM]<<< [E:65325r S:13243 M:40495510 (Ack:138086620)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 115077 [IN](S) Sending msg 40495510 on secure session with LSID: 13243
I: 115096 [EM]>>> [E:65325r S:13243 M:138086621 (Ack:40495510)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 115114 [EM]>>> [E:65326r S:13243 M:138086622] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 115126 [IM]Received Read request
I: 115131 [EM]<<< [E:65326r S:13243 M:40495511 (Ack:138086622)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 115143 [IN](S) Sending msg 40495511 on secure session with LSID: 13243
I: 115167 [EM]>>> [E:65326r S:13243 M:138086623 (Ack:40495511)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 115181 [EM]>>> [E:65327r S:13243 M:138086624] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 115192 [IM]Received Read request
I: 115197 [EM]<<< [E:65327r S:13243 M:40495512 (Ack:138086624)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 115209 [IN](S) Sending msg 40495512 on secure session with LSID: 13243
I: 115232 [EM]>>> [E:65327r S:13243 M:138086625 (Ack:40495512)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 115703 [EM]>>> [E:65328r S:13243 M:138086626] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I: 115715 [EM]<<< [E:65328r S:13243 M:40495513 (Ack:138086626)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:01 (IM:StatusResponse)
I: 115727 [IN](S) Sending msg 40495513 on secure session with LSID: 13243
I: 115746 [EM]>>> [E:65328r S:13243 M:138086627 (Ack:40495513)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 115759 [FS]GeneralCommissioning: Received ArmFailSafe (120s)
I: 115766 [EM]<<< [E:65328r S:13243 M:40495514 (Ack:138086627)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 115779 [IN](S) Sending msg 40495514 on secure session with LSID: 13243
I: 116150 [IN](S) Sending msg 40495514 on secure session with LSID: 13243
I: 116192 [EM]>>> [E:65328r S:13243 M:138086628 (Ack:40495514)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 116209 [EM]>>> [E:65329r S:13243 M:138086629] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
D: 116220 [IM]Received Read request
I: 116225 [EM]<<< [E:65329r S:13243 M:40495515 (Ack:138086629)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I: 116236 [IN](S) Sending msg 40495515 on secure session with LSID: 13243
I: 116265 [EM]>>> [E:65329r S:13243 M:138086630 (Ack:40495515)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 116279 [EM]>>> [E:65330r S:13243 M:138086631] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I: 116291 [EM]<<< [E:65330r S:13243 M:40495516 (Ack:138086631)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:01 (IM:StatusResponse)
I: 116303 [IN](S) Sending msg 40495516 on secure session with LSID: 13243
I: 116321 [EM]>>> [E:65330r S:13243 M:138086632 (Ack:40495516)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 116338 [EM]<<< [E:65330r S:13243 M:40495517 (Ack:138086632)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 116352 [IN](S) Sending msg 40495517 on secure session with LSID: 13243
I: 116370 [EM]>>> [E:65330r S:13243 M:138086633 (Ack:40495517)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 116387 [EM]>>> [E:65331r S:13243 M:138086634] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 116399 [ZCL]OpCreds: Certificate Chain request received for PAI
I: 116408 [EM]<<< [E:65331r S:13243 M:40495518 (Ack:138086634)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 116421 [IN](S) Sending msg 40495518 on secure session with LSID: 13243
I: 116445 [EM]>>> [E:65331r S:13243 M:138086635 (Ack:40495518)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 116460 [EM]>>> [E:65332r S:13243 M:138086636] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 116473 [ZCL]OpCreds: Received an AttestationRequest command
I: 116479 [EM]<<< [E:65332r S:13243 M:40495519 (Ack:138086636)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 116492 [IN](S) Sending msg 40495519 on secure session with LSID: 13243
I: 116529 [ZCL]OpCreds: AttestationRequest successful.
I: 116538 [EM]<<< [E:65332r S:13243 M:40495520 (Ack:138086636)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 116556 [IN](S) Sending msg 40495520 on secure session with LSID: 13243
I: 116579 [EM]>>> [E:65332r S:13243 M:138086637 (Ack:40495520)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 116596 [EM]>>> [E:65333r S:13243 M:138086638] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 116608 [ZCL]OpCreds: Received a CSRRequest command
I: 116614 [EM]<<< [E:65333r S:13243 M:40495521 (Ack:138086638)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 116627 [IN](S) Sending msg 40495521 on secure session with LSID: 13243
I: 116779 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded
I: 116813 [ZCL]OpCreds: CSRRequest successful.
I: 116819 [EM]<<< [E:65333r S:13243 M:40495522 (Ack:138086638)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 116832 [IN](S) Sending msg 40495522 on secure session with LSID: 13243
I: 116854 [EM]>>> [E:65333r S:13243 M:138086639 (Ack:40495522)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 117341 [EM]>>> [E:65334r S:13243 M:138086640] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 117353 [ZCL]OpCreds: Received an AddTrustedRootCertificate command
I: 117360 [EM]<<< [E:65334r S:13243 M:40495523 (Ack:138086640)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 117374 [IN](S) Sending msg 40495523 on secure session with LSID: 13243
I: 117436 [ZCL]OpCreds: AddTrustedRootCertificate successful.
I: 117443 [EM]<<< [E:65334r S:13243 M:40495524 (Ack:138086640)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 117455 [IN](S) Sending msg 40495524 on secure session with LSID: 13243
I: 117483 [EM]>>> [E:65334r S:13243 M:138086641 (Ack:40495524)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 117499 [EM]>>> [E:65335r S:13243 M:138086642] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 117512 [ZCL]OpCreds: Received an AddNOC command
I: 117517 [EM]<<< [E:65335r S:13243 M:40495525 (Ack:138086642)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 117530 [IN](S) Sending msg 40495525 on secure session with LSID: 13243
I: 117541 [FP]Validating NOC chain
I: 117600 [FP]NOC chain validation successful
I: 117604 [FP]Added new fabric at index: 0x2
I: 117608 [FP]Assigned compressed fabric ID: 0x7E6F1438C5793472, node ID: 0x168D26C74BA38C7E
I: 117616 [TS]Last Known Good Time: 2024-07-17T01:55:33
I: 117621 [TS]New proposed Last Known Good Time: 2024-07-10T00:00:00
I: 117627 [TS]Retaining current Last Known Good Time
I: 117641 [ZCL]OpCreds: ACL entry created for Fabric index 0x2 CASE Admin Subject 0xFFFFFFFD00010001
D: 117650 [DL]Using wifi MAC for hostname
I: 117654 [DIS]Advertise operational node EE309033DCA5085D-4D689E084A806B90
D: 117661 [DIS]Responding with _matter._tcp.local
D: 117665 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 117673 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 117681 [DIS]Responding with F4CE360024C2.local
D: 117686 [DIS]Responding with F4CE360024C2.local
D: 117690 [DIS]Responding with _IEE309033DCA5085D._sub._matter._tcp.local
I: 117697 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: EE309033DCA5085D-4D689E084A806B90.
I: 117743 [DIS]mDNS service published: _matter._tcp
D: 117748 [DL]Using wifi MAC for hostname
I: 117752 [DIS]Advertise operational node 7E6F1438C5793472-168D26C74BA38C7E
D: 117759 [DIS]Responding with _matter._tcp.local
D: 117764 [DIS]Responding with 7E6F1438C5793472-168D26C74BA38C7E._matter._tcp.local
D: 117771 [DIS]Responding with 7E6F1438C5793472-168D26C74BA38C7E._matter._tcp.local
D: 117781 [DIS]Responding with F4CE360024C2.local
D: 117786 [DIS]Responding with F4CE360024C2.local
D: 117790 [DIS]Responding with _I7E6F1438C5793472._sub._matter._tcp.local
I: 117797 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 7E6F1438C5793472-168D26C74BA38C7E.
I: 117861 [DIS]mDNS service published: _matter._tcp
I: 117867 [ZCL]OpCreds: successfully created fabric index 0x2 via AddNOC
I: 117874 [EM]<<< [E:65335r S:13243 M:40495526 (Ack:138086642)] (S) Msg TX to 2:FFFFFFFB00000000 [3472] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 117888 [IN](S) Sending msg 40495526 on secure session with LSID: 13243
I: 117904 [EM]>>> [E:65335r S:13243 M:138086643 (Ack:40495526)] (S) Msg RX from 2:FFFFFFFB00000000 [3472] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 118115 [EM]>>> [E:65336r S:0 M:144893784] (U) Msg RX from 0:B634B29A9CABCC5A [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I: 118126 [IN]CASE Server received Sigma1 message . Starting handshake. EC 0x200033c8
I: 118134 [IN]CASE Server disabling CASE session setups
I: 118139 [EM]<<< [E:65336r S:0 M:5644608 (Ack:144893784)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 118152 [IN](U) Sending msg 5644608 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 118161 [SC]Received Sigma1 msg
I: 118173 [SC]CASE matched destination ID: fabricIndex 2, NodeID 0x168D26C74BA38C7E
I: 118349 [EM]<<< [E:65336r S:0 M:5644609 (Ack:144893784)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I: 118362 [IN](U) Sending msg 5644609 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 118371 [SC]Sent Sigma2 msg
I: 118386 [EM]>>> [E:65336r S:0 M:144893785 (Ack:5644609)] (U) Msg RX from 0:B634B29A9CABCC5A [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I: 118404 [EM]<<< [E:65336r S:0 M:5644610 (Ack:144893785)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 118417 [IN](U) Sending msg 5644610 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 118426 [SC]Received Sigma3 msg
E: 118433 [SC]The device does not support GetClock_RealTimeMS() API: 3.  Falling back to Last Known Good UTC Time
E: 118551 [DL]Long dispatch time: 108 ms, for event type 2
I: 118562 [EM]<<< [E:65336r S:0 M:5644611 (Ack:144893785)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I: 118574 [IN](U) Sending msg 5644611 to IP address 'UDP:[fe80::506a:62ff:fe84:9a0e]:39126'
I: 118585 [SC]SecureSession[0x20001b78]: Moving from state 'kEstablishing' --> 'kActive'
D: 118593 [IN]SecureSession[0x20001b78]: Activated - Type:2 LSID:13242
D: 118604 [IN]New secure session activated for device <FD38A73EBE1C8EAE, 2>, LSID:13242 PSID:27347!
I: 118613 [IN]CASE Session established to peer: <FD38A73EBE1C8EAE, 2>
I: 118619 [IN]CASE Server enabling CASE session setups
D: 118624 [IN]SecureSession[0x20001c30]: Allocated Type:2 LSID:13244
I: 118631 [EM]>>> [E:65336r S:0 M:144893786 (Ack:5644611)] (U) Msg RX from 0:B634B29A9CABCC5A [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 118644 [EM]>>> [E:65337r S:13242 M:234516169] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0001:02 (IM:ReadRequest)
D: 118655 [IM]Received Read request
I: 118660 [EM]<<< [E:65337r S:13242 M:159687884 (Ack:234516169)] (S) Msg TX to 2:FD38A73EBE1C8EAE [3472] --- Type 0001:05 (IM:ReportData)
I: 118672 [IN](S) Sending msg 159687884 on secure session with LSID: 13242
I: 118692 [EM]>>> [E:65337r S:13242 M:234516170 (Ack:159687884)] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 118765 [EM]>>> [E:65338r S:13242 M:234516171] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0001:02 (IM:ReadRequest)
D: 118776 [IM]Received Read request
I: 118782 [EM]<<< [E:65338r S:13242 M:159687885 (Ack:234516171)] (S) Msg TX to 2:FD38A73EBE1C8EAE [3472] --- Type 0001:05 (IM:ReportData)
I: 118794 [IN](S) Sending msg 159687885 on secure session with LSID: 13242
I: 118816 [EM]>>> [E:65338r S:13242 M:234516172 (Ack:159687885)] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 118837 [EM]>>> [E:65339r S:13242 M:234516173] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 118849 [FS]GeneralCommissioning: Received CommissioningComplete
I: 118857 [FP]Metadata for Fabric 0x2 persisted to storage.
I: 118874 [TS]Committing Last Known Good Time to storage: 2024-07-17T01:55:33
I: 118882 [ZCL]OpCreds: Fabric index 0x2 was committed to storage. Compressed Fabric Id 0x7E6F1438C5793472, FabricId 2D414A912B1524F6, NodeId 168D26C74BA38C7E, VendorId 0x6006
I: 118898 [FS]GeneralCommissioning: Successfully commited pending fabric data
I: 118904 [FS]Fail-safe cleanly disarmed
I: 118910 [EM]<<< [E:65339r S:13242 M:159687886 (Ack:234516173)] (S) Msg TX to 2:FD38A73EBE1C8EAE [3472] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 118932 [IN](S) Sending msg 159687886 on secure session with LSID: 13242
I: 118940 [SVR]Commissioning completed successfully
I: 118945 [DIS]Updating services using commissioning mode 0
E: 118953 [IN]SO_REUSEPORT failed: 109
I: 118956 [DIS]CHIP minimal mDNS started advertising.
D: 119079 [DL]Using wifi MAC for hostname
I: 119083 [DIS]Advertise operational node EE309033DCA5085D-4D689E084A806B90
D: 119090 [DIS]Responding with _matter._tcp.local
D: 119095 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 119102 [DIS]Responding with EE309033DCA5085D-4D689E084A806B90._matter._tcp.local
D: 119111 [DIS]Responding with F4CE360024C2.local
D: 119115 [DIS]Responding with F4CE360024C2.local
D: 119120 [DIS]Responding with _IEE309033DCA5085D._sub._matter._tcp.local
I: 119126 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: EE309033DCA5085D-4D689E084A806B90.
I: 119147 [DIS]mDNS service published: _matter._tcp
D: 119152 [DL]Using wifi MAC for hostname
I: 119156 [DIS]Advertise operational node 7E6F1438C5793472-168D26C74BA38C7E
D: 119163 [DIS]Responding with _matter._tcp.local
D: 119167 [DIS]Responding with 7E6F1438C5793472-168D26C74BA38C7E._matter._tcp.local
D: 119175 [DIS]Responding with 7E6F1438C5793472-168D26C74BA38C7E._matter._tcp.local
D: 119182 [DIS]Responding with F4CE360024C2.local
D: 119187 [DIS]Responding with F4CE360024C2.local
D: 119191 [DIS]Responding with _I7E6F1438C5793472._sub._matter._tcp.local
I: 119198 [DIS]CHIP minimal mDNS configured as 'Operational device'; instance name: 7E6F1438C5793472-168D26C74BA38C7E.
I: 119229 [DIS]mDNS service published: _matter._tcp
D: 119234 [DL]Using wifi MAC for hostname
I: 119239 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0
D: 119248 [DIS]Responding with _matterc._udp.local
D: 119253 [DIS]Responding with E387030DFED0E531._matterc._udp.local
D: 119259 [DIS]Responding with F4CE360024C2.local
D: 119263 [DIS]Responding with F4CE360024C2.local
D: 119267 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 119273 [DIS]Responding with _S15._sub._matterc._udp.local
D: 119279 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 119284 [DIS]Responding with E387030DFED0E531._matterc._udp.local
I: 119290 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: E387030DFED0E531.
I: 119366 [DIS]mDNS service published: _matterc._udp
D: 119371 [IN]Expiring all PASE sessions
D: 119375 [IN]SecureSession[0x20001a08]: MarkForEviction Type:1 LSID:13243
I: 119382 [SC]SecureSession[0x20001a08]: Moving from state 'kActive' --> 'kPendingEviction'
D: 119390 [IN]SecureSession[0x20001a08]: Released - Type:1 LSID:13243
E: 119396 [DL]Long dispatch time: 456 ms, for event type 32781
I: 119402 [IN](S) Sending msg 159687886 on secure session with LSID: 13242
I: 119411 [EM]>>> [E:65339r S:13242 M:234516174 (Ack:159687886)] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 119429 [EM]>>> [E:65339r S:13242 M:234516175 (Ack:159687886)] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 137443 [DMG]Refresh subscribe timer sync after 29 seconds
I: 137450 [EM]<<< [E:58759i S:13241 M:38031928] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 137460 [IN](S) Sending msg 38031928 on secure session with LSID: 13241
I: 137468 [DMG]Refresh Subscribe Sync Timer with min 1 seconds and max 30 seconds
I: 137479 [EM]>>> [E:58759i S:13241 M:257134237 (Ack:38031928)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:01 (IM:StatusResponse)
I: 137491 [IM]Received status response, status is 0x00
I: 137497 [EM]<<< [E:58759i S:13241 M:38031929 (Ack:257134237)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 137510 [IN](S) Sending msg 38031929 on secure session with LSID: 13241
I: 167479 [DMG]Refresh subscribe timer sync after 29 seconds
I: 167485 [EM]<<< [E:58760i S:13241 M:38031930] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 167496 [IN](S) Sending msg 38031930 on secure session with LSID: 13241
I: 167503 [DMG]Refresh Subscribe Sync Timer with min 1 seconds and max 30 seconds
I: 167514 [EM]>>> [E:58760i S:13241 M:257134238 (Ack:38031930)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:01 (IM:StatusResponse)
I: 167527 [IM]Received status response, status is 0x00
I: 167532 [EM]<<< [E:58760i S:13241 M:38031931 (Ack:257134238)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 167557 [IN](S) Sending msg 38031931 on secure session with LSID: 13241
I: 197513 [DMG]Refresh subscribe timer sync after 29 seconds
I: 197520 [EM]<<< [E:58761i S:13241 M:38031932] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0001:05 (IM:ReportData)
I: 197530 [IN](S) Sending msg 38031932 on secure session with LSID: 13241
I: 197538 [DMG]Refresh Subscribe Sync Timer with min 1 seconds and max 30 seconds
I: 197660 [EM]>>> [E:58761i S:13241 M:257134239 (Ack:38031932)] (S) Msg RX from 1:1BC15B739A5D27FF [085D] --- Type 0001:01 (IM:StatusResponse)
I: 197673 [IM]Received status response, status is 0x00
I: 197678 [EM]<<< [E:58761i S:13241 M:38031933 (Ack:257134239)] (S) Msg TX to 1:1BC15B739A5D27FF [085D] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 197691 [IN](S) Sending msg 38031933 on secure session with LSID: 13241

 

▶ Matter  Light-bulb 장치의 LED2  ON/OFF  하기

  →  SmartThings 앱에서 Matter   Light-bulb 장치 선택

   : SmartThings Station 장비가 없으면 동작 안함.

 

 →  전원 버튼 클릭시 ON/OFF 토글 됩니다.

   >> nRF7002 보드에서 Button2 를 누를경우 앱상에도 현재 상태 반영됩니다.

 

 

 

→  light bulb LED2 On/OFF 시 시리얼 로그

>> LED2 OFF
I: 138576 [EM]>>> [E:50890r S:47612 M:155836868] (S) Msg RX from 1:5D63ACC59E0B41DD [406B] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 138589 [ZCL]Toggle ep1 on/off from state 1 to 0
I: 138593 [ZCL]Setting on/off to OFF due to level change
I: 138598 [ZCL]Toggle ep1 on/off from state 1 to 0
I: 138603 [ZCL]Cluster OnOff: attribute OnOff set to 0
I: Turn Off Action has been initiated
I: Turn Off Action has been completed
I: 138615 [ZCL]Off completed. reset OnTime to  0
I: 138622 [EM]<<< [E:50890r S:47612 M:232732421 (Ack:155836868)] (S) Msg TX to 1:5D63ACC59E0B41DD [406B] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 138635 [IN](S) Sending msg 232732421 on secure session with LSID: 47612
I: 138644 [EM]<<< [E:22608i S:47612 M:232732422] (S) Msg TX to 1:5D63ACC59E0B41DD [406B] --- Type 0001:05 (IM:ReportData)
I: 138655 [IN](S) Sending msg 232732422 on secure session with LSID: 47612
I: 138663 [DMG]Refresh Subscribe Sync Timer with min 1 seconds and max 30 seconds
I: 138673 [EM]>>> [E:50890r S:47612 M:155836869 (Ack:232732421)] (S) Msg RX from 1:5D63ACC59E0B41DD [406B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 138689 [EM]>>> [E:22608i S:47612 M:155836870 (Ack:232732422)] (S) Msg RX from 1:5D63ACC59E0B41DD [406B] --- Type 0001:01 (IM:StatusResponse)
I: 138702 [IM]Received status response, status is 0x00
I: 138708 [EM]<<< [E:22608i S:47612 M:232732423 (Ack:155836870)] (S) Msg TX to 1:5D63ACC59E0B41DD [406B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 138722 [IN](S) Sending msg 232732423 on secure session with LSID: 47612


>> LED2 ON
uart:~$ I: 160480 [EM]>>> [E:50891r S:47612 M:155836871] (S) Msg RX from 1:5D63ACC59E0B41DD [406B] --- Type 0001:08 (IM:InvokeCommandRequest)
I: 160492 [ZCL]Toggle ep1 on/off from state 0 to 1
I: 160497 [ZCL]On Command - OffWaitTime :  0
I: 160503 [ZCL]On/Toggle Command - Stop Timer
I: 160508 [ZCL]Cluster OnOff: attribute OnOff set to 1
I: Turn On Action has been initiated
I: Turn On Action has been completed
I: 160519 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
I: 160526 [EM]<<< [E:50891r S:47612 M:232732424 (Ack:155836871)] (S) Msg TX to 1:5D63ACC59E0B41DD [406B] --- Type 0001:09 (IM:InvokeCommandResponse)
I: 160540 [IN](S) Sending msg 232732424 on secure session with LSID: 47612
I: 160550 [EM]<<< [E:22609i S:47612 M:232732425] (S) Msg TX to 1:5D63ACC59E0B41DD [406B] --- Type 0001:05 (IM:ReportData)
I: 160561 [IN](S) Sending msg 232732425 on secure session with LSID: 47612
I: 160569 [DMG]Refresh Subscribe Sync Timer with min 1 seconds and max 30 seconds
I: 160579 [EM]>>> [E:50891r S:47612 M:155836872 (Ack:232732424)] (S) Msg RX from 1:5D63ACC59E0B41DD [406B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 160607 [EM]>>> [E:22609i S:47612 M:155836873 (Ack:232732425)] (S) Msg RX from 1:5D63ACC59E0B41DD [406B] --- Type 0001:01 (IM:StatusResponse)
I: 160619 [IM]Received status response, status is 0x00
I: 160625 [EM]<<< [E:22609i S:47612 M:232732426 (Ack:155836873)] (S) Msg TX to 1:5D63ACC59E0B41DD [406B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 160647 [IN](S) Sending msg 232732426 on secure session with LSID: 47612
I: 190578 [DMG]Refresh subscribe timer sync after 29 seconds
I: 190585 [EM]<<< [E:22610i S:47612 M:232732427] (S) Msg TX to 1:5D63ACC59E0B41DD [406B] --- Type 0001:05 (IM:ReportData)
I: 190595 [IN](S) Sending msg 232732427 on secure session with LSID: 47612
I: 190603 [DMG]Refresh Subscribe Sync Timer with min 1 seconds and max 30 seconds
I: 190664 [EM]>>> [E:22610i S:47612 M:155836874 (Ack:232732427)] (S) Msg RX from 1:5D63ACC59E0B41DD [406B] --- Type 0001:01 (IM:StatusResponse)
I: 190676 [IM]Received status response, status is 0x00
I: 190682 [EM]<<< [E:22610i S:47612 M:232732428 (Ack:155836874)] (S) Msg TX to 1:5D63ACC59E0B41DD [406B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I: 190695 [IN](S) Sending msg 232732428 on secure session with LSID: 47612

 

 

 

 

< 기타 >

▶ light-bulb  부팅로그

uart:~$ *** Booting Zephyr OS build v3.3.99-ncs1-1 ***
I: Init CHIP stack
I: 168 [DL]BLE address: F2:A0:A0:5A:56:48
D: 201 [DL]WiFiManager has been initialized
I: 206 [SVR]Subscription persistence not supported
I: 210 [SVR]Server initializing...
I: 214 [TS]Last Known Good Time: [unknown]
I: 218 [TS]Setting Last Known Good Time to firmware build time 2023-10-19T20:46:33
I: 227 [DMG]AccessControl: initializing
I: 231 [DMG]Examples::AccessControlDelegate::Init
I: 235 [DMG]AccessControl: setting
I: 238 [DMG]DefaultAclStorage: initializing
I: 242 [DMG]DefaultAclStorage: 0 entries loaded
D: 246 [IN]UDP::Init bind&listen port=5540
E: 250 [IN]SO_REUSEPORT failed: 109
D: 253 [IN]UDP::Init bound to port=5540
D: 257 [IN]BLEBase::Init - setting/overriding transport
D: 262 [IN]TransportMgr initialized
I: 270 [ZCL]Using ZAP configuration...
I: 277 [DMG]AccessControlCluster: initializing
D: 282 [DL]Boot reason: 1
I: 284 [ZCL]Initiating Admin Commissioning cluster.
I: 289 [ZCL]Endpoint 1 On/off already set to new value
I: 295 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 1
D: 303 [IN]SecureSession[0x200041d0]: Allocated Type:1 LSID:63608
D: 309 [DL]CHIPoBLE advertising set to on
I: 312 [DIS]Updating services using commissioning mode 1
I: 318 [DIS]CHIP minimal mDNS started advertising.
D: 322 [DL]Using wifi MAC for hostname
I: 327 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1
D: 336 [DIS]Responding with _matterc._udp.local
D: 340 [DIS]Responding with A4B6032B541000E7._matterc._udp.local
D: 346 [DIS]Responding with F4CE360024C2.local
D: 350 [DIS]Responding with F4CE360024C2.local
D: 354 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 359 [DIS]Responding with _S15._sub._matterc._udp.local
D: 364 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 370 [DIS]Responding with _CM._sub._matterc._udp.local
D: 375 [DIS]Responding with A4B6032B541000E7._matterc._udp.local
I: 381 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A4B6032B541000E7.
I: 390 [DIS]mDNS service published: _matterc._udp
I: 395 [DIS]Updating services using commissioning mode 1
I: 400 [DIS]CHIP minimal mDNS started advertising.
D: 404 [DL]Using wifi MAC for hostname
I: 409 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1
D: 418 [DIS]Responding with _matterc._udp.local
D: 422 [DIS]Responding with A4B6032B541000E7._matterc._udp.local
D: 428 [DIS]Responding with F4CE360024C2.local
D: 432 [DIS]Responding with F4CE360024C2.local
D: 436 [DIS]Responding with _V65521._sub._matterc._udp.local
D: 441 [DIS]Responding with _S15._sub._matterc._udp.local
D: 447 [DIS]Responding with _L3840._sub._matterc._udp.local
D: 452 [DIS]Responding with _CM._sub._matterc._udp.local
D: 457 [DIS]Responding with A4B6032B541000E7._matterc._udp.local
I: 463 [DIS]CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A4B6032B541000E7.
I: 472 [DIS]mDNS service published: _matterc._udp
I: 477 [IN]CASE Server enabling CASE session setups
D: 482 [IN]SecureSession[0x20004288]: Allocated Type:2 LSID:63609
I: 487 [SVR]Joining Multicast groups
I: 491 [SVR]Server Listening...
I: 494 [DL]Device Configuration:
I: 497 [DL]  Serial Number: 11223344556677889900
I: 501 [DL]  Vendor Id: 65521 (0xFFF1)
I: 505 [DL]  Product Id: 32773 (0x8005)
I: 508 [DL]  Product Name: not-specified
I: 513 [DL]  Hardware Version: 0
I: 516 [DL]  Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
I: 522 [DL]  Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
I: 529 [DL]  Manufacturing Date: (not set)
I: 533 [DL]  Device Type: 65535 (0xFFFF)
I: 538 [SVR]SetupQRCode: [MT:6FCJ142C00KA0648G00]
I: 542 [SVR]Copy/paste the below URL in a browser to see the QR Code:
I: 548 [SVR]https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A6FCJ142C00KA0648G00
I: 557 [SVR]Manual pairing code: [34970112332]
I: 565 [DL]CHIP task running
I: 579 [DL]CHIPoBLE advertising started
I: 584 [ZCL]Cluster OnOff: attribute OnOff set to 0
I: 588 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254
I: 596 [DL]NFC Tag emulation started
I: 30583 [DL]CHIPoBLE advertising mode changed to slow
I: 30592 [DL]CHIPoBLE advertising started

uart:~$
uart:~$

 

▶ light-bulb  와 SmartThings 앱 연결 구성시 로그 메시지

light-bulb_connect.lgo.txt

0.09MB

 

그럼 수고하세요.