2023-08-29 11:56:46.804570+0800 CHIPTool[12736:3614845] Payload vendorID 65521
2023-08-29 11:56:46.804784+0800 CHIPTool[12736:3614845] Rendezvous Default
2023-08-29 11:56:46.813828+0800 CHIPTool[12736:3614845] Found an existing IPK in the keychain
2023-08-29 11:56:46.815700+0800 CHIPTool[12736:3614845] Found an existing CA key in the keychain
2023-08-29 11:56:46.815889+0800 CHIPTool[12736:3614845] Shutting down the stack
2023-08-29 11:56:46.816249+0800 CHIPTool[12736:3614845] [Controller] Shutting down the commissioner
2023-08-29 11:56:46.816278+0800 CHIPTool[12736:3614845] [Controller] Stopping commissioning discovery over DNS-SD
2023-08-29 11:56:46.816296+0800 CHIPTool[12736:3614845] [Discovery] Closing all BLE connections
2023-08-29 11:56:46.816375+0800 CHIPTool[12736:3614845] [Inet] SecureSession[0x280df4780]: MarkForEviction Type:1 LSID:24370
2023-08-29 11:56:46.816401+0800 CHIPTool[12736:3614845] [SecureChannel] SecureSession[0x280df4780]: Moving from state 'kActive' --> 'kPendingEviction'
2023-08-29 11:56:46.816534+0800 CHIPTool[12736:3614845] [DataManagement] Time out! failed to receive report data from Exchange: 52909i
2023-08-29 11:56:46.816631+0800 CHIPTool[12736:3614845] [Default] DeviceControllerDelegate Read Commissioning Info. VendorId 0 ProductId 0
2023-08-29 11:56:46.816738+0800 CHIPTool[12736:3614845] [Inet] SecureSession[0x280df4780]: Released - Type:1 LSID:24370
2023-08-29 11:56:46.816769+0800 CHIP2023-08-29 11:56:46.816768+0800 CHIPTool[12736:3616512] readCommissioningInfo, vendorID:0, productID:0
Tool[12736:3614845] [Controller] Shutting down the controller
2023-08-29 11:56:46.817078+0800 CHIPTool[12736:3614845] [Inet] Expiring all sessions for fabric 0x1!!
2023-08-29 11:56:46.817098+0800 CHIPTool[12736:3614845] [Controller] Shutting down the System State, this will teardown the CHIP Stack
2023-08-29 11:56:46.817128+0800 CHIPTool[12736:3614845] [DataManagement] All ReadHandler-s are clean, clear GlobalDirtySet
2023-08-29 11:56:46.817252+0800 CHIPTool[12736:3614845] [Ble] CancelConnection
2023-08-29 11:56:46.817374+0800 CHIPTool[12736:3614845] [FabricProvisioning] Shutting down FabricTable
2023-08-29 11:56:46.817443+0800 CHIPTool[12736:3614845] [TimeService] Pending Last Known Good Time: 2023-08-28T19:11:04
2023-08-29 11:56:46.817595+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/lkgt, value {length = 8, bytes = 0x152600c8ad7f2c18}
2023-08-29 11:56:46.817677+0800 CHIPTool[12736:3614845] [TimeService] Previous Last Known Good Time: 2023-08-28T19:11:04
2023-08-29 11:56:46.818495+0800 CHIPTool[12736:3614845] [TimeService] Reverted Last Known Good Time to previous value
2023-08-29 11:56:46.818556+0800 CHIPTool[12736:3614845] [DeviceLayer] Inet Layer shutdown
2023-08-29 11:56:46.818738+0800 CHIPTool[12736:3614845] [DeviceLayer] BLE shutdown
2023-08-29 11:56:46.818866+0800 CHIPTool[12736:3614845] [DeviceLayer] System Layer shutdown
2023-08-29 11:56:46.819063+0800 CHIPTool[12736:3614845] Starting up the stack
2023-08-29 11:56:46.819267+0800 CHIPTool[12736:3614845] [Controller] Trying to start operational browse
2023-08-29 11:56:46.820314+0800 CHIPTool[12736:3615048] [CoreBluetooth] XPC connection invalid
2023-08-29 11:56:46.822291+0800 CHIPTool[12736:3614845] [FabricProvisioning] Initializing FabricTable from persistent storage
2023-08-29 11:56:46.822380+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/lkgt, value {length = 8, bytes = 0x152600c8ad7f2c18}
2023-08-29 11:56:46.822451+0800 CHIPTool[12736:3614845] [TimeService] Last Known Good Time: 2023-08-28T19:11:04
2023-08-29 11:56:46.822542+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/fidx, value {length = 10, bytes = 0x15240002360104011818}
2023-08-29 11:56:46.822667+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/n, value {length = 258, bytes = 0x15300101 01240201 37032714 d43db9dc ... bb544af4 16693318 }
2023-08-29 11:56:46.822766+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/r, value {length = 251, bytes = 0x15300101 00240201 37032714 d43db9dc ... 4b5568a1 d40fdd18 }
2023-08-29 11:56:46.823089+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/m, value {length = 9, bytes = 0x152500f1ff2c010018}
2023-08-29 11:56:46.823149+0800 CHIPTool[12736:3614845] [FabricProvisioning] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x810BA8FD69CFCEFA, FabricId 0x0000000000000001, NodeId 0x59B5875699398305, VendorId 0xFFF1
2023-08-29 11:56:46.823223+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/fs/c, value (null)
2023-08-29 11:56:46.823333+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/n, value {length = 258, bytes = 0x15300101 01240201 37032714 d43db9dc ... bb544af4 16693318 }
2023-08-29 11:56:46.823520+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/o, value {length = 105, bytes = 0x15240001 30016104 12de8516 fb034d15 ... ab6e25f2 e2a09318 }
2023-08-29 11:56:46.823635+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/i, value (null)
2023-08-29 11:56:46.823707+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/n, value {length = 258, bytes = 0x15300101 01240201 37032714 d43db9dc ... bb544af4 16693318 }
2023-08-29 11:56:46.823785+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/r, value {length = 251, bytes = 0x15300101 00240201 37032714 d43db9dc ... 4b5568a1 d40fdd18 }
2023-08-29 11:56:46.823944+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: '87D5FA08314A60C3-00000000686280AF'
2023-08-29 11:56:46.823992+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: '4DCA89448629FD17-00000000E16938B3'
2023-08-29 11:56:46.824105+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: '2AA2FA571C5881B9-000000007C2BE945'
2023-08-29 11:56:46.824155+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: 'AC0C38AF24938037-000000007A7BDA6C'
2023-08-29 11:56:46.824194+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: '10D4B587FDC164E7-00000000BEBA7B18'
2023-08-29 11:56:46.824231+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: 'AC0C38AF24938037-000000007A7BDA6C'
2023-08-29 11:56:46.824267+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: '10D4B587FDC164E7-00000000BEBA7B18'
2023-08-29 11:56:46.824303+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: '810BA8FD69CFCEFA-0000000000000010'
2023-08-29 11:56:46.824798+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: '609C8F61EC325564-008C72C958B14501'
2023-08-29 11:56:46.824840+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: '87D5FA08314A60C3-000000005F0F5F09'
2023-08-29 11:56:46.824881+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: 'ED2FD24BA396DC53-000000004801437A'
2023-08-29 11:56:46.824921+0800 CHIPTool[12736:3616512] [Controller] Notifying controller factory about new operational instance: 'C3BBF6174C6D2356-239C5855549E000A'
2023-08-29 11:56:46.827215+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/o, value {length = 105, bytes = 0x15240001 30016104 12de8516 fb034d15 ... ab6e25f2 e2a09318 }
2023-08-29 11:56:46.827361+0800 CHIPTool[12736:3614845] [Controller] Stopping commissioning discovery over DNS-SD
2023-08-29 11:56:46.827409+0800 CHIPTool[12736:3614845] [Controller] Setting attestation nonce to random value
2023-08-29 11:56:46.827695+0800 CHIPTool[12736:3614845] [Controller] Setting CSR nonce to random value
2023-08-29 11:56:46.828216+0800 CHIPTool[12736:3614845] [DeviceLayer] _Init
2023-08-29 11:56:46.828244+0800 CHIPTool[12736:3614845] [Inet] UDP::Init bind&listen port=0
2023-08-29 11:56:46.828413+0800 CHIPTool[12736:3614845] [Inet] UDP::Init bound to port=49626
2023-08-29 11:56:46.828425+0800 CHIPTool[12736:3614845] [Inet] UDP::Init bind&listen port=0
2023-08-29 11:56:46.828503+0800 CHIPTool[12736:3614845] [Inet] UDP::Init bound to port=58274
2023-08-29 11:56:46.828512+0800 CHIPTool[12736:3614845] [Inet] BLEBase::Init - setting/overriding transport
2023-08-29 11:56:46.828517+0800 CHIPTool[12736:3614845] [Inet] TransportMgr initialized
2023-08-29 11:56:46.828534+0800 CHIPTool[12736:3614845] [FabricProvisioning] Initializing FabricTable from persistent storage
2023-08-29 11:56:46.828582+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/lkgt, value {length = 8, bytes = 0x152600c8ad7f2c18}
2023-08-29 11:56:46.828630+0800 CHIPTool[12736:3614845] [TimeService] Last Known Good Time: 2023-08-28T19:11:04
2023-08-29 11:56:46.828691+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/fidx, value {length = 10, bytes = 0x15240002360104011818}
2023-08-29 11:56:46.828770+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/n, value {length = 258, bytes = 0x15300101 01240201 37032714 d43db9dc ... bb544af4 16693318 }
2023-08-29 11:56:46.828828+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/r, value {length = 251, bytes = 0x15300101 00240201 37032714 d43db9dc ... 4b5568a1 d40fdd18 }
2023-08-29 11:56:46.829037+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/m, value {length = 9, bytes = 0x152500f1ff2c010018}
2023-08-29 11:56:46.829074+0800 CHIPTool[12736:3614845] [FabricProvisioning] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x810BA8FD69CFCEFA, FabricId 0x0000000000000001, NodeId 0x59B5875699398305, VendorId 0xFFF1
2023-08-29 11:56:46.829124+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/fs/c, value (null)
2023-08-29 11:56:46.829519+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/gcc, value {length = 4, bytes = 0xd0840000}
2023-08-29 11:56:46.829647+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/gdc, value {length = 4, bytes = 0xd0840000}
2023-08-29 11:56:46.831773+0800 CHIPTool[12736:3614845] [Controller] System State Initialized...
2023-08-29 11:56:46.831908+0800 CHIPTool[12736:3614845] [Controller] Intermediate CA is not needed
2023-08-29 11:56:46.832217+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/o, value {length = 105, bytes = 0x15240001 30016104 12de8516 fb034d15 ... ab6e25f2 e2a09318 }
2023-08-29 11:56:46.832307+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/o, value {length = 105, bytes = 0x15240001 30016104 12de8516 fb034d15 ... ab6e25f2 e2a09318 }
2023-08-29 11:56:46.832419+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/r, value {length = 251, bytes = 0x15300101 00240201 37032714 d43db9dc ... 4b5568a1 d40fdd18 }
2023-08-29 11:56:46.832522+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/r, value {length = 251, bytes = 0x15300101 00240201 37032714 d43db9dc ... 4b5568a1 d40fdd18 }
2023-08-29 11:56:46.832614+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/n, value {length = 258, bytes = 0x15300101 01240201 37032714 d43db9dc ... bb544af4 16693318 }
2023-08-29 11:56:46.832709+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/r, value {length = 251, bytes = 0x15300101 00240201 37032714 d43db9dc ... 4b5568a1 d40fdd18 }
2023-08-29 11:56:46.832752+0800 CHIPTool[12736:3614845] [FabricProvisioning] Validating NOC chain
2023-08-29 11:56:46.839925+0800 CHIPTool[12736:3614845] [FabricProvisioning] NOC chain validation successful
2023-08-29 11:56:46.840107+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/o, value {length = 105, bytes = 0x15240001 30016104 12de8516 fb034d15 ... ab6e25f2 e2a09318 }
2023-08-29 11:56:46.840177+0800 CHIPTool[12736:3614845] [FabricProvisioning] Updated fabric at index: 0x1, Node ID: 0x59B5875699398305
2023-08-29 11:56:46.840219+0800 CHIPTool[12736:3614845] [TimeService] Last Known Good Time: 2023-08-28T19:11:04
2023-08-29 11:56:46.840248+0800 CHIPTool[12736:3614845] [TimeService] New proposed Last Known Good Time: 2023-08-28T11:12:45
2023-08-29 11:56:46.840272+0800 CHIPTool[12736:3614845] [TimeService] Retaining current Last Known Good Time
2023-08-29 11:56:46.840317+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: g/sri, value (null)
2023-08-29 11:56:46.840376+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/o, value {length = 105, bytes = 0x15240001 30016104 12de8516 fb034d15 ... ab6e25f2 e2a09318 }
2023-08-29 11:56:46.840901+0800 CHIPTool[12736:3614845] [FabricProvisioning] Metadata for Fabric 0x1 persisted to storage.
2023-08-29 11:56:46.841040+0800 CHIPTool[12736:3614845] MTRPersistentStorageDelegate Get Value for Key: f/1/o, value {length = 105, bytes = 0x15240001 30016104 12de8516 fb034d15 ... ab6e25f2 e2a09318 }
2023-08-29 11:56:46.841089+0800 CHIPTool[12736:3614845] [TimeService] Committing Last Known Good Time to storage: 2023-08-28T19:11:04
2023-08-29 11:56:46.841410+0800 CHIPTool[12736:3614845] [Controller] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 810BA8FD69CFCEFA)
2023-08-29 11:56:46.841695+0800 CHIPTool[12736:3616512] [DeviceLayer] _OnPlatformEvent
2023-08-29 11:56:46.841839+0800 CHIPTool[12736:3614845] [Controller] Stopping commissioning discovery over DNS-SD
2023-08-29 11:56:46.841849+0800 CHIPTool[12736:3614845] [Controller] Starting commissioning discovery over BLE
2023-08-29 11:56:46.841877+0800 CHIPTool[12736:3614845] [Ble] NewConnection
2023-08-29 11:56:46.841920+0800 CHIPTool[12736:3614845] [Controller] Starting commissioning discovery over DNS-SD
2023-08-29 11:56:46.841976+0800 CHIPTool[12736:3614845] [Discovery] Browsing for: _matterc._udp,_L391
2023-08-29 11:56:46.848958+0800 CHIPTool[12736:3615048] [Ble] CBManagerState: ON
2023-08-29 11:56:46.915029+0800 CHIPTool[12736:3616512] [Ble] Connecting to device 0x2809f0a90 with discriminator: 391
2023-08-29 11:56:47.987988+0800 CHIPTool[12736:3615048] [Controller] Discovered device to be commissioned over BLE
2023-08-29 11:56:47.988165+0800 CHIPTool[12736:3615048] [Controller] Attempting PASE connection to BLE
2023-08-29 11:56:47.988286+0800 CHIPTool[12736:3615048] [Inet] BleConnectionComplete: endPoint 0x10998cf60
2023-08-29 11:56:47.988328+0800 CHIPTool[12736:3615048] [Ble] ATT MTU = 517
2023-08-29 11:56:47.989600+0800 CHIPTool[12736:3615048] [Inet] SecureSession[0x280df4780]: Allocated Type:1 LSID:55878
2023-08-29 11:56:47.989644+0800 CHIPTool[12736:3615048] [SecureChannel] Assigned local session key ID 55878
2023-08-29 11:56:47.990177+0800 CHIPTool[12736:3615048] [ExchangeManager] <<< [E:3653i S:0 M:20244786] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
2023-08-29 11:56:47.990311+0800 CHIPTool[12736:3615048] [Inet] (U) Sending msg 20244786 to IP address 'BLE'
2023-08-29 11:56:47.990394+0800 CHIPTool[12736:3615048] [Inet] Message appended to BLE send queue
2023-08-29 11:56:47.990413+0800 CHIPTool[12736:3615048] [SecureChannel] Sent PBKDF param request
2023-08-29 11:56:48.228524+0800 CHIPTool[12736:3616512] [Ble] subscribe complete, ep = 0x10998cf60
2023-08-29 11:56:48.228752+0800 CHIPTool[12736:3616512] [Ble] peripheral chose BTP version 4; central expected between 4 and 4
2023-08-29 11:56:48.228854+0800 CHIPTool[12736:3616512] [Ble] using BTP fragment sizes rx 244 / tx 244.
2023-08-29 11:56:48.228935+0800 CHIPTool[12736:3616512] [Ble] local and remote recv window size = 5
2023-08-29 11:56:48.229453+0800 CHIPTool[12736:3616512] [Inet] BLE EndPoint 0x10998cf60 Connection Complete
2023-08-29 11:56:48.348077+0800 CHIPTool[12736:3616512] [ExchangeManager] >>> [E:3653i S:0 M:135349533] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
2023-08-29 11:56:48.348298+0800 CHIPTool[12736:3616512] [ExchangeManager] Found matching exchange: 3653i, Delegate: 0x15980e050
2023-08-29 11:56:48.348364+0800 CHIPTool[12736:3616512] [SecureChannel] Received PBKDF param response
2023-08-29 11:56:48.348438+0800 CHIPTool[12736:3616512] [SecureChannel] Peer assigned session ID 56043
2023-08-29 11:56:48.457526+0800 CHIPTool[12736:3616512] [ExchangeManager] <<< [E:3653i S:0 M:20244787] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
2023-08-29 11:56:48.457605+0800 CHIPTool[12736:3616512] [Inet] (U) Sending msg 20244787 to IP address 'BLE'
2023-08-29 11:56:48.457744+0800 CHIPTool[12736:3616512] [SecureChannel] Sent spake2p msg1
2023-08-29 11:56:49.338930+0800 CHIPTool[12736:3616510] [ExchangeManager] >>> [E:3653i S:0 M:135349534] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
2023-08-29 11:56:49.339140+0800 CHIPTool[12736:3616510] [ExchangeManager] Found matching exchange: 3653i, Delegate: 0x15980e050
2023-08-29 11:56:49.339202+0800 CHIPTool[12736:3616510] [SecureChannel] Received spake2p msg2
2023-08-29 11:56:49.374248+0800 CHIPTool[12736:3616510] [ExchangeManager] <<< [E:3653i S:0 M:20244788] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
2023-08-29 11:56:49.374311+0800 CHIPTool[12736:3616510] [Inet] (U) Sending msg 20244788 to IP address 'BLE'
2023-08-29 11:56:49.374448+0800 CHIPTool[12736:3616510] [SecureChannel] Sent spake2p msg3
2023-08-29 11:56:49.458869+0800 CHIPTool[12736:3616510] [ExchangeManager] >>> [E:3653i S:0 M:135349535] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
2023-08-29 11:56:49.459030+0800 CHIPTool[12736:3616510] [ExchangeManager] Found matching exchange: 3653i, Delegate: 0x15980e050
2023-08-29 11:56:49.459331+0800 CHIPTool[12736:3616510] [SecureChannel] I2RKey key is 894bfdcf54dcd1908d5a21e97c32903d
2023-08-29 11:56:49.459463+0800 CHIPTool[12736:3616510] [SecureChannel] kR2IKey key is 4f93cd00d87f754f8e332f16b49fff83
2023-08-29 11:56:49.459555+0800 CHIPTool[12736:3616510] [SecureChannel] SecureSession[0x280df4780]: Moving from state 'kEstablishing' --> 'kActive'
2023-08-29 11:56:49.459637+0800 CHIPTool[12736:3616510] [Inet] SecureSession[0x280df4780]: Activated - Type:1 LSID:55878
2023-08-29 11:56:49.459660+0800 CHIPTool[12736:3616510] [Inet] New secure session activated for device <FFFFFFFB00000000, 0>, LSID:55878 PSID:56043!
2023-08-29 11:56:49.459690+0800 CHIPTool[12736:3616510] [Controller] Remote device completed SPAKE2+ handshake
2023-08-29 11:56:49.459746+0800 CHIPTool[12736:3616510] [Controller] Stopping commissioning discovery over DNS-SD
2023-08-29 11:56:49.459804+0800 CHIPTool[12736:3616510] [Discovery] Mdns: Browse failure (src/platform/Darwin/DnssdImpl.cpp:481: CHIP Error 0x00000074: The operation has been cancelled)
2023-08-29 11:56:49.459939+0800 CHIPTool[12736:3616510] [Default] DeviceControllerDelegate Pairing complete. Status src/controller/CHIPDeviceController.cpp:969: Success
2023-08-29 11:56:49.497619+0800 CHIPTool[12736:3614845] [LayoutConstraints] Changing the translatesAutoresizingMaskIntoConstraints property of a UICollectionViewCell that is managed by a UICollectionView is not supported, and will result in incorrect self-sizing. View: <_UIAlertControllerTextFieldViewCollectionCell: 0x157f529e0; frame = (0 0; 270 24); gestureRecognizers = <NSArray: 0x283dc79c0>; layer = <CALayer: 0x2832d3ac0>>
2023-08-29 11:56:49.498887+0800 CHIPTool[12736:3614845] [LayoutConstraints] Changing the translatesAutoresizingMaskIntoConstraints property of a UICollectionViewCell that is managed by a UICollectionView is not supported, and will result in incorrect self-sizing. View: <_UIAlertControllerTextFieldViewCollectionCell: 0x157fc3c60; frame = (0 47.3333; 270 47.3333); gestureRecognizers = <NSArray: 0x283db99b0>; layer = <CALayer: 0x2832d2640>>
2023-08-29 11:56:53.277659+0800 CHIPTool[12736:3614845] New SSID: MiMesh_1E3F_E1C0 Password: 234567890
2023-08-29 11:56:53.277847+0800 CHIPTool[12736:3614845] [Controller] Setting wifi credentials from parameters
2023-08-29 11:56:53.277898+0800 CHIPTool[12736:3614845] [Controller] Setting attestation nonce to random value
2023-08-29 11:56:53.278189+0800 CHIPTool[12736:3614845] [Controller] Setting CSR nonce to random value
2023-08-29 11:56:53.278496+0800 CHIPTool[12736:3614845] [Controller] Commission called for node ID 0x0000000000000013
2023-08-29 11:56:53.278670+0800 CHIPTool[12736:3614845] [Controller] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
2023-08-29 11:56:53.279239+0800 CHIPTool[12736:3614845] [Controller] Performing next commissioning step 'ReadCommissioningInfo'
2023-08-29 11:56:53.279289+0800 CHIPTool[12736:3614845] [Controller] Sending request for commissioning information
2023-08-29 11:56:53.279351+0800 CHIPTool[12736:3614845] [DataManagement] SendReadRequest ReadClient[0x1594191e0]: Sending Read Request
2023-08-29 11:56:53.279738+0800 CHIPTool[12736:3614845] [ExchangeManager] <<< [E:3654i S:55878 M:18469459] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
2023-08-29 11:56:53.279816+0800 CHIPTool[12736:3614845] [Inet] (S) Sending msg 18469459 on secure session with LSID: 55878
2023-08-29 11:56:53.280137+0800 CHIPTool[12736:3614845] [DataManagement] MoveToState ReadClient[0x1594191e0]: Moving to [AwaitingIn]
2023-08-29 11:56:53.358863+0800 CHIPTool[12736:3616510] [ExchangeManager] >>> [E:3654i S:55878 M:215249151] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
2023-08-29 11:56:53.359011+0800 CHIPTool[12736:3616510] [ExchangeManager] Found matching exchange: 3654i, Delegate: 0x1594191f0
2023-08-29 11:56:53.359157+0800 CHIPTool[12736:3616510] [DataManagement] ReportDataMessage =
2023-08-29 11:56:53.359175+0800 CHIPTool[12736:3616510] [DataManagement] {
2023-08-29 11:56:53.361852+0800 CHIPTool[12736:3616510] [DataManagement]         AttributeReportIBs =
2023-08-29 11:56:53.361925+0800 CHIPTool[12736:3616510] [DataManagement]         [
2023-08-29 11:56:53.361950+0800 CHIPTool[12736:3616510] [DataManagement]                 AttributeReportIB =
2023-08-29 11:56:53.361975+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.362277+0800 CHIPTool[12736:3616510] [DataManagement]                         AttributeDataIB =
2023-08-29 11:56:53.362344+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.362360+0800 CHIPTool[12736:3616510] [DataManagement]                                 DataVersion = 0xbd9f25af,
2023-08-29 11:56:53.362677+0800 CHIPTool[12736:3616510] [DataManagement]                                 AttributePathIB =
2023-08-29 11:56:53.362696+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.362703+0800 CHIPTool[12736:3616510] [DataManagement]                                         Endpoint = 0x0,
2023-08-29 11:56:53.362710+0800 CHIPTool[12736:3616510] [DataManagement]                                         Cluster = 0x31,
2023-08-29 11:56:53.362716+0800 CHIPTool[12736:3616510] [DataManagement]                                         Attribute = 0x0000_0003,
2023-08-29 11:56:53.362721+0800 CHIPTool[12736:3616510] [DataManagement]                                 }
2023-08-29 11:56:53.362759+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.362768+0800 CHIPTool[12736:3616510] [DataManagement]                                 Data = 30,
2023-08-29 11:56:53.362773+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.362782+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.362786+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.363305+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.363316+0800 CHIPTool[12736:3616510] [DataManagement]                 AttributeReportIB =
2023-08-29 11:56:53.363326+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.363330+0800 CHIPTool[12736:3616510] [DataManagement]                         AttributeDataIB =
2023-08-29 11:56:53.363338+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.363343+0800 CHIPTool[12736:3616510] [DataManagement]                                 DataVersion = 0x96c71337,
2023-08-29 11:56:53.363348+0800 CHIPTool[12736:3616510] [DataManagement]                                 AttributePathIB =
2023-08-29 11:56:53.363354+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.363359+0800 CHIPTool[12736:3616510] [DataManagement]                                         Endpoint = 0x0,
2023-08-29 11:56:53.363364+0800 CHIPTool[12736:3616510] [DataManagement]                                         Cluster = 0x28,
2023-08-29 11:56:53.363369+0800 CHIPTool[12736:3616510] [DataManagement]                                         Attribute = 0x0000_0004,
2023-08-29 11:56:53.363373+0800 CHIPTool[12736:3616510] [DataManagement]                                 }
2023-08-29 11:56:53.363468+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.363475+0800 CHIPTool[12736:3616510] [DataManagement]                                 Data = 32768,
2023-08-29 11:56:53.363480+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.363487+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.363491+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.363504+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.363508+0800 CHIPTool[12736:3616510] [DataManagement]                 AttributeReportIB =
2023-08-29 11:56:53.363517+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.363521+0800 CHIPTool[12736:3616510] [DataManagement]                         AttributeDataIB =
2023-08-29 11:56:53.363526+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.363531+0800 CHIPTool[12736:3616510] [DataManagement]                                 DataVersion = 0x96c71337,
2023-08-29 11:56:53.363536+0800 CHIPTool[12736:3616510] [DataManagement]                                 AttributePathIB =
2023-08-29 11:56:53.363689+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.363698+0800 CHIPTool[12736:3616510] [DataManagement]                                         Endpoint = 0x0,
2023-08-29 11:56:53.363704+0800 CHIPTool[12736:3616510] [DataManagement]                                         Cluster = 0x28,
2023-08-29 11:56:53.363711+0800 CHIPTool[12736:3616510] [DataManagement]                                         Attribute = 0x0000_0002,
2023-08-29 11:56:53.363716+0800 CHIPTool[12736:3616510] [DataManagement]                                 }
2023-08-29 11:56:53.363723+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.363740+0800 CHIPTool[12736:3616510] [DataManagement]                                 Data = 65521,
2023-08-29 11:56:53.363746+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.363753+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.363758+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.363769+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.363774+0800 CHIPTool[12736:3616510] [DataManagement]                 AttributeReportIB =
2023-08-29 11:56:53.365578+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.365592+0800 CHIPTool[12736:3616510] [DataManagement]                         AttributeDataIB =
2023-08-29 11:56:53.365599+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.365605+0800 CHIPTool[12736:3616510] [DataManagement]                                 DataVersion = 0x253cf2a1,
2023-08-29 11:56:53.365610+0800 CHIPTool[12736:3616510] [DataManagement]                                 AttributePathIB =
2023-08-29 11:56:53.365615+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.365620+0800 CHIPTool[12736:3616510] [DataManagement]                                         Endpoint = 0x0,
2023-08-29 11:56:53.365626+0800 CHIPTool[12736:3616510] [DataManagement]                                         Cluster = 0x30,
2023-08-29 11:56:53.365632+0800 CHIPTool[12736:3616510] [DataManagement]                                         Attribute = 0x0000_0003,
2023-08-29 11:56:53.365636+0800 CHIPTool[12736:3616510] [DataManagement]                                 }
2023-08-29 11:56:53.365643+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.365872+0800 CHIPTool[12736:3616510] [DataManagement]                                 Data = 0,
2023-08-29 11:56:53.365885+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.365893+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.365898+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.365910+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.365915+0800 CHIPTool[12736:3616510] [DataManagement]                 AttributeReportIB =
2023-08-29 11:56:53.365923+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.365928+0800 CHIPTool[12736:3616510] [DataManagement]                         AttributeDataIB =
2023-08-29 11:56:53.365939+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.365949+0800 CHIPTool[12736:3616510] [DataManagement]                                 DataVersion = 0x253cf2a1,
2023-08-29 11:56:53.365955+0800 CHIPTool[12736:3616510] [DataManagement]                                 AttributePathIB =
2023-08-29 11:56:53.365961+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.366529+0800 CHIPTool[12736:3616510] [DataManagement]                                         Endpoint = 0x0,
2023-08-29 11:56:53.366538+0800 CHIPTool[12736:3616510] [DataManagement]                                         Cluster = 0x30,
2023-08-29 11:56:53.366544+0800 CHIPTool[12736:3616510] [DataManagement]                                         Attribute = 0x0000_0002,
2023-08-29 11:56:53.366549+0800 CHIPTool[12736:3616510] [DataManagement]                                 }
2023-08-29 11:56:53.366556+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.366561+0800 CHIPTool[12736:3616510] [DataManagement]                                 Data = 0,
2023-08-29 11:56:53.366566+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.366573+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.366577+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.366591+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.366596+0800 CHIPTool[12736:3616510] [DataManagement]                 AttributeReportIB =
2023-08-29 11:56:53.366616+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.366735+0800 CHIPTool[12736:3616510] [DataManagement]                         AttributeDataIB =
2023-08-29 11:56:53.366744+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.366750+0800 CHIPTool[12736:3616510] [DataManagement]                                 DataVersion = 0x253cf2a1,
2023-08-29 11:56:53.366755+0800 CHIPTool[12736:3616510] [DataManagement]                                 AttributePathIB =
2023-08-29 11:56:53.366760+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.366765+0800 CHIPTool[12736:3616510] [DataManagement]                                         Endpoint = 0x0,
2023-08-29 11:56:53.366770+0800 CHIPTool[12736:3616510] [DataManagement]                                         Cluster = 0x30,
2023-08-29 11:56:53.366776+0800 CHIPTool[12736:3616510] [DataManagement]                                         Attribute = 0x0000_0001,
2023-08-29 11:56:53.366780+0800 CHIPTool[12736:3616510] [DataManagement]                                 }
2023-08-29 11:56:53.366787+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.366791+0800 CHIPTool[12736:3616510] [DataManagement]                                 Data =
2023-08-29 11:56:53.366831+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.367468+0800 CHIPTool[12736:3616510] [DataManagement]                                         0x0 = 60,
2023-08-29 11:56:53.367513+0800 CHIPTool[12736:3616510] [DataManagement]                                         0x1 = 900,
2023-08-29 11:56:53.367519+0800 CHIPTool[12736:3616510] [DataManagement]                                 },
2023-08-29 11:56:53.367524+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.367533+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.367537+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.367550+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.367554+0800 CHIPTool[12736:3616510] [DataManagement]                 AttributeReportIB =
2023-08-29 11:56:53.367563+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.367567+0800 CHIPTool[12736:3616510] [DataManagement]                         AttributeDataIB =
2023-08-29 11:56:53.367572+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.367577+0800 CHIPTool[12736:3616510] [DataManagement]                                 DataVersion = 0x253cf2a1,
2023-08-29 11:56:53.367720+0800 CHIPTool[12736:3616510] [DataManagement]                                 AttributePathIB =
2023-08-29 11:56:53.367729+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.367735+0800 CHIPTool[12736:3616510] [DataManagement]                                         Endpoint = 0x0,
2023-08-29 11:56:53.367741+0800 CHIPTool[12736:3616510] [DataManagement]                                         Cluster = 0x30,
2023-08-29 11:56:53.367746+0800 CHIPTool[12736:3616510] [DataManagement]                                         Attribute = 0x0000_0000,
2023-08-29 11:56:53.367751+0800 CHIPTool[12736:3616510] [DataManagement]                                 }
2023-08-29 11:56:53.367757+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.367763+0800 CHIPTool[12736:3616510] [DataManagement]                                 Data = 0,
2023-08-29 11:56:53.367768+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.367775+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.367779+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.367791+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.367822+0800 CHIPTool[12736:3616510] [DataManagement]                 AttributeReportIB =
2023-08-29 11:56:53.367831+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.367836+0800 CHIPTool[12736:3616510] [DataManagement]                         AttributeDataIB =
2023-08-29 11:56:53.367841+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.367846+0800 CHIPTool[12736:3616510] [DataManagement]                                 DataVersion = 0xbd9f25af,
2023-08-29 11:56:53.367850+0800 CHIPTool[12736:3616510] [DataManagement]                                 AttributePathIB =
2023-08-29 11:56:53.367855+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.367860+0800 CHIPTool[12736:3616510] [DataManagement]                                         Endpoint = 0x0,
2023-08-29 11:56:53.367865+0800 CHIPTool[12736:3616510] [DataManagement]                                         Cluster = 0x31,
2023-08-29 11:56:53.367870+0800 CHIPTool[12736:3616510] [DataManagement]                                         Attribute = 0x0000_FFFC,
2023-08-29 11:56:53.367875+0800 CHIPTool[12736:3616510] [DataManagement]                                 }
2023-08-29 11:56:53.368094+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.368106+0800 CHIPTool[12736:3616510] [DataManagement]                                 Data = 1,
2023-08-29 11:56:53.368145+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.368155+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.368159+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.368168+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.368173+0800 CHIPTool[12736:3616510] [DataManagement]         ],
2023-08-29 11:56:53.368238+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.368246+0800 CHIPTool[12736:3616510] [DataManagement]         SuppressResponse = true,
2023-08-29 11:56:53.368251+0800 CHIPTool[12736:3616510] [DataManagement]         InteractionModelRevision = 1
2023-08-29 11:56:53.368256+0800 CHIPTool[12736:3616510] [DataManagement] }
2023-08-29 11:56:53.369076+0800 CHIPTool[12736:3616510] [Controller] ----- NetworkCommissioning Features: has WiFi. endpointid = 0
2023-08-29 11:56:53.369324+0800 CHIPTool[12736:3616510] [Default] DeviceControllerDelegate Read Commissioning Info. VendorId 65521 ProductId 32768
2023-08-29 11:56:53.369411+0800 CHIPTool[12736:3616510] [Controller] Successfully finished commissioning step 'ReadCommissioningInfo'
2023-08-29 11:56:53.369445+0800 CHIPTool[12736:3616510] [Controller] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe'
2023-08-29 11:56:53.369470+0800 CHIPTool[12736:3615048] readCommissioningInfo, vendorID:65521, productID:32768
2023-08-29 11:56:53.369478+0800 CHIPTool[12736:3616510] [Controller] Performing next commissioning step 'ArmFailSafe'
2023-08-29 11:56:53.369511+0800 CHIPTool[12736:3616510] [Controller] Arming failsafe (60 seconds)
2023-08-29 11:56:53.369587+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [AddingComm]
2023-08-29 11:56:53.369598+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [AddedComma]
2023-08-29 11:56:53.369768+0800 CHIPTool[12736:3616510] [ExchangeManager] <<< [E:3655i S:55878 M:18469460] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
2023-08-29 11:56:53.369818+0800 CHIPTool[12736:3616510] [Inet] (S) Sending msg 18469460 on secure session with LSID: 55878
2023-08-29 11:56:53.370069+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [CommandSen]
2023-08-29 11:56:53.572659+0800 CHIPTool[12736:3616510] [ExchangeManager] >>> [E:3655i S:55878 M:215249152] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
2023-08-29 11:56:53.572865+0800 CHIPTool[12736:3616510] [ExchangeManager] Found matching exchange: 3655i, Delegate: 0x15960c048
2023-08-29 11:56:53.572927+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [ResponseRe]
2023-08-29 11:56:53.572993+0800 CHIPTool[12736:3616510] [DataManagement] InvokeResponseMessage =
2023-08-29 11:56:53.573013+0800 CHIPTool[12736:3616510] [DataManagement] {
2023-08-29 11:56:53.573034+0800 CHIPTool[12736:3616510] [DataManagement]         suppressResponse = false,
2023-08-29 11:56:53.573050+0800 CHIPTool[12736:3616510] [DataManagement]         InvokeResponseIBs =
2023-08-29 11:56:53.573086+0800 CHIPTool[12736:3616510] [DataManagement]         [
2023-08-29 11:56:53.573102+0800 CHIPTool[12736:3616510] [DataManagement]                 InvokeResponseIB =
2023-08-29 11:56:53.573135+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.573150+0800 CHIPTool[12736:3616510] [DataManagement]                         CommandDataIB =
2023-08-29 11:56:53.573197+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.573213+0800 CHIPTool[12736:3616510] [DataManagement]                                 CommandPathIB =
2023-08-29 11:56:53.573231+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.573249+0800 CHIPTool[12736:3616510] [DataManagement]                                         EndpointId = 0x0,
2023-08-29 11:56:53.573311+0800 CHIPTool[12736:3616510] [DataManagement]                                         ClusterId = 0x30,
2023-08-29 11:56:53.573344+0800 CHIPTool[12736:3616510] [DataManagement]                                         CommandId = 0x1,
2023-08-29 11:56:53.573363+0800 CHIPTool[12736:3616510] [DataManagement]                                 },
2023-08-29 11:56:53.573388+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.573405+0800 CHIPTool[12736:3616510] [DataManagement]                                 CommandFields =
2023-08-29 11:56:53.573424+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.573445+0800 CHIPTool[12736:3616510] [DataManagement]                                         0x0 = 0,
2023-08-29 11:56:53.573467+0800 CHIPTool[12736:3616510] [DataManagement]                                         0x1 = "" (0 chars),
2023-08-29 11:56:53.573485+0800 CHIPTool[12736:3616510] [DataManagement]                                 },
2023-08-29 11:56:53.573768+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.573810+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.573837+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.573867+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.573881+0800 CHIPTool[12736:3616510] [DataManagement]         ],
2023-08-29 11:56:53.573918+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.573936+0800 CHIPTool[12736:3616510] [DataManagement]         InteractionModelRevision = 1
2023-08-29 11:56:53.573951+0800 CHIPTool[12736:3616510] [DataManagement] },
2023-08-29 11:56:53.574060+0800 CHIPTool[12736:3616510] [DataManagement] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
2023-08-29 11:56:53.574255+0800 CHIPTool[12736:3616510] [Controller] Received ArmFailSafe response errorCode=0
2023-08-29 11:56:53.574435+0800 CHIPTool[12736:3616510] [Controller] Successfully finished commissioning step 'ArmFailSafe'
2023-08-29 11:56:53.574540+0800 CHIPTool[12736:3616510] [Controller] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
2023-08-29 11:56:53.574640+0800 CHIPTool[12736:3616510] [Controller] Performing next commissioning step 'ConfigRegulatory'
2023-08-29 11:56:53.574713+0800 CHIPTool[12736:3616510] [Controller] Setting Regulatory Config
2023-08-29 11:56:53.574793+0800 CHIPTool[12736:3616510] [Controller] Device does not support configurable regulatory location
2023-08-29 11:56:53.574928+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [AddingComm]
2023-08-29 11:56:53.574959+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [AddedComma]
2023-08-29 11:56:53.575400+0800 CHIPTool[12736:3616510] [ExchangeManager] <<< [E:3656i S:55878 M:18469461] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
2023-08-29 11:56:53.575517+0800 CHIPTool[12736:3616510] [Inet] (S) Sending msg 18469461 on secure session with LSID: 55878
2023-08-29 11:56:53.576046+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [CommandSen]
2023-08-29 11:56:53.576141+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [AwaitingDe]
2023-08-29 11:56:53.717980+0800 CHIPTool[12736:3616510] [ExchangeManager] >>> [E:3656i S:55878 M:215249153] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
2023-08-29 11:56:53.718144+0800 CHIPTool[12736:3616510] [ExchangeManager] Found matching exchange: 3656i, Delegate: 0x159614758
2023-08-29 11:56:53.718215+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [ResponseRe]
2023-08-29 11:56:53.718290+0800 CHIPTool[12736:3616510] [DataManagement] InvokeResponseMessage =
2023-08-29 11:56:53.718330+0800 CHIPTool[12736:3616510] [DataManagement] {
2023-08-29 11:56:53.718363+0800 CHIPTool[12736:3616510] [DataManagement]         suppressResponse = false,
2023-08-29 11:56:53.718382+0800 CHIPTool[12736:3616510] [DataManagement]         InvokeResponseIBs =
2023-08-29 11:56:53.718413+0800 CHIPTool[12736:3616510] [DataManagement]         [
2023-08-29 11:56:53.718429+0800 CHIPTool[12736:3616510] [DataManagement]                 InvokeResponseIB =
2023-08-29 11:56:53.718460+0800 CHIPTool[12736:3616510] [DataManagement]                 {
2023-08-29 11:56:53.718478+0800 CHIPTool[12736:3616510] [DataManagement]                         CommandDataIB =
2023-08-29 11:56:53.718496+0800 CHIPTool[12736:3616510] [DataManagement]                         {
2023-08-29 11:56:53.718511+0800 CHIPTool[12736:3616510] [DataManagement]                                 CommandPathIB =
2023-08-29 11:56:53.718528+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.718545+0800 CHIPTool[12736:3616510] [DataManagement]                                         EndpointId = 0x0,
2023-08-29 11:56:53.718563+0800 CHIPTool[12736:3616510] [DataManagement]                                         ClusterId = 0x30,
2023-08-29 11:56:53.718580+0800 CHIPTool[12736:3616510] [DataManagement]                                         CommandId = 0x3,
2023-08-29 11:56:53.718594+0800 CHIPTool[12736:3616510] [DataManagement]                                 },
2023-08-29 11:56:53.718615+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.718631+0800 CHIPTool[12736:3616510] [DataManagement]                                 CommandFields =
2023-08-29 11:56:53.718648+0800 CHIPTool[12736:3616510] [DataManagement]                                 {
2023-08-29 11:56:53.718695+0800 CHIPTool[12736:3616510] [DataManagement]                                         0x0 = 0,
2023-08-29 11:56:53.718718+0800 CHIPTool[12736:3616510] [DataManagement]                                         0x1 = "" (0 chars),
2023-08-29 11:56:53.718736+0800 CHIPTool[12736:3616510] [DataManagement]                                 },
2023-08-29 11:56:53.718750+0800 CHIPTool[12736:3616510] [DataManagement]                         },
2023-08-29 11:56:53.718778+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.718792+0800 CHIPTool[12736:3616510] [DataManagement]                 },
2023-08-29 11:56:53.718823+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.718837+0800 CHIPTool[12736:3616510] [DataManagement]         ],
2023-08-29 11:56:53.718897+0800 CHIPTool[12736:3616510] [DataManagement] 
2023-08-29 11:56:53.718927+0800 CHIPTool[12736:3616510] [DataManagement]         InteractionModelRevision = 1
2023-08-29 11:56:53.718944+0800 CHIPTool[12736:3616510] [DataManagement] },
2023-08-29 11:56:53.719044+0800 CHIPTool[12736:3616510] [DataManagement] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
2023-08-29 11:56:53.719161+0800 CHIPTool[12736:3616510] [Controller] Received SetRegulatoryConfig response errorCode=0
2023-08-29 11:56:53.719250+0800 CHIPTool[12736:3616510] [Controller] Successfully finished commissioning step 'ConfigRegulatory'
2023-08-29 11:56:53.719326+0800 CHIPTool[12736:3616510] [Controller] Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest'
2023-08-29 11:56:53.719401+0800 CHIPTool[12736:3616510] [Controller] Performing next commissioning step 'SendPAICertificateRequest'
2023-08-29 11:56:53.719469+0800 CHIPTool[12736:3616510] [Controller] Sending request for PAI certificate
2023-08-29 11:56:53.719534+0800 CHIPTool[12736:3616510] [Controller] Sending Certificate Chain request to 0x15980e000 device
2023-08-29 11:56:53.719607+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [AddingComm]
2023-08-29 11:56:53.719631+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [AddedComma]
2023-08-29 11:56:53.720039+0800 CHIPTool[12736:3616510] [ExchangeManager] <<< [E:3657i S:55878 M:18469462] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
2023-08-29 11:56:53.720342+0800 CHIPTool[12736:3616510] [Inet] (S) Sending msg 18469462 on secure session with LSID: 55878
2023-08-29 11:56:53.720750+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [CommandSen]
2023-08-29 11:56:53.720827+0800 CHIPTool[12736:3616510] [DataManagement] ICR moving to [AwaitingDe]
2023-08-29 11:56:53.809684+0800 CHIPTool[12736:3616510] [Ble] HandleCharacteristicReceived failed, err = src/ble/BtpEngine.cpp:298: Ble Error 0x0000041E: Received invalid BLE transport protocol sequence number, rx_flags = 2
2023-08-29 11:56:53.809828+0800 CHIPTool[12736:3616510] [Ble] mAppState: 0x10998cf60
2023-08-29 11:56:53.809922+0800 CHIPTool[12736:3616510] [Ble] mRxFragmentSize: 244
2023-08-29 11:56:53.809997+0800 CHIPTool[12736:3616510] [Ble] mRxState: 3
2023-08-29 11:56:53.810070+0800 CHIPTool[12736:3616510] [Ble] mRxBuf: 0
2023-08-29 11:56:53.810139+0800 CHIPTool[12736:3616510] [Ble] mRxNextSeqNum: 8
2023-08-29 11:56:53.810202+0800 CHIPTool[12736:3616510] [Ble] mRxNewestUnackedSeqNum: 9
2023-08-29 11:56:53.810264+0800 CHIPTool[12736:3616510] [Ble] mRxOldestUnackedSeqNum: 8
2023-08-29 11:56:53.810326+0800 CHIPTool[12736:3616510] [Ble] mRxCharCount: 8
2023-08-29 11:56:53.810469+0800 CHIPTool[12736:3616510] [Ble] mRxPacketCount: 6
2023-08-29 11:56:53.810730+0800 CHIPTool[12736:3616510] [Ble] mTxFragmentSize: 244
2023-08-29 11:56:53.810980+0800 CHIPTool[12736:3616510] [Ble] mTxState: 0
2023-08-29 11:56:53.811123+0800 CHIPTool[12736:3616510] [Ble] mTxBuf: 0
2023-08-29 11:56:53.811350+0800 CHIPTool[12736:3616510] [Ble] mTxNextSeqNum: 8
2023-08-29 11:56:53.811604+0800 CHIPTool[12736:3616510] [Ble] mTxNewestUnackedSeqNum: 7
2023-08-29 11:56:53.811828+0800 CHIPTool[12736:3616510] [Ble] mTxOldestUnackedSeqNum: 7
2023-08-29 11:56:53.811993+0800 CHIPTool[12736:3616510] [Ble] mTxCharCount: 7
2023-08-29 11:56:53.812172+0800 CHIPTool[12736:3616510] [Ble] mTxPacketCount: 7
2023-08-29 11:56:53.812409+0800 CHIPTool[12736:3616510] [Inet] Clearing BLE pending packets.
2023-08-29 11:56:53.812735+0800 CHIPTool[12736:3616510] [Ble] BLEEndPoint rcv failed, err = src/ble/BtpEngine.cpp:298: Ble Error 0x0000041E: Received invalid BLE transport protocol sequence number
2023-08-29 11:56:53.867447+0800 CHIPTool[12736:3615048] [Ble] Auto-closing end point's BLE connection.