ubuntu@ubuntu:~/apps$ ./chip-tool pairing ble-thread 0x10 hex:0e08000000000001000035060004001fffe00708fd9ccbc54a0f2fd00410a98dbf7fc6d57ab84fe27dd7d3916dc20c0402a0f7f8000300000f0102123402081111111122222222030444454d4f051000112233445566778899aabbccddeeff 20202021 3840 [1738802740.388] [9484:9484] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1738802740.389] [9484:9484] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1738802740.395] [9484:9484] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1738802740.395] [9484:9484] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1738802740.395] [9484:9484] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1738802740.408] [9484:9484] [DL] Wrote settings to /tmp/chip_counters.ini [1738802740.408] [9484:9484] [DL] NVS set: chip-counters/reboot-count = 13 (0xD) [1738802740.409] [9484:9484] [DL] Got Ethernet interface: eth0 [1738802740.410] [9484:9484] [DL] Found the primary Ethernet interface:eth0 [1738802740.411] [9484:9484] [DL] Got WiFi interface: wlan0 [1738802740.411] [9484:9484] [DL] Failed to reset WiFi statistic counts [1738802740.412] [9484:9484] [IN] UDP::Init bind&listen port=0 [1738802740.412] [9484:9484] [IN] UDP::Init bound to port=38506 [1738802740.412] [9484:9484] [IN] BLEBase::Init - setting/overriding transport [1738802740.412] [9484:9484] [IN] TransportMgr initialized [1738802740.412] [9484:9484] [FP] Initializing FabricTable from persistent storage [1738802740.413] [9484:9484] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802740.416] [9484:9484] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xCC0161AC8CF43C47, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1738802740.420] [9484:9484] [ZCL] Using ZAP configuration... [1738802740.425] [9484:9484] [DL] Avahi client registered [1738802740.425] [9484:9484] [IM] WARNING ┌──────────────────────────────────────────────────── [1738802740.425] [9484:9484] [IM] WARNING │ Interaction Model Engine running in 'Checked' mode. [1738802740.425] [9484:9484] [IM] WARNING │ This executes BOTH ember and data-model code paths. [1738802740.425] [9484:9484] [IM] WARNING │ which is inefficient and consumes more flash space. [1738802740.425] [9484:9484] [IM] WARNING │ This should be done for testing only. [1738802740.425] [9484:9484] [IM] WARNING └──────────────────────────────────────────────────── [1738802740.425] [9484:9484] [CTL] System State Initialized... [1738802740.426] [9484:9484] [CTL] Setting attestation nonce to random value [1738802740.426] [9484:9484] [CTL] Setting CSR nonce to random value [1738802740.426] [9484:9484] [IN] UDP::Init bind&listen port=5550 [1738802740.426] [9484:9484] [IN] UDP::Init bound to port=5550 [1738802740.426] [9484:9484] [IN] TransportMgr initialized [1738802740.426] [9484:9486] [DL] CHIP task running [1738802740.427] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 32786 [1738802740.427] [9484:9486] [CTL] Setting attestation nonce to random value [1738802740.427] [9484:9486] [CTL] Setting CSR nonce to random value [1738802740.428] [9484:9486] [CTL] Generating NOC [1738802740.429] [9484:9486] [FP] Validating NOC chain [1738802740.430] [9484:9486] [FP] NOC chain validation successful [1738802740.431] [9484:9486] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1738802740.431] [9484:9486] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802740.431] [9484:9486] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1738802740.431] [9484:9486] [TS] Retaining current Last Known Good Time [1738802740.435] [9484:9486] [FP] Metadata for Fabric 0x1 persisted to storage. [1738802740.438] [9484:9486] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1738802740.440] [9484:9486] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: CC0161AC8CF43C47) [1738802740.440] [9484:9486] [IN] UDP::Init bind&listen port=5550 [1738802740.440] [9484:9486] [IN] UDP::Init bound to port=5550 [1738802740.440] [9484:9486] [IN] TransportMgr initialized [1738802740.455] [9484:9486] [CTL] Setting thread operational dataset from parameters [1738802740.455] [9484:9486] [CTL] Setting attestation nonce to random value [1738802740.455] [9484:9486] [CTL] Setting CSR nonce to random value [1738802740.455] [9484:9486] [CTL] Commission called for node ID 0x0000000000000010 [1738802740.468] [9484:9485] [BLE] BLE removing known devices [1738802740.469] [9484:9485] [BLE] BLE initiating scan [1738802740.472] [9484:9486] [BLE] ChipDeviceScanner has started scanning! [1738802740.479] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802740.493] [9484:9485] [BLE] Device 6C:0E:16:BA:E6:AB does not look like a CHIP device. [1738802740.493] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802740.506] [9484:9485] [BLE] Device 49:40:B6:1F:D1:A1 does not look like a CHIP device. [1738802740.507] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802740.510] [9484:9485] [BLE] Device 56:10:24:A6:52:62 does not look like a CHIP device. [1738802740.521] [9484:9485] [BLE] Device 1F:95:C0:DE:8E:B5 does not look like a CHIP device. [1738802740.524] [9484:9485] [BLE] Device 05:C4:50:28:48:C7 does not look like a CHIP device. [1738802740.528] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802740.533] [9484:9485] [BLE] Device 2C:67:6F:AF:99:39 does not look like a CHIP device. [1738802740.536] [9484:9485] [BLE] Device 10:60:FD:0B:5C:34 does not look like a CHIP device. [1738802740.539] [9484:9485] [BLE] Device 30:D7:22:DD:20:A7 does not look like a CHIP device. [1738802740.541] [9484:9485] [BLE] Device 24:FA:AA:D6:3A:70 does not look like a CHIP device. [1738802740.546] [9484:9485] [BLE] Device 08:C9:AC:60:1C:39 does not look like a CHIP device. [1738802740.547] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802740.550] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802740.552] [9484:9485] [BLE] Device 11:DF:78:41:7F:8E does not look like a CHIP device. [1738802740.553] [9484:9485] [BLE] Device 76:7F:49:75:80:DB does not look like a CHIP device. [1738802740.559] [9484:9485] [BLE] Device 10:75:D0:3A:88:EB does not look like a CHIP device. [1738802740.560] [9484:9485] [BLE] Device 6A:04:5A:7C:6D:4D does not look like a CHIP device. [1738802740.564] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802740.567] [9484:9485] [BLE] Device 7C:A8:E8:0A:F2:D8 does not look like a CHIP device. [1738802740.572] [9484:9485] [BLE] Device 67:C1:43:DD:AA:B3 does not look like a CHIP device. [1738802740.573] [9484:9485] [BLE] Device 3C:BF:75:CA:0C:C3 does not look like a CHIP device. [1738802740.575] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802740.576] [9484:9485] [BLE] Device 04:68:97:22:02:7E does not look like a CHIP device. [1738802740.580] [9484:9485] [BLE] Device 0E:8C:B6:A3:34:DB does not look like a CHIP device. [1738802740.583] [9484:9485] [BLE] Device 6A:52:7C:3E:F2:A9 does not look like a CHIP device. [1738802740.594] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802740.599] [9484:9485] [BLE] Device 18:47:89:DD:50:7D does not look like a CHIP device. [1738802740.600] [9484:9485] [BLE] Device 24:AE:32:B4:E0:6A does not look like a CHIP device. [1738802740.608] [9484:9485] [BLE] Device 18:EE:88:07:3B:A8 does not look like a CHIP device. [1738802740.623] [9484:9485] [BLE] Device 68:A8:01:E1:6D:DD does not look like a CHIP device. [1738802740.624] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802740.635] [9484:9485] [BLE] Device 10:60:FD:0B:5C:34 does not look like a CHIP device. [1738802740.638] [9484:9485] [BLE] Device 60:AE:43:BA:74:54 does not look like a CHIP device. [1738802740.643] [9484:9485] [BLE] Device 3F:EF:EB:D9:7F:8D does not look like a CHIP device. [1738802740.649] [9484:9485] [BLE] Device 42:74:A6:5D:B1:51 does not look like a CHIP device. [1738802740.650] [9484:9485] [BLE] Device 5B:B1:67:7E:9C:BD does not look like a CHIP device. [1738802740.661] [9484:9485] [BLE] Device 77:4B:4E:55:AE:53 does not look like a CHIP device. [1738802740.662] [9484:9485] [BLE] Device 2E:B3:51:56:DA:63 does not look like a CHIP device. [1738802740.668] [9484:9485] [BLE] Device 05:73:60:26:BA:0E does not look like a CHIP device. [1738802740.672] [9484:9485] [BLE] Device 24:F6:C8:5A:1B:C2 does not look like a CHIP device. [1738802740.679] [9484:9485] [BLE] Device 5D:2A:77:6F:8E:8E does not look like a CHIP device. [1738802740.680] [9484:9485] [BLE] Device 12:36:D5:AF:F4:1F does not look like a CHIP device. [1738802740.688] [9484:9485] [BLE] Device 68:51:29:20:0D:BD does not look like a CHIP device. [1738802740.688] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802740.692] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802740.694] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802740.699] [9484:9485] [BLE] Device 6F:82:9C:62:DF:D4 does not look like a CHIP device. [1738802740.711] [9484:9485] [BLE] Device 18:EE:88:07:3B:A8 does not look like a CHIP device. [1738802740.716] [9484:9485] [BLE] Device 4C:48:78:61:4D:D0 does not look like a CHIP device. [1738802740.725] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802740.726] [9484:9485] [BLE] Device 42:9B:AC:DC:85:14 does not look like a CHIP device. [1738802740.730] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802740.733] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802740.737] [9484:9485] [BLE] Device 52:FF:AE:07:DC:FE does not look like a CHIP device. [1738802740.739] [9484:9485] [BLE] Device 2C:67:6F:AF:99:39 does not look like a CHIP device. [1738802740.743] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802740.745] [9484:9485] [BLE] Device 3F:EF:EB:D9:7F:8D does not look like a CHIP device. [1738802740.749] [9484:9485] [BLE] Device 3A:57:0E:83:AD:5B does not look like a CHIP device. [1738802740.751] [9484:9485] [BLE] Device 1A:C4:33:14:03:D1 does not look like a CHIP device. [1738802740.753] [9484:9485] [BLE] Device 08:C9:AC:60:1C:39 does not look like a CHIP device. [1738802740.755] [9484:9485] [BLE] Device 12:87:F5:21:7B:EC does not look like a CHIP device. [1738802740.756] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802740.758] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802740.760] [9484:9485] [BLE] Device 11:DF:78:41:7F:8E does not look like a CHIP device. [1738802740.771] [9484:9485] [BLE] Device 45:07:20:30:99:C0 does not look like a CHIP device. [1738802740.771] [9484:9485] [BLE] Device 49:40:B6:1F:D1:A1 does not look like a CHIP device. [1738802740.776] [9484:9485] [BLE] Device 75:5A:0C:64:8B:DB does not look like a CHIP device. [1738802740.781] [9484:9485] [BLE] Device 12:36:D5:AF:F4:1F does not look like a CHIP device. [1738802740.786] [9484:9485] [BLE] Device 56:10:24:A6:52:62 does not look like a CHIP device. [1738802740.787] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802740.790] [9484:9485] [BLE] Device 3C:A8:86:C4:F6:C0 does not look like a CHIP device. [1738802740.800] [9484:9485] [BLE] Device 64:20:3E:23:24:1A does not look like a CHIP device. [1738802740.803] [9484:9485] [BLE] Device 67:09:33:3A:60:B0 does not look like a CHIP device. [1738802740.804] [9484:9485] [BLE] Device 22:FF:D2:9F:CF:B9 does not look like a CHIP device. [1738802740.810] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802740.813] [9484:9485] [BLE] Device 0A:BC:9E:3C:1F:54 does not look like a CHIP device. [1738802740.817] [9484:9485] [BLE] Device 43:A8:96:03:31:E9 does not look like a CHIP device. [1738802740.819] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802740.821] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802740.823] [9484:9485] [BLE] Device 76:7F:49:75:80:DB does not look like a CHIP device. [1738802740.835] [9484:9485] [BLE] Device 79:07:59:EE:EF:9C does not look like a CHIP device. [1738802740.835] [9484:9485] [BLE] Device 1F:95:C0:DE:8E:B5 does not look like a CHIP device. [1738802740.836] [9484:9485] [BLE] Device 38:4B:87:73:7E:A1 does not look like a CHIP device. [1738802740.838] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802740.844] [9484:9485] [BLE] Device 05:C4:50:28:48:C7 does not look like a CHIP device. [1738802740.846] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802740.852] [9484:9485] [BLE] Device 24:FA:AA:D6:3A:70 does not look like a CHIP device. [1738802740.854] [9484:9485] [BLE] Device 3A:57:0E:83:AD:5B does not look like a CHIP device. [1738802740.861] [9484:9485] [BLE] Device 4E:66:ED:7D:11:43 does not look like a CHIP device. [1738802740.862] [9484:9485] [BLE] Device 08:C9:AC:60:1C:39 does not look like a CHIP device. [1738802740.862] [9484:9485] [BLE] Device 11:DF:78:41:7F:8E does not look like a CHIP device. [1738802740.864] [9484:9485] [BLE] Device 29:BA:6C:A0:3A:80 does not look like a CHIP device. [1738802740.865] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802740.869] [9484:9485] [BLE] Device 2E:B3:51:56:DA:63 does not look like a CHIP device. [1738802740.874] [9484:9485] [BLE] Device 26:E6:C6:5D:62:F0 does not look like a CHIP device. [1738802740.886] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802740.899] [9484:9485] [BLE] Device DD:7F:BC:42:BF:2B does not look like a CHIP device. [1738802740.907] [9484:9485] [BLE] Device 00:F3:11:1E:8E:78 does not look like a CHIP device. [1738802740.907] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802740.909] [9484:9485] [BLE] Device 60:AE:43:BA:74:54 does not look like a CHIP device. [1738802740.914] [9484:9485] [BLE] Device 49:6C:42:96:76:AC does not look like a CHIP device. [1738802740.916] [9484:9485] [BLE] Device 24:AE:32:B4:E0:6A does not look like a CHIP device. [1738802740.919] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802740.921] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802740.923] [9484:9485] [BLE] Device 4C:48:78:61:4D:D0 does not look like a CHIP device. [1738802740.931] [9484:9485] [BLE] Device 77:4B:4E:55:AE:53 does not look like a CHIP device. [1738802740.932] [9484:9485] [BLE] Device 32:F9:9B:24:C3:19 does not look like a CHIP device. [1738802740.937] [9484:9485] [BLE] Device 40:1B:89:83:FF:57 does not look like a CHIP device. [1738802740.937] [9484:9485] [BLE] Device 1F:95:C0:DE:8E:B5 does not look like a CHIP device. [1738802740.940] [9484:9485] [BLE] Device 37:2D:68:FF:A5:8A does not look like a CHIP device. [1738802740.941] [9484:9485] [BLE] Device 38:4B:87:73:7E:A1 does not look like a CHIP device. [1738802740.943] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802740.945] [9484:9485] [BLE] Device 10:60:FD:0B:5C:34 does not look like a CHIP device. [1738802740.950] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802740.957] [9484:9485] [BLE] Device 20:1C:3A:1D:63:99 does not look like a CHIP device. [1738802740.959] [9484:9485] [BLE] Device 3F:EF:EB:D9:7F:8D does not look like a CHIP device. [1738802740.962] [9484:9485] [BLE] Device 08:C9:AC:60:1C:39 does not look like a CHIP device. [1738802740.966] [9484:9485] [BLE] Device 29:BA:6C:A0:3A:80 does not look like a CHIP device. [1738802740.968] [9484:9485] [BLE] Device 11:DF:78:41:7F:8E does not look like a CHIP device. [1738802740.968] [9484:9485] [BLE] Device 2E:B3:51:56:DA:63 does not look like a CHIP device. [1738802740.974] [9484:9485] [BLE] Device 05:73:60:26:BA:0E does not look like a CHIP device. [1738802740.981] [9484:9485] [BLE] Device 6F:82:9C:62:DF:D4 does not look like a CHIP device. [1738802740.982] [9484:9485] [BLE] Device 16:5C:D9:29:61:3A does not look like a CHIP device. [1738802740.996] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802741.004] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.010] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802741.017] [9484:9485] [BLE] Device 73:53:01:5F:EC:93 does not look like a CHIP device. [1738802741.018] [9484:9485] [BLE] Device 78:B6:E4:B7:AA:86 does not look like a CHIP device. [1738802741.018] [9484:9485] [BLE] Device 2B:80:D9:F6:F9:46 does not look like a CHIP device. [1738802741.022] [9484:9485] [BLE] Device 24:AE:32:B4:E0:6A does not look like a CHIP device. [1738802741.029] [9484:9485] [BLE] Device 3A:96:3B:29:72:C5 does not look like a CHIP device. [1738802741.032] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802741.045] [9484:9485] [BLE] Device 6C:0E:16:BA:E6:AB does not look like a CHIP device. [1738802741.051] [9484:9485] [BLE] Device 5C:3A:D5:C7:4F:1E does not look like a CHIP device. [1738802741.051] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802741.051] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802741.053] [9484:9485] [BLE] Device 64:20:3E:23:24:1A does not look like a CHIP device. [1738802741.055] [9484:9485] [BLE] Device 05:C4:50:28:48:C7 does not look like a CHIP device. [1738802741.058] [9484:9485] [BLE] Device 56:10:24:A6:52:62 does not look like a CHIP device. [1738802741.063] [9484:9485] [BLE] Device 1A:C4:33:14:03:D1 does not look like a CHIP device. [1738802741.068] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802741.068] [9484:9485] [BLE] Device 29:BA:6C:A0:3A:80 does not look like a CHIP device. [1738802741.075] [9484:9485] [BLE] Device 67:09:33:3A:60:B0 does not look like a CHIP device. [1738802741.076] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.079] [9484:9485] [BLE] Device 05:73:60:26:BA:0E does not look like a CHIP device. [1738802741.082] [9484:9485] [BLE] Device 24:F6:C8:5A:1B:C2 does not look like a CHIP device. [1738802741.086] [9484:9485] [BLE] Device 7C:8D:AD:10:3E:A4 does not look like a CHIP device. [1738802741.087] [9484:9485] [BLE] Device 16:5C:D9:29:61:3A does not look like a CHIP device. [1738802741.093] [9484:9485] [BLE] Device 4C:48:78:61:4D:D0 does not look like a CHIP device. [1738802741.097] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802741.107] [9484:9485] [BLE] Device C8:6B:80:86:5D:1A does not look like a CHIP device. [1738802741.107] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802741.109] [9484:9485] [BLE] Device 22:FF:D2:9F:CF:B9 does not look like a CHIP device. [1738802741.112] [9484:9485] [BLE] Device F5:D7:43:24:5C:20 does not look like a CHIP device. [1738802741.113] [9484:9485] [BLE] Device 7C:A8:E8:0A:F2:D8 does not look like a CHIP device. [1738802741.120] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.121] [9484:9485] [BLE] Device 67:C1:43:DD:AA:B3 does not look like a CHIP device. [1738802741.126] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802741.128] [9484:9485] [BLE] Device 24:AE:32:B4:E0:6A does not look like a CHIP device. [1738802741.133] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802741.137] [9484:9485] [BLE] Device 4E:66:ED:7D:11:43 does not look like a CHIP device. [1738802741.145] [9484:9485] [BLE] Device DA:4D:3F:BB:E9:EF does not look like a CHIP device. [1738802741.151] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802741.151] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.155] [9484:9485] [BLE] Device ED:55:81:CA:43:F9 does not look like a CHIP device. [1738802741.155] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802741.159] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802741.161] [9484:9485] [BLE] Device 05:C4:50:28:48:C7 does not look like a CHIP device. [1738802741.169] [9484:9485] [BLE] Device 00:80:98:58:0A:0C does not look like a CHIP device. [1738802741.172] [9484:9485] [BLE] Device 68:A8:01:E1:6D:DD does not look like a CHIP device. [1738802741.172] [9484:9485] [BLE] Device 29:BA:6C:A0:3A:80 does not look like a CHIP device. [1738802741.175] [9484:9485] [BLE] Device 2E:B3:51:56:DA:63 does not look like a CHIP device. [1738802741.177] [9484:9485] [BLE] Device 11:DF:78:41:7F:8E does not look like a CHIP device. [1738802741.180] [9484:9485] [BLE] Device 60:AE:43:BA:74:54 does not look like a CHIP device. [1738802741.181] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802741.186] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802741.188] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.193] [9484:9485] [BLE] Device 3C:BF:75:CA:0C:C3 does not look like a CHIP device. [1738802741.197] [9484:9485] [BLE] Device 7E:9B:85:61:C2:17 does not look like a CHIP device. [1738802741.211] [9484:9485] [BLE] Device 00:4D:98:E0:44:67 does not look like a CHIP device. [1738802741.214] [9484:9485] [BLE] Device 22:FF:D2:9F:CF:B9 does not look like a CHIP device. [1738802741.216] [9484:9485] [BLE] Device CE:8B:79:E0:CD:00 does not look like a CHIP device. [1738802741.223] [9484:9485] [BLE] Device 03:DD:63:A7:22:E6 does not look like a CHIP device. [1738802741.225] [9484:9485] [BLE] Device 20:80:19:89:7B:B2 does not look like a CHIP device. [1738802741.227] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802741.237] [9484:9485] [BLE] Device 68:51:29:20:0D:BD does not look like a CHIP device. [1738802741.238] [9484:9485] [BLE] Device 18:EE:88:07:3B:A8 does not look like a CHIP device. [1738802741.240] [9484:9485] [BLE] Device 17:5F:DA:96:EA:14 does not look like a CHIP device. [1738802741.241] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802741.251] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.257] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802741.259] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802741.266] [9484:9485] [BLE] Device 42:9B:AC:DC:85:14 does not look like a CHIP device. [1738802741.267] [9484:9485] [BLE] Device 06:C2:1C:2B:5B:DC does not look like a CHIP device. [1738802741.278] [9484:9485] [BLE] Device 12:87:F5:21:7B:EC does not look like a CHIP device. [1738802741.280] [9484:9485] [BLE] Device 29:BA:6C:A0:3A:80 does not look like a CHIP device. [1738802741.286] [9484:9485] [BLE] Device 52:87:CC:0E:12:FF does not look like a CHIP device. [1738802741.286] [9484:9485] [BLE] Device 11:DF:78:41:7F:8E does not look like a CHIP device. [1738802741.289] [9484:9485] [BLE] Device 52:FF:AE:07:DC:FE does not look like a CHIP device. [1738802741.292] [9484:9485] [BLE] Device 7A:54:50:C9:1A:37 does not look like a CHIP device. [1738802741.292] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802741.294] [9484:9485] [BLE] Device 26:E6:C6:5D:62:F0 does not look like a CHIP device. [1738802741.299] [9484:9485] [BLE] Device 3C:BF:75:CA:0C:C3 does not look like a CHIP device. [1738802741.305] [9484:9485] [BLE] Device CB:D0:D7:C4:BA:D1 does not look like a CHIP device. [1738802741.307] [9484:9485] [BLE] Device 45:07:20:30:99:C0 does not look like a CHIP device. [1738802741.310] [9484:9485] [BLE] Device 04:68:97:22:02:7E does not look like a CHIP device. [1738802741.314] [9484:9485] [BLE] Device 64:20:3E:23:24:1A does not look like a CHIP device. [1738802741.318] [9484:9485] [BLE] Device 6C:0E:16:BA:E6:AB does not look like a CHIP device. [1738802741.319] [9484:9485] [BLE] Device 22:FF:D2:9F:CF:B9 does not look like a CHIP device. [1738802741.321] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802741.324] [9484:9485] [BLE] Device 00:F3:11:1E:8E:78 does not look like a CHIP device. [1738802741.325] [9484:9485] [BLE] Device 2B:80:D9:F6:F9:46 does not look like a CHIP device. [1738802741.331] [9484:9485] [BLE] Device 65:34:57:8D:96:4E does not look like a CHIP device. [1738802741.334] [9484:9485] [BLE] Device 75:5A:0C:64:8B:DB does not look like a CHIP device. [1738802741.336] [9484:9485] [BLE] Device 2A:1B:43:05:B0:BA does not look like a CHIP device. [1738802741.336] [9484:9485] [BLE] Device 24:AE:32:B4:E0:6A does not look like a CHIP device. [1738802741.341] [9484:9485] [BLE] Device 0A:BC:9E:3C:1F:54 does not look like a CHIP device. [1738802741.353] [9484:9485] [BLE] Device 67:09:33:3A:60:B0 does not look like a CHIP device. [1738802741.353] [9484:9485] [BLE] Device 1F:95:C0:DE:8E:B5 does not look like a CHIP device. [1738802741.361] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802741.364] [9484:9485] [BLE] Device 8C:52:19:BA:62:AB does not look like a CHIP device. [1738802741.368] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802741.371] [9484:9485] [BLE] Device 20:1C:3A:1D:63:99 does not look like a CHIP device. [1738802741.375] [9484:9485] [BLE] Device 42:EB:71:62:6E:64 does not look like a CHIP device. [1738802741.378] [9484:9485] [BLE] Device 08:C9:AC:60:1C:39 does not look like a CHIP device. [1738802741.380] [9484:9485] [BLE] Device 3A:57:0E:83:AD:5B does not look like a CHIP device. [1738802741.383] [9484:9485] [BLE] Device 2E:B3:51:56:DA:63 does not look like a CHIP device. [1738802741.387] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.390] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802741.395] [9484:9485] [BLE] Device 7A:54:50:C9:1A:37 does not look like a CHIP device. [1738802741.396] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802741.398] [9484:9485] [BLE] Device 67:C1:43:DD:AA:B3 does not look like a CHIP device. [1738802741.410] [9484:9485] [BLE] Device 45:55:B4:34:9B:2A does not look like a CHIP device. [1738802741.410] [9484:9485] [BLE] Device 3C:BF:75:CA:0C:C3 does not look like a CHIP device. [1738802741.413] [9484:9485] [BLE] Device 14:8D:9B:45:F5:57 does not look like a CHIP device. [1738802741.414] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802741.416] [9484:9485] [BLE] Device 00:4D:98:E0:44:67 does not look like a CHIP device. [1738802741.423] [9484:9485] [BLE] Device 22:FF:D2:9F:CF:B9 does not look like a CHIP device. [1738802741.425] [9484:9485] [BLE] Device 03:DD:63:A7:22:E6 does not look like a CHIP device. [1738802741.429] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802741.431] [9484:9485] [BLE] Device 58:D5:7E:50:3D:6E does not look like a CHIP device. [1738802741.437] [9484:9485] [BLE] Device 2A:1B:43:05:B0:BA does not look like a CHIP device. [1738802741.439] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802741.443] [9484:9485] [BLE] Device 17:5F:DA:96:EA:14 does not look like a CHIP device. [1738802741.445] [9484:9485] [BLE] Device 68:A8:01:E1:6D:DD does not look like a CHIP device. [1738802741.448] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802741.450] [9484:9485] [BLE] Device 3C:A8:86:C4:F6:C0 does not look like a CHIP device. [1738802741.456] [9484:9485] [BLE] Device B0:E9:FE:55:5F:E3 does not look like a CHIP device. [1738802741.459] [9484:9485] [BLE] Device A4:59:D3:60:00:00 does not look like a CHIP device. [1738802741.462] [9484:9485] [BLE] Device 1F:95:C0:DE:8E:B5 does not look like a CHIP device. [1738802741.466] [9484:9485] [BLE] Device 4C:48:78:61:4D:D0 does not look like a CHIP device. [1738802741.467] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802741.468] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802741.473] [9484:9485] [BLE] Device 06:C2:1C:2B:5B:DC does not look like a CHIP device. [1738802741.477] [9484:9485] [BLE] Device 7E:9B:85:61:C2:17 does not look like a CHIP device. [1738802741.478] [9484:9485] [BLE] Device 10:60:FD:0B:5C:34 does not look like a CHIP device. [1738802741.483] [9484:9485] [BLE] Device 3F:EF:EB:D9:7F:8D does not look like a CHIP device. [1738802741.486] [9484:9485] [BLE] Device 30:D7:22:DD:20:A7 does not look like a CHIP device. [1738802741.494] [9484:9485] [BLE] Device 00:80:98:58:0A:04 does not look like a CHIP device. [1738802741.494] [9484:9485] [BLE] Device 42:74:A6:5D:B1:51 does not look like a CHIP device. [1738802741.495] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.497] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802741.499] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802741.508] [9484:9485] [BLE] Device 3C:BF:75:CA:0C:C3 does not look like a CHIP device. [1738802741.511] [9484:9485] [BLE] Device 24:F6:C8:5A:1B:C2 does not look like a CHIP device. [1738802741.515] [9484:9485] [BLE] Device 14:8D:9B:45:F5:57 does not look like a CHIP device. [1738802741.524] [9484:9485] [BLE] Device 73:53:01:5F:EC:93 does not look like a CHIP device. [1738802741.524] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802741.526] [9484:9485] [BLE] Device 22:FF:D2:9F:CF:B9 does not look like a CHIP device. [1738802741.535] [9484:9485] [BLE] Device 2B:80:D9:F6:F9:46 does not look like a CHIP device. [1738802741.544] [9484:9485] [BLE] Device 7F:10:8D:AF:0D:A2 does not look like a CHIP device. [1738802741.546] [9484:9485] [BLE] Device 17:5F:DA:96:EA:14 does not look like a CHIP device. [1738802741.548] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802741.550] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802741.559] [9484:9485] [BLE] Device 32:F9:9B:24:C3:19 does not look like a CHIP device. [1738802741.565] [9484:9485] [BLE] Device 32:A3:A6:C6:44:FA does not look like a CHIP device. [1738802741.565] [9484:9485] [BLE] Device 37:2D:68:FF:A5:8A does not look like a CHIP device. [1738802741.570] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802741.577] [9484:9485] [BLE] Device 38:4B:87:73:7E:A1 does not look like a CHIP device. [1738802741.581] [9484:9485] [BLE] Device 10:60:FD:0B:5C:34 does not look like a CHIP device. [1738802741.583] [9484:9485] [BLE] Device 24:FA:AA:D6:3A:70 does not look like a CHIP device. [1738802741.586] [9484:9485] [BLE] Device 45:07:20:30:99:C0 does not look like a CHIP device. [1738802741.589] [9484:9485] [BLE] Device 2C:67:6F:AF:99:39 does not look like a CHIP device. [1738802741.595] [9484:9485] [BLE] Device 49:40:B6:1F:D1:A1 does not look like a CHIP device. [1738802741.597] [9484:9485] [BLE] Device 5C:3A:D5:C7:4F:1E does not look like a CHIP device. [1738802741.598] [9484:9485] [BLE] Device 2E:B3:51:56:DA:63 does not look like a CHIP device. [1738802741.602] [9484:9485] [BLE] Device 12:87:F5:21:7B:EC does not look like a CHIP device. [1738802741.609] [9484:9485] [BLE] Device 16:5C:D9:29:61:3A does not look like a CHIP device. [1738802741.610] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.621] [9484:9485] [BLE] Device 14:8D:9B:45:F5:57 does not look like a CHIP device. [1738802741.624] [9484:9485] [BLE] Device 00:4D:98:E0:44:67 does not look like a CHIP device. [1738802741.625] [9484:9485] [BLE] Device 67:09:33:3A:60:B0 does not look like a CHIP device. [1738802741.630] [9484:9485] [BLE] Device 0E:8C:B6:A3:34:DB does not look like a CHIP device. [1738802741.632] [9484:9485] [BLE] Device 03:DD:63:A7:22:E6 does not look like a CHIP device. [1738802741.651] [9484:9485] [BLE] Device 17:5F:DA:96:EA:14 does not look like a CHIP device. [1738802741.655] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802741.657] [9484:9485] [BLE] Device 79:07:59:EE:EF:9C does not look like a CHIP device. [1738802741.662] [9484:9485] [BLE] Device 78:B6:E4:B7:AA:86 does not look like a CHIP device. [1738802741.673] [9484:9485] [BLE] Device 7C:A8:E8:0A:F2:D8 does not look like a CHIP device. [1738802741.674] [9484:9485] [BLE] Device 3C:A8:86:C4:F6:C0 does not look like a CHIP device. [1738802741.676] [9484:9485] [BLE] Device 45:55:B4:34:9B:2A does not look like a CHIP device. [1738802741.678] [9484:9485] [BLE] Device 1F:95:C0:DE:8E:B5 does not look like a CHIP device. [1738802741.679] [9484:9485] [BLE] Device 38:4B:87:73:7E:A1 does not look like a CHIP device. [1738802741.687] [9484:9485] [BLE] Device 06:C2:1C:2B:5B:DC does not look like a CHIP device. [1738802741.694] [9484:9485] [BLE] Device 29:52:E3:C3:DB:14 does not look like a CHIP device. [1738802741.694] [9484:9485] [BLE] Device 1A:C4:33:14:03:D1 does not look like a CHIP device. [1738802741.695] [9484:9485] [BLE] Device 30:D7:22:DD:20:A7 does not look like a CHIP device. [1738802741.699] [9484:9485] [BLE] Device 47:84:92:3C:45:56 does not look like a CHIP device. [1738802741.705] [9484:9485] [BLE] Device 42:74:A6:5D:B1:51 does not look like a CHIP device. [1738802741.711] [9484:9485] [BLE] Device 11:DF:78:41:7F:8E does not look like a CHIP device. [1738802741.712] [9484:9485] [BLE] Device 05:73:60:26:BA:0E does not look like a CHIP device. [1738802741.718] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.725] [9484:9485] [BLE] Device F9:E9:EB:62:6F:90 does not look like a CHIP device. [1738802741.728] [9484:9485] [BLE] Device 14:8D:9B:45:F5:57 does not look like a CHIP device. [1738802741.733] [9484:9485] [BLE] Device C7:26:9B:CB:D3:65 does not look like a CHIP device. [1738802741.738] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802741.740] [9484:9485] [BLE] Device 04:68:97:22:02:7E does not look like a CHIP device. [1738802741.742] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802741.751] [9484:9485] [BLE] Device 40:1B:89:83:FF:57 does not look like a CHIP device. [1738802741.751] [9484:9485] [BLE] Device 17:5F:DA:96:EA:14 does not look like a CHIP device. [1738802741.754] [9484:9485] [BLE] Device 7E:9B:85:61:C2:17 does not look like a CHIP device. [1738802741.763] [9484:9485] [BLE] Device 24:AE:32:B4:E0:6A does not look like a CHIP device. [1738802741.766] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802741.770] [9484:9485] [BLE] Device 73:53:01:5F:EC:93 does not look like a CHIP device. [1738802741.776] [9484:9485] [BLE] Device DA:4D:3F:BB:E9:EF does not look like a CHIP device. [1738802741.778] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802741.782] [9484:9485] [BLE] Device 1F:95:C0:DE:8E:B5 does not look like a CHIP device. [1738802741.784] [9484:9485] [BLE] Device 38:4B:87:73:7E:A1 does not look like a CHIP device. [1738802741.794] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.794] [9484:9485] [BLE] Device 08:C9:AC:60:1C:39 does not look like a CHIP device. [1738802741.796] [9484:9485] [BLE] Device 05:C4:50:28:48:C7 does not look like a CHIP device. [1738802741.799] [9484:9485] [BLE] Device 3A:57:0E:83:AD:5B does not look like a CHIP device. [1738802741.802] [9484:9485] [BLE] Device 1A:C4:33:14:03:D1 does not look like a CHIP device. [1738802741.807] [9484:9485] [BLE] Device 2C:67:6F:AF:99:39 does not look like a CHIP device. [1738802741.817] [9484:9485] [BLE] Device 42:9B:AC:DC:85:14 does not look like a CHIP device. [1738802741.819] [9484:9485] [BLE] Device 59:C4:81:92:83:F1 does not look like a CHIP device. [1738802741.819] [9484:9485] [BLE] Device 3C:BF:75:CA:0C:C3 does not look like a CHIP device. [1738802741.825] [9484:9485] [BLE] Device 24:F6:C8:5A:1B:C2 does not look like a CHIP device. [1738802741.827] [9484:9485] [BLE] Device 64:20:3E:23:24:1A does not look like a CHIP device. [1738802741.831] [9484:9485] [BLE] Device 52:87:CC:0E:12:FF does not look like a CHIP device. [1738802741.839] [9484:9485] [BLE] Device 4C:48:78:61:4D:D0 does not look like a CHIP device. [1738802741.845] [9484:9485] [BLE] Device 22:FF:D2:9F:CF:B9 does not look like a CHIP device. [1738802741.847] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802741.851] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802741.854] [9484:9485] [BLE] Device 2B:80:D9:F6:F9:46 does not look like a CHIP device. [1738802741.856] [9484:9485] [BLE] Device 17:5F:DA:96:EA:14 does not look like a CHIP device. [1738802741.861] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802741.863] [9484:9485] [BLE] Device 45:07:20:30:99:C0 does not look like a CHIP device. [1738802741.867] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802741.872] [9484:9485] [BLE] Device 6C:0E:16:BA:E6:AB does not look like a CHIP device. [1738802741.873] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802741.884] [9484:9485] [BLE] Device 75:5A:0C:64:8B:DB does not look like a CHIP device. [1738802741.885] [9484:9485] [BLE] Device 37:2D:68:FF:A5:8A does not look like a CHIP device. [1738802741.889] [9484:9485] [BLE] Device 3C:A8:86:C4:F6:C0 does not look like a CHIP device. [1738802741.891] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802741.895] [9484:9485] [BLE] Device 06:C2:1C:2B:5B:DC does not look like a CHIP device. [1738802741.898] [9484:9485] [BLE] Device 67:09:33:3A:60:B0 does not look like a CHIP device. [1738802741.902] [9484:9485] [BLE] Device CD:90:6B:00:A6:B2 does not look like a CHIP device. [1738802741.909] [9484:9485] [BLE] Device 1A:C4:33:14:03:D1 does not look like a CHIP device. [1738802741.912] [9484:9485] [BLE] Device 2C:67:6F:AF:99:39 does not look like a CHIP device. [1738802741.914] [9484:9485] [BLE] Device 42:74:A6:5D:B1:51 does not look like a CHIP device. [1738802741.918] [9484:9485] [BLE] Device 16:5C:D9:29:61:3A does not look like a CHIP device. [1738802741.921] [9484:9485] [BLE] Device 7C:8D:AD:10:3E:A4 does not look like a CHIP device. [1738802741.933] [9484:9485] [BLE] Device 42:EB:71:62:6E:64 does not look like a CHIP device. [1738802741.933] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802741.934] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802741.942] [9484:9485] [BLE] Device 8C:52:19:BA:62:AB does not look like a CHIP device. [1738802741.943] [9484:9485] [BLE] Device 5B:B1:67:7E:9C:BD does not look like a CHIP device. [1738802741.945] [9484:9485] [BLE] Device D7:8A:26:0A:77:D2 does not look like a CHIP device. [1738802741.952] [9484:9485] [BLE] Device 45:55:B4:34:9B:2A does not look like a CHIP device. [1738802741.952] [9484:9485] [BLE] Device 0E:8C:B6:A3:34:DB does not look like a CHIP device. [1738802741.955] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802741.957] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.961] [9484:9485] [BLE] Device 00:F3:11:1E:8E:78 does not look like a CHIP device. [1738802741.975] [9484:9485] [BLE] Device 32:F9:9B:24:C3:19 does not look like a CHIP device. [1738802741.979] [9484:9485] [BLE] Device 24:AE:32:B4:E0:6A does not look like a CHIP device. [1738802741.985] [9484:9485] [BLE] Device A4:59:D3:60:00:01 does not look like a CHIP device. [1738802741.986] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802741.988] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802741.990] [9484:9485] [BLE] Device 38:4B:87:73:7E:A1 does not look like a CHIP device. [1738802741.993] [9484:9485] [BLE] Device 3C:A8:86:C4:F6:C0 does not look like a CHIP device. [1738802741.996] [9484:9485] [BLE] Device 06:C2:1C:2B:5B:DC does not look like a CHIP device. [1738802742.005] [9484:9485] [BLE] Device 24:FA:AA:D6:3A:70 does not look like a CHIP device. [1738802742.012] [9484:9485] [BLE] Device 3A:57:0E:83:AD:5B does not look like a CHIP device. [1738802742.019] [9484:9485] [BLE] Device 30:D7:22:DD:20:A7 does not look like a CHIP device. [1738802742.023] [9484:9485] [BLE] Device 44:79:CB:47:C2:2C does not look like a CHIP device. [1738802742.028] [9484:9485] [BLE] Device 11:DF:78:41:7F:8E does not look like a CHIP device. [1738802742.031] [9484:9485] [BLE] Device 7E:9B:85:61:C2:17 does not look like a CHIP device. [1738802742.036] [9484:9485] [BLE] Device 77:4B:4E:55:AE:53 does not look like a CHIP device. [1738802742.037] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802742.040] [9484:9485] [BLE] Device 12:36:D5:AF:F4:1F does not look like a CHIP device. [1738802742.047] [9484:9485] [BLE] Device 14:8D:9B:45:F5:57 does not look like a CHIP device. [1738802742.051] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802742.058] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802742.065] [9484:9485] [BLE] Device 00:F3:11:1E:8E:78 does not look like a CHIP device. [1738802742.069] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802742.072] [9484:9485] [BLE] Device 6F:82:9C:62:DF:D4 does not look like a CHIP device. [1738802742.076] [9484:9485] [BLE] Device 32:F9:9B:24:C3:19 does not look like a CHIP device. [1738802742.084] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802742.091] [9484:9485] [BLE] Device 7D:CB:86:7C:5D:B9 does not look like a CHIP device. [1738802742.091] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802742.100] [9484:9485] [BLE] Device 64:91:39:E3:64:A3 does not look like a CHIP device. [1738802742.100] [9484:9485] [BLE] Device 2C:4A:95:4C:0B:96 does not look like a CHIP device. [1738802742.101] [9484:9485] [BLE] Device 37:2D:68:FF:A5:8A does not look like a CHIP device. [1738802742.102] [9484:9485] [BLE] Device 3F:EF:EB:D9:7F:8D does not look like a CHIP device. [1738802742.104] [9484:9485] [BLE] Device 1F:95:C0:DE:8E:B5 does not look like a CHIP device. [1738802742.105] [9484:9485] [BLE] Device 06:C2:1C:2B:5B:DC does not look like a CHIP device. [1738802742.109] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802742.126] [9484:9485] [BLE] Device 4A:B6:6E:3C:B6:6E does not look like a CHIP device. [1738802742.132] [9484:9485] [BLE] Device 30:D7:22:DD:20:A7 does not look like a CHIP device. [1738802742.135] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802742.140] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802742.144] [9484:9485] [BLE] Device 49:40:B6:1F:D1:A1 does not look like a CHIP device. [1738802742.151] [9484:9485] [BLE] Device 6C:0E:16:BA:E6:AB does not look like a CHIP device. [1738802742.151] [9484:9485] [BLE] Device 00:4D:98:E0:44:67 does not look like a CHIP device. [1738802742.155] [9484:9485] [BLE] Device 00:80:98:58:0A:0C does not look like a CHIP device. [1738802742.166] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802742.167] [9484:9485] [BLE] Device 3A:96:3B:29:72:C5 does not look like a CHIP device. [1738802742.169] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802742.174] [9484:9485] [BLE] Device 00:F3:11:1E:8E:78 does not look like a CHIP device. [1738802742.179] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802742.190] [9484:9485] [BLE] Device D9:7A:C2:1A:39:A2 does not look like a CHIP device. [1738802742.191] [9484:9485] [BLE] Device 24:AE:32:B4:E0:6A does not look like a CHIP device. [1738802742.195] [9484:9485] [BLE] Device 38:4B:87:73:7E:A1 does not look like a CHIP device. [1738802742.199] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802742.203] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802742.208] [9484:9485] [BLE] Device 3C:A8:86:C4:F6:C0 does not look like a CHIP device. [1738802742.210] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802742.211] [9484:9485] [BLE] Device 10:60:FD:0B:5C:34 does not look like a CHIP device. [1738802742.214] [9484:9485] [BLE] Device 08:C9:AC:60:1C:39 does not look like a CHIP device. [1738802742.217] [9484:9485] [BLE] Device 20:1C:3A:1D:63:99 does not look like a CHIP device. [1738802742.219] [9484:9485] [BLE] Device 4C:48:78:61:4D:D0 does not look like a CHIP device. [1738802742.221] [9484:9485] [BLE] Device 3A:57:0E:83:AD:5B does not look like a CHIP device. [1738802742.227] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802742.230] [9484:9485] [BLE] Device 8C:52:19:BA:62:AB does not look like a CHIP device. [1738802742.230] [9484:9485] [BLE] Device 3C:BF:75:CA:0C:C3 does not look like a CHIP device. [1738802742.238] [9484:9485] [BLE] Device 4E:66:ED:7D:11:43 does not look like a CHIP device. [1738802742.238] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802742.241] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802742.247] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802742.249] [9484:9485] [BLE] Device 24:F6:C8:5A:1B:C2 does not look like a CHIP device. [1738802742.251] [9484:9485] [BLE] Device 2E:B3:51:56:DA:63 does not look like a CHIP device. [1738802742.256] [9484:9485] [BLE] Device 22:FF:D2:9F:CF:B9 does not look like a CHIP device. [1738802742.259] [9484:9485] [BLE] Device 00:4D:98:E0:44:67 does not look like a CHIP device. [1738802742.264] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802742.270] [9484:9485] [BLE] Device 10:75:D0:3A:88:EB does not look like a CHIP device. [1738802742.272] [9484:9485] [BLE] Device 62:F2:A0:E1:DF:E1 does not look like a CHIP device. [1738802742.274] [9484:9485] [BLE] Device 20:80:19:89:7B:B2 does not look like a CHIP device. [1738802742.276] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802742.278] [9484:9485] [BLE] Device 00:F3:11:1E:8E:78 does not look like a CHIP device. [1738802742.279] [9484:9485] [BLE] Device 17:5F:DA:96:EA:14 does not look like a CHIP device. [1738802742.290] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802742.301] [9484:9485] [BLE] Device 49:6C:42:96:76:AC does not look like a CHIP device. [1738802742.302] [9484:9485] [BLE] Device 32:A3:A6:C6:44:FA does not look like a CHIP device. [1738802742.315] [9484:9485] [BLE] Device 40:1B:89:83:FF:57 does not look like a CHIP device. [1738802742.315] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802742.317] [9484:9485] [BLE] Device 05:C4:50:28:48:C7 does not look like a CHIP device. [1738802742.330] [9484:9485] [BLE] Device 68:51:29:20:0D:BD does not look like a CHIP device. [1738802742.339] [9484:9485] [BLE] Device 72:F9:5B:29:73:C2 does not look like a CHIP device. [1738802742.339] [9484:9485] [BLE] Device 2C:67:6F:AF:99:39 does not look like a CHIP device. [1738802742.346] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802742.350] [9484:9485] [BLE] Device 65:34:57:8D:96:4E does not look like a CHIP device. [1738802742.350] [9484:9485] [BLE] Device 12:87:F5:21:7B:EC does not look like a CHIP device. [1738802742.353] [9484:9485] [BLE] Device F6:86:49:2B:20:96 does not look like a CHIP device. [1738802742.354] [9484:9485] [BLE] Device 14:8D:9B:45:F5:57 does not look like a CHIP device. [1738802742.362] [9484:9485] [BLE] Device 42:9B:AC:DC:85:14 does not look like a CHIP device. [1738802742.362] [9484:9485] [BLE] Device 2B:80:D9:F6:F9:46 does not look like a CHIP device. [1738802742.364] [9484:9485] [BLE] Device 00:4D:98:E0:44:67 does not look like a CHIP device. [1738802742.369] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802742.374] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802742.380] [9484:9485] [BLE] Device 52:87:CC:0E:12:FF does not look like a CHIP device. [1738802742.381] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802742.388] [9484:9485] [BLE] Device 52:FF:AE:07:DC:FE does not look like a CHIP device. [1738802742.389] [9484:9485] [BLE] Device D8:49:4B:B1:FD:92 does not look like a CHIP device. [1738802742.391] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802742.394] [9484:9485] [BLE] Device DA:4D:3F:BB:E9:EF does not look like a CHIP device. [1738802742.399] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802742.418] [9484:9485] [BLE] Device 45:07:20:30:99:C0 does not look like a CHIP device. [1738802742.421] [9484:9485] [BLE] Device E4:4A:2F:25:41:75 does not look like a CHIP device. [1738802742.421] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802742.424] [9484:9485] [BLE] Device 42:1D:27:8E:D4:57 does not look like a CHIP device. [1738802742.425] [9484:9485] [BLE] Device 06:C2:1C:2B:5B:DC does not look like a CHIP device. [1738802742.427] [9484:9485] [BLE] Device 1A:C4:33:14:03:D1 does not look like a CHIP device. [1738802742.431] [9484:9485] [BLE] Device 6F:D8:D9:AA:47:3E does not look like a CHIP device. [1738802742.435] [9484:9485] [BLE] Device 3A:57:0E:83:AD:5B does not look like a CHIP device. [1738802742.439] [9484:9485] [BLE] Device F9:6F:27:53:54:7F does not look like a CHIP device. [1738802742.439] [9484:9485] [BLE] Device 2C:67:6F:AF:99:39 does not look like a CHIP device. [1738802742.444] [9484:9485] [BLE] Device A4:59:D3:60:00:00 does not look like a CHIP device. [1738802742.448] [9484:9485] [BLE] Device E9:CA:38:D5:AE:1D does not look like a CHIP device. [1738802742.448] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802742.448] [9484:9485] [BLE] Device 30:D7:22:DD:20:A7 does not look like a CHIP device. [1738802742.450] [9484:9485] [BLE] Device 29:BA:6C:A0:3A:80 does not look like a CHIP device. [1738802742.452] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802742.454] [9484:9485] [BLE] Device 16:5C:D9:29:61:3A does not look like a CHIP device. [1738802742.456] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802742.463] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802742.463] [9484:9485] [BLE] Device 03:DD:63:A7:22:E6 does not look like a CHIP device. [1738802742.469] [9484:9485] [BLE] Device 7C:8D:AD:10:3E:A4 does not look like a CHIP device. [1738802742.480] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802742.485] [9484:9485] [BLE] Device 56:64:88:24:B9:C0 does not look like a CHIP device. [1738802742.490] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802742.491] [9484:9485] [BLE] Device 5B:B1:67:7E:9C:BD does not look like a CHIP device. [1738802742.497] [9484:9485] [BLE] Device DA:C0:EB:2F:6E:87 does not look like a CHIP device. [1738802742.498] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802742.504] [9484:9485] [BLE] Device DA:4D:3F:BB:E9:EF does not look like a CHIP device. [1738802742.506] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802742.510] [9484:9485] [BLE] Device 37:2D:68:FF:A5:8A does not look like a CHIP device. [1738802742.516] [9484:9485] [BLE] Device 3F:EF:EB:D9:7F:8D does not look like a CHIP device. [1738802742.517] [9484:9485] [BLE] Device 38:4B:87:73:7E:A1 does not look like a CHIP device. [1738802742.521] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802742.522] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802742.529] [9484:9485] [BLE] Device 24:FA:AA:D6:3A:70 does not look like a CHIP device. [1738802742.530] [9484:9485] [BLE] Device 06:C2:1C:2B:5B:DC does not look like a CHIP device. [1738802742.531] [9484:9485] [BLE] Device 1F:95:C0:DE:8E:B5 does not look like a CHIP device. [1738802742.533] [9484:9485] [BLE] Device 1A:C4:33:14:03:D1 does not look like a CHIP device. [1738802742.539] [9484:9485] [BLE] Device 7A:54:50:C9:1A:37 does not look like a CHIP device. [1738802742.547] [9484:9485] [BLE] Device 68:A8:01:E1:6D:DD does not look like a CHIP device. [1738802742.549] [9484:9485] [BLE] Device 30:D7:22:DD:20:A7 does not look like a CHIP device. [1738802742.554] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802742.560] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802742.562] [9484:9485] [BLE] Device 24:F6:C8:5A:1B:C2 does not look like a CHIP device. [1738802742.572] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802742.572] [9484:9485] [BLE] Device 00:4D:98:E0:44:67 does not look like a CHIP device. [1738802742.577] [9484:9485] [BLE] Device 22:FF:D2:9F:CF:B9 does not look like a CHIP device. [1738802742.578] [9484:9485] [BLE] Device 0E:8C:B6:A3:34:DB does not look like a CHIP device. [1738802742.580] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802742.583] [9484:9485] [BLE] Device 00:F3:11:1E:8E:78 does not look like a CHIP device. [1738802742.586] [9484:9485] [BLE] Device 2F:85:CC:2F:56:7A does not look like a CHIP device. [1738802742.591] [9484:9485] [BLE] Device 7E:9B:85:61:C2:17 does not look like a CHIP device. [1738802742.591] [9484:9485] [BLE] Device 7D:CB:86:7C:5D:B9 does not look like a CHIP device. [1738802742.594] [9484:9485] [BLE] Device 18:EE:88:07:3B:A8 does not look like a CHIP device. [1738802742.601] [9484:9485] [BLE] Device 68:51:29:20:0D:BD does not look like a CHIP device. [1738802742.601] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802742.604] [9484:9485] [BLE] Device 53:D1:0D:9D:23:57 does not look like a CHIP device. [1738802742.609] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802742.611] [9484:9485] [BLE] Device 37:2D:68:FF:A5:8A does not look like a CHIP device. [1738802742.613] [9484:9485] [BLE] Device 32:A3:A6:C6:44:FA does not look like a CHIP device. [1738802742.623] [9484:9485] [BLE] Device 6F:82:9C:62:DF:D4 does not look like a CHIP device. [1738802742.623] [9484:9485] [BLE] Device 05:C4:50:28:48:C7 does not look like a CHIP device. [1738802742.627] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802742.630] [9484:9485] [BLE] Device 10:60:FD:0B:5C:34 does not look like a CHIP device. [1738802742.642] [9484:9485] [BLE] Device 3A:57:0E:83:AD:5B does not look like a CHIP device. [1738802742.645] [9484:9485] [BLE] Device 7A:54:50:C9:1A:37 does not look like a CHIP device. [1738802742.647] [9484:9485] [BLE] Device 2C:67:6F:AF:99:39 does not look like a CHIP device. [1738802742.653] [9484:9485] [BLE] Device 10:41:D6:D8:9E:7B does not look like a CHIP device. [1738802742.657] [9484:9485] [BLE] Device 11:DF:78:41:7F:8E does not look like a CHIP device. [1738802742.661] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802742.666] [9484:9485] [BLE] Device 52:FF:AE:07:DC:FE does not look like a CHIP device. [1738802742.666] [9484:9485] [BLE] Device 16:5C:D9:29:61:3A does not look like a CHIP device. [1738802742.666] [9484:9485] [BLE] Device 12:36:D5:AF:F4:1F does not look like a CHIP device. [1738802742.674] [9484:9485] [BLE] Device 4A:B6:6E:3C:B6:6E does not look like a CHIP device. [1738802742.674] [9484:9485] [BLE] Device 00:4D:98:E0:44:67 does not look like a CHIP device. [1738802742.680] [9484:9485] [BLE] Device 5D:2A:77:6F:8E:8E does not look like a CHIP device. [1738802742.687] [9484:9485] [BLE] Device 45:07:20:30:99:C0 does not look like a CHIP device. [1738802742.688] [9484:9485] [BLE] Device 0E:8C:B6:A3:34:DB does not look like a CHIP device. [1738802742.691] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802742.697] [9484:9485] [BLE] Device 50:16:BC:27:3E:A1 does not look like a CHIP device. [1738802742.701] [9484:9485] [BLE] Device 75:5A:0C:64:8B:DB does not look like a CHIP device. [1738802742.703] [9484:9485] [BLE] Device 17:5F:DA:96:EA:14 does not look like a CHIP device. [1738802742.706] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802742.710] [9484:9485] [BLE] Device 16:4A:86:5C:DD:EB does not look like a CHIP device. [1738802742.712] [9484:9485] [BLE] Device 27:0B:A3:31:FC:9C does not look like a CHIP device. [1738802742.714] [9484:9485] [BLE] Device DA:4D:3F:BB:E9:EF does not look like a CHIP device. [1738802742.718] [9484:9485] [BLE] Device 37:2D:68:FF:A5:8A does not look like a CHIP device. [1738802742.723] [9484:9485] [BLE] Device 05:C4:50:28:48:C7 does not look like a CHIP device. [1738802742.731] [9484:9485] [BLE] Device 24:FA:AA:D6:3A:70 does not look like a CHIP device. [1738802742.733] [9484:9485] [BLE] Device 3F:EF:EB:D9:7F:8D does not look like a CHIP device. [1738802742.736] [9484:9485] [BLE] Device 08:C9:AC:60:1C:39 does not look like a CHIP device. [1738802742.744] [9484:9485] [BLE] Device 06:C2:1C:2B:5B:DC does not look like a CHIP device. [1738802742.754] [9484:9485] [BLE] Device 6A:04:5A:7C:6D:4D does not look like a CHIP device. [1738802742.755] [9484:9485] [BLE] Device 7A:54:50:C9:1A:37 does not look like a CHIP device. [1738802742.758] [9484:9485] [BLE] Device 30:D7:22:DD:20:A7 does not look like a CHIP device. [1738802742.762] [9484:9485] [BLE] Device 29:BA:6C:A0:3A:80 does not look like a CHIP device. [1738802742.767] [9484:9485] [BLE] Device 3F:44:E1:90:86:9C does not look like a CHIP device. [1738802742.768] [9484:9485] [BLE] Device 18:65:B4:B0:31:4E does not look like a CHIP device. [1738802742.779] [9484:9485] [BLE] Device 2E:B3:51:56:DA:63 does not look like a CHIP device. [1738802742.781] [9484:9485] [BLE] Device 22:38:27:60:A3:C9 does not look like a CHIP device. [1738802742.784] [9484:9485] [BLE] Device 4C:48:78:61:4D:D0 does not look like a CHIP device. [1738802742.789] [9484:9485] [BLE] Device 73:53:01:5F:EC:93 does not look like a CHIP device. [1738802742.790] [9484:9485] [BLE] Device 0E:8C:B6:A3:34:DB does not look like a CHIP device. [1738802742.790] [9484:9485] [BLE] Device 02:1A:AF:C3:16:65 does not look like a CHIP device. [1738802742.795] [9484:9485] [BLE] Device 0A:BC:9E:3C:1F:54 does not look like a CHIP device. [1738802742.804] [9484:9485] [BLE] Device 8C:52:19:BA:62:AB does not look like a CHIP device. [1738802742.814] [9484:9485] [BLE] Device 64:E1:C7:82:A0:6F does not look like a CHIP device. [1738802742.814] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802742.816] [9484:9485] [BLE] Device 3B:19:83:15:6B:9A does not look like a CHIP device. [1738802742.818] [9484:9485] [BLE] Device DA:4D:3F:BB:E9:EF does not look like a CHIP device. [1738802742.823] [9484:9485] [BLE] Device 62:F2:A0:E1:DF:E1 does not look like a CHIP device. [1738802742.823] [9484:9485] [BLE] Device 32:A3:A6:C6:44:FA does not look like a CHIP device. [1738802742.824] [9484:9485] [BLE] Device 04:68:97:22:02:7E does not look like a CHIP device. [1738802742.825] [9484:9485] [BLE] Device 43:A8:96:03:31:E9 does not look like a CHIP device. [1738802742.829] [9484:9485] [BLE] Device 05:C4:50:28:48:C7 does not look like a CHIP device. [1738802742.831] [9484:9485] [BLE] Device 17:B2:C3:80:60:73 does not look like a CHIP device. [1738802742.833] [9484:9485] [BLE] Device 24:FA:AA:D6:3A:70 does not look like a CHIP device. [1738802742.835] [9484:9485] [BLE] Device C8:66:9A:99:5C:CA does not look like a CHIP device. [1738802742.842] [9484:9485] [BLE] Device 3D:F4:24:D5:D7:10 does not look like a CHIP device. [1738802742.849] [9484:9485] [BLE] Device 65:34:57:8D:96:4E does not look like a CHIP device. [1738802742.851] [9484:9485] [BLE] Device 3C:A8:86:C4:F6:C0 does not look like a CHIP device. [1738802742.857] [9484:9485] [BLE] New device scanned: D4:36:BC:7A:7A:25 [1738802742.858] [9484:9485] [BLE] Device discriminator match. Attempting to connect. [1738802742.862] [9484:9485] [BLE] ChipDeviceScanner has stopped scanning! [1738802743.041] [9484:9485] [DL] ConnectDevice complete [1738802743.041] [9484:9485] [BLE] New device connected: D4:36:BC:7A:7A:25 [1738802744.054] [9484:9485] [DL] CHIP service found [1738802744.055] [9484:9485] [DL] Valid C2 characteristic found [1738802744.055] [9484:9485] [DL] Valid C1 characteristic found [1738802744.055] [9484:9485] [DL] New BLE connection: conn=0xffffa00bd5f0 device=D4:36:BC:7A:7A:25 path=/org/bluez/hci0/dev_D4_36_BC_7A_7A_25 [1738802744.055] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16387 [1738802744.056] [9484:9486] [DIS] Closing all BLE connections [1738802744.056] [9484:9486] [IN] BleConnectionComplete: endPoint 0xaaaab685cb38 [1738802744.056] [9484:9486] [IN] SecureSession[0xffff9800c290]: Allocated Type:1 LSID:24305 [1738802744.057] [9484:9486] [SC] Assigned local session key ID 24305 [1738802744.057] [9484:9486] [EM] <<< [E:51488i S:0 M:95916615] (U) Msg TX from 9CEE8AD0CDE68E60 to 0:0000000000000000 [0000] [BLE] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) (B:98) [1738802744.057] [9484:9486] [IN] Message appended to BLE send queue [1738802744.057] [9484:9486] [SC] Sent PBKDF param request [II:500ms AI:300ms AT:4000ms) [1738802744.588] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802744.723] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16390 [1738802744.723] [9484:9486] [BLE] subscribe complete, ep = 0xaaaab685cb38 [1738802744.724] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802744.724] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802744.724] [9484:9486] [BLE] peripheral chose BTP version 4; central expected between 4 and 4 [1738802744.724] [9484:9486] [BLE] using BTP fragment sizes rx 244 / tx 244. [1738802744.724] [9484:9486] [BLE] local and remote recv window size = 5 [1738802744.725] [9484:9486] [IN] BLE EndPoint 0xaaaab685cb38 Connection Complete [1738802744.812] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802744.815] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802744.815] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802744.816] [9484:9486] [EM] >>> [E:51488i S:0 M:123259088] (U) Msg RX from 0:0000000000000000 [0000] to 9CEE8AD0CDE68E60 --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (B:154) [1738802744.816] [9484:9486] [EM] Found matching exchange: 51488i, Delegate: 0xffff9800b768 [1738802744.816] [9484:9486] [SC] Received PBKDF param response [1738802744.816] [9484:9486] [SC] Peer assigned session ID 57842 [1738802744.816] [9484:9486] [SC] Found MRP parameters in the message [1738802744.828] [9484:9486] [EM] <<< [E:51488i S:0 M:95916616] (U) Msg TX from 9CEE8AD0CDE68E60 to 0:0000000000000000 [0000] [BLE] --- Type 0000:22 (SecureChannel:PASE_Pake1) (B:92) [1738802744.829] [9484:9486] [SC] Sent spake2p msg1 [1738802744.902] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802745.038] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.038] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.039] [9484:9486] [EM] >>> [E:51488i S:0 M:123259089] (U) Msg RX from 0:0000000000000000 [0000] to 9CEE8AD0CDE68E60 --- Type 0000:23 (SecureChannel:PASE_Pake2) (B:127) [1738802745.039] [9484:9486] [EM] Found matching exchange: 51488i, Delegate: 0xffff9800b768 [1738802745.039] [9484:9486] [SC] Received spake2p msg2 [1738802745.041] [9484:9486] [EM] <<< [E:51488i S:0 M:95916617] (U) Msg TX from 9CEE8AD0CDE68E60 to 0:0000000000000000 [0000] [BLE] --- Type 0000:24 (SecureChannel:PASE_Pake3) (B:59) [1738802745.041] [9484:9486] [SC] Sent spake2p msg3 [1738802745.127] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802745.129] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.129] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.130] [9484:9486] [EM] >>> [E:51488i S:0 M:123259090] (U) Msg RX from 0:0000000000000000 [0000] to 9CEE8AD0CDE68E60 --- Type 0000:40 (SecureChannel:StatusReport) (B:30) [1738802745.130] [9484:9486] [EM] Found matching exchange: 51488i, Delegate: 0xffff9800b768 [1738802745.130] [9484:9486] [SC] SecureSession[0xffff9800c290, LSID:24305]: State change 'kEstablishing' --> 'kActive' [1738802745.130] [9484:9486] [IN] SecureSession[0xffff9800c290]: Activated - Type:1 LSID:24305 [1738802745.130] [9484:9486] [IN] New secure session activated for device , LSID:24305 PSID:57842! [1738802745.130] [9484:9486] [CTL] Remote device completed SPAKE2+ handshake [1738802745.130] [9484:9486] [TOO] Pairing Success [1738802745.130] [9484:9486] [TOO] PASE establishment successful [1738802745.131] [9484:9486] [CTL] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1738802745.131] [9484:9486] [CTL] Performing next commissioning step 'ReadCommissioningInfo' [1738802745.131] [9484:9486] [CTL] Sending read request for commissioning information [1738802745.131] [9484:9486] [DMG] SendReadRequest ReadClient[0xffff9800a050]: Sending Read Request [1738802745.131] [9484:9486] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1738802745.131] [9484:9486] [EM] <<< [E:51489i S:24305 M:566859] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) (B:131) [1738802745.132] [9484:9486] [DMG] MoveToState ReadClient[0xffff9800a050]: Moving to [AwaitingIn] [1738802745.132] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 32792 [1738802745.217] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802745.221] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.221] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.307] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.307] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.307] [9484:9486] [EM] >>> [E:51489i S:24305 M:50946122] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 (IM:ReportData) (B:258) [1738802745.307] [9484:9486] [EM] Found matching exchange: 51489i, Delegate: 0xffff9800a060 [1738802745.308] [9484:9486] [DMG] ReportDataMessage = [1738802745.308] [9484:9486] [DMG] { [1738802745.308] [9484:9486] [DMG] AttributeReportIBs = [1738802745.308] [9484:9486] [DMG] [ [1738802745.308] [9484:9486] [DMG] AttributeReportIB = [1738802745.308] [9484:9486] [DMG] { [1738802745.308] [9484:9486] [DMG] AttributeDataIB = [1738802745.308] [9484:9486] [DMG] { [1738802745.308] [9484:9486] [DMG] DataVersion = 0xc0c80381, [1738802745.308] [9484:9486] [DMG] AttributePathIB = [1738802745.308] [9484:9486] [DMG] { [1738802745.308] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.308] [9484:9486] [DMG] Cluster = 0x31, [1738802745.308] [9484:9486] [DMG] Attribute = 0x0000_0003, [1738802745.308] [9484:9486] [DMG] } [1738802745.308] [9484:9486] [DMG] [1738802745.308] [9484:9486] [DMG] Data = 20 (unsigned), [1738802745.308] [9484:9486] [DMG] }, [1738802745.308] [9484:9486] [DMG] [1738802745.308] [9484:9486] [DMG] }, [1738802745.308] [9484:9486] [DMG] [1738802745.308] [9484:9486] [DMG] AttributeReportIB = [1738802745.308] [9484:9486] [DMG] { [1738802745.308] [9484:9486] [DMG] AttributeDataIB = [1738802745.309] [9484:9486] [DMG] { [1738802745.309] [9484:9486] [DMG] DataVersion = 0x73ee829f, [1738802745.309] [9484:9486] [DMG] AttributePathIB = [1738802745.309] [9484:9486] [DMG] { [1738802745.309] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.309] [9484:9486] [DMG] Cluster = 0x28, [1738802745.309] [9484:9486] [DMG] Attribute = 0x0000_0004, [1738802745.309] [9484:9486] [DMG] } [1738802745.309] [9484:9486] [DMG] [1738802745.309] [9484:9486] [DMG] Data = 32774 (unsigned), [1738802745.309] [9484:9486] [DMG] }, [1738802745.309] [9484:9486] [DMG] [1738802745.309] [9484:9486] [DMG] }, [1738802745.309] [9484:9486] [DMG] [1738802745.309] [9484:9486] [DMG] AttributeReportIB = [1738802745.309] [9484:9486] [DMG] { [1738802745.309] [9484:9486] [DMG] AttributeDataIB = [1738802745.309] [9484:9486] [DMG] { [1738802745.309] [9484:9486] [DMG] DataVersion = 0x73ee829f, [1738802745.309] [9484:9486] [DMG] AttributePathIB = [1738802745.310] [9484:9486] [DMG] { [1738802745.310] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.310] [9484:9486] [DMG] Cluster = 0x28, [1738802745.310] [9484:9486] [DMG] Attribute = 0x0000_0002, [1738802745.310] [9484:9486] [DMG] } [1738802745.310] [9484:9486] [DMG] [1738802745.310] [9484:9486] [DMG] Data = 65521 (unsigned), [1738802745.310] [9484:9486] [DMG] }, [1738802745.310] [9484:9486] [DMG] [1738802745.310] [9484:9486] [DMG] }, [1738802745.310] [9484:9486] [DMG] [1738802745.310] [9484:9486] [DMG] AttributeReportIB = [1738802745.310] [9484:9486] [DMG] { [1738802745.310] [9484:9486] [DMG] AttributeDataIB = [1738802745.310] [9484:9486] [DMG] { [1738802745.311] [9484:9486] [DMG] DataVersion = 0x3feeaa3c, [1738802745.311] [9484:9486] [DMG] AttributePathIB = [1738802745.311] [9484:9486] [DMG] { [1738802745.311] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.311] [9484:9486] [DMG] Cluster = 0x30, [1738802745.311] [9484:9486] [DMG] Attribute = 0x0000_0003, [1738802745.311] [9484:9486] [DMG] } [1738802745.311] [9484:9486] [DMG] [1738802745.311] [9484:9486] [DMG] Data = 0 (unsigned), [1738802745.311] [9484:9486] [DMG] }, [1738802745.311] [9484:9486] [DMG] [1738802745.311] [9484:9486] [DMG] }, [1738802745.311] [9484:9486] [DMG] [1738802745.311] [9484:9486] [DMG] AttributeReportIB = [1738802745.311] [9484:9486] [DMG] { [1738802745.311] [9484:9486] [DMG] AttributeDataIB = [1738802745.311] [9484:9486] [DMG] { [1738802745.311] [9484:9486] [DMG] DataVersion = 0x3feeaa3c, [1738802745.311] [9484:9486] [DMG] AttributePathIB = [1738802745.311] [9484:9486] [DMG] { [1738802745.312] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.312] [9484:9486] [DMG] Cluster = 0x30, [1738802745.312] [9484:9486] [DMG] Attribute = 0x0000_0002, [1738802745.312] [9484:9486] [DMG] } [1738802745.312] [9484:9486] [DMG] [1738802745.312] [9484:9486] [DMG] Data = 0 (unsigned), [1738802745.312] [9484:9486] [DMG] }, [1738802745.312] [9484:9486] [DMG] [1738802745.312] [9484:9486] [DMG] }, [1738802745.312] [9484:9486] [DMG] [1738802745.312] [9484:9486] [DMG] AttributeReportIB = [1738802745.312] [9484:9486] [DMG] { [1738802745.312] [9484:9486] [DMG] AttributeDataIB = [1738802745.312] [9484:9486] [DMG] { [1738802745.312] [9484:9486] [DMG] DataVersion = 0x3feeaa3c, [1738802745.312] [9484:9486] [DMG] AttributePathIB = [1738802745.312] [9484:9486] [DMG] { [1738802745.312] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.312] [9484:9486] [DMG] Cluster = 0x30, [1738802745.312] [9484:9486] [DMG] Attribute = 0x0000_0001, [1738802745.313] [9484:9486] [DMG] } [1738802745.313] [9484:9486] [DMG] [1738802745.313] [9484:9486] [DMG] Data = [1738802745.313] [9484:9486] [DMG] { [1738802745.313] [9484:9486] [DMG] 0x0 = 60 (unsigned), [1738802745.313] [9484:9486] [DMG] 0x1 = 900 (unsigned), [1738802745.313] [9484:9486] [DMG] }, [1738802745.313] [9484:9486] [DMG] }, [1738802745.313] [9484:9486] [DMG] [1738802745.313] [9484:9486] [DMG] }, [1738802745.313] [9484:9486] [DMG] [1738802745.313] [9484:9486] [DMG] AttributeReportIB = [1738802745.313] [9484:9486] [DMG] { [1738802745.313] [9484:9486] [DMG] AttributeDataIB = [1738802745.313] [9484:9486] [DMG] { [1738802745.313] [9484:9486] [DMG] DataVersion = 0x3feeaa3c, [1738802745.313] [9484:9486] [DMG] AttributePathIB = [1738802745.313] [9484:9486] [DMG] { [1738802745.313] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.313] [9484:9486] [DMG] Cluster = 0x30, [1738802745.313] [9484:9486] [DMG] Attribute = 0x0000_0000, [1738802745.313] [9484:9486] [DMG] } [1738802745.314] [9484:9486] [DMG] [1738802745.314] [9484:9486] [DMG] Data = 0 (unsigned), [1738802745.314] [9484:9486] [DMG] }, [1738802745.314] [9484:9486] [DMG] [1738802745.314] [9484:9486] [DMG] }, [1738802745.314] [9484:9486] [DMG] [1738802745.314] [9484:9486] [DMG] AttributeReportIB = [1738802745.314] [9484:9486] [DMG] { [1738802745.314] [9484:9486] [DMG] AttributeDataIB = [1738802745.314] [9484:9486] [DMG] { [1738802745.314] [9484:9486] [DMG] DataVersion = 0xc0c80381, [1738802745.314] [9484:9486] [DMG] AttributePathIB = [1738802745.314] [9484:9486] [DMG] { [1738802745.314] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.314] [9484:9486] [DMG] Cluster = 0x31, [1738802745.315] [9484:9486] [DMG] Attribute = 0x0000_FFFC, [1738802745.315] [9484:9486] [DMG] } [1738802745.315] [9484:9486] [DMG] [1738802745.315] [9484:9486] [DMG] Data = 2 (unsigned), [1738802745.315] [9484:9486] [DMG] }, [1738802745.315] [9484:9486] [DMG] [1738802745.315] [9484:9486] [DMG] }, [1738802745.315] [9484:9486] [DMG] [1738802745.315] [9484:9486] [DMG] ], [1738802745.315] [9484:9486] [DMG] [1738802745.315] [9484:9486] [DMG] SuppressResponse = true, [1738802745.315] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802745.315] [9484:9486] [DMG] } [1738802745.316] [9484:9486] [CTL] Successfully finished commissioning step 'ReadCommissioningInfo' [1738802745.316] [9484:9486] [CTL] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ReadCommissioningInfo2' [1738802745.316] [9484:9486] [CTL] Performing next commissioning step 'ReadCommissioningInfo2' [1738802745.316] [9484:9486] [DMG] SendReadRequest ReadClient[0xffff9800a050]: Sending Read Request [1738802745.317] [9484:9486] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1738802745.317] [9484:9486] [EM] <<< [E:51490i S:24305 M:566860] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) (B:106) [1738802745.317] [9484:9486] [DMG] MoveToState ReadClient[0xffff9800a050]: Moving to [AwaitingIn] [1738802745.397] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802745.445] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.445] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.445] [9484:9486] [EM] >>> [E:51490i S:24305 M:50946123] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 (IM:ReportData) (B:191) [1738802745.446] [9484:9486] [EM] Found matching exchange: 51490i, Delegate: 0xffff9800a060 [1738802745.446] [9484:9486] [DMG] ReportDataMessage = [1738802745.446] [9484:9486] [DMG] { [1738802745.446] [9484:9486] [DMG] AttributeReportIBs = [1738802745.446] [9484:9486] [DMG] [ [1738802745.446] [9484:9486] [DMG] AttributeReportIB = [1738802745.446] [9484:9486] [DMG] { [1738802745.446] [9484:9486] [DMG] AttributeDataIB = [1738802745.446] [9484:9486] [DMG] { [1738802745.446] [9484:9486] [DMG] DataVersion = 0xc3b562d4, [1738802745.446] [9484:9486] [DMG] AttributePathIB = [1738802745.446] [9484:9486] [DMG] { [1738802745.446] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.446] [9484:9486] [DMG] Cluster = 0x46, [1738802745.446] [9484:9486] [DMG] Attribute = 0x0000_0002, [1738802745.447] [9484:9486] [DMG] } [1738802745.447] [9484:9486] [DMG] [1738802745.447] [9484:9486] [DMG] Data = 300 (unsigned), [1738802745.447] [9484:9486] [DMG] }, [1738802745.447] [9484:9486] [DMG] [1738802745.447] [9484:9486] [DMG] }, [1738802745.447] [9484:9486] [DMG] [1738802745.447] [9484:9486] [DMG] AttributeReportIB = [1738802745.447] [9484:9486] [DMG] { [1738802745.447] [9484:9486] [DMG] AttributeDataIB = [1738802745.447] [9484:9486] [DMG] { [1738802745.447] [9484:9486] [DMG] DataVersion = 0xc3b562d4, [1738802745.447] [9484:9486] [DMG] AttributePathIB = [1738802745.447] [9484:9486] [DMG] { [1738802745.447] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.447] [9484:9486] [DMG] Cluster = 0x46, [1738802745.447] [9484:9486] [DMG] Attribute = 0x0000_0001, [1738802745.448] [9484:9486] [DMG] } [1738802745.448] [9484:9486] [DMG] [1738802745.448] [9484:9486] [DMG] Data = 300 (unsigned), [1738802745.448] [9484:9486] [DMG] }, [1738802745.448] [9484:9486] [DMG] [1738802745.448] [9484:9486] [DMG] }, [1738802745.448] [9484:9486] [DMG] [1738802745.448] [9484:9486] [DMG] AttributeReportIB = [1738802745.448] [9484:9486] [DMG] { [1738802745.448] [9484:9486] [DMG] AttributeDataIB = [1738802745.448] [9484:9486] [DMG] { [1738802745.448] [9484:9486] [DMG] DataVersion = 0xc3b562d4, [1738802745.448] [9484:9486] [DMG] AttributePathIB = [1738802745.448] [9484:9486] [DMG] { [1738802745.448] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.448] [9484:9486] [DMG] Cluster = 0x46, [1738802745.449] [9484:9486] [DMG] Attribute = 0x0000_0000, [1738802745.449] [9484:9486] [DMG] } [1738802745.449] [9484:9486] [DMG] [1738802745.449] [9484:9486] [DMG] Data = 120 (unsigned), [1738802745.449] [9484:9486] [DMG] }, [1738802745.449] [9484:9486] [DMG] [1738802745.449] [9484:9486] [DMG] }, [1738802745.449] [9484:9486] [DMG] [1738802745.449] [9484:9486] [DMG] AttributeReportIB = [1738802745.449] [9484:9486] [DMG] { [1738802745.449] [9484:9486] [DMG] AttributeStatusIB = [1738802745.449] [9484:9486] [DMG] { [1738802745.449] [9484:9486] [DMG] AttributePathIB = [1738802745.449] [9484:9486] [DMG] { [1738802745.449] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.449] [9484:9486] [DMG] Cluster = 0x46, [1738802745.450] [9484:9486] [DMG] Attribute = 0x0000_0007, [1738802745.450] [9484:9486] [DMG] } [1738802745.450] [9484:9486] [DMG] [1738802745.450] [9484:9486] [DMG] StatusIB = [1738802745.450] [9484:9486] [DMG] { [1738802745.450] [9484:9486] [DMG] status = 0x86 (UNSUPPORTED_ATTRIBUTE), [1738802745.450] [9484:9486] [DMG] }, [1738802745.450] [9484:9486] [DMG] [1738802745.450] [9484:9486] [DMG] }, [1738802745.450] [9484:9486] [DMG] [1738802745.450] [9484:9486] [DMG] }, [1738802745.450] [9484:9486] [DMG] [1738802745.450] [9484:9486] [DMG] AttributeReportIB = [1738802745.450] [9484:9486] [DMG] { [1738802745.450] [9484:9486] [DMG] AttributeStatusIB = [1738802745.450] [9484:9486] [DMG] { [1738802745.451] [9484:9486] [DMG] AttributePathIB = [1738802745.451] [9484:9486] [DMG] { [1738802745.451] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.451] [9484:9486] [DMG] Cluster = 0x46, [1738802745.451] [9484:9486] [DMG] Attribute = 0x0000_0006, [1738802745.451] [9484:9486] [DMG] } [1738802745.451] [9484:9486] [DMG] [1738802745.451] [9484:9486] [DMG] StatusIB = [1738802745.451] [9484:9486] [DMG] { [1738802745.451] [9484:9486] [DMG] status = 0x86 (UNSUPPORTED_ATTRIBUTE), [1738802745.451] [9484:9486] [DMG] }, [1738802745.451] [9484:9486] [DMG] [1738802745.451] [9484:9486] [DMG] }, [1738802745.451] [9484:9486] [DMG] [1738802745.451] [9484:9486] [DMG] }, [1738802745.451] [9484:9486] [DMG] [1738802745.452] [9484:9486] [DMG] AttributeReportIB = [1738802745.452] [9484:9486] [DMG] { [1738802745.452] [9484:9486] [DMG] AttributeDataIB = [1738802745.452] [9484:9486] [DMG] { [1738802745.452] [9484:9486] [DMG] DataVersion = 0x3feeaa3c, [1738802745.452] [9484:9486] [DMG] AttributePathIB = [1738802745.452] [9484:9486] [DMG] { [1738802745.452] [9484:9486] [DMG] Endpoint = 0x0, [1738802745.452] [9484:9486] [DMG] Cluster = 0x30, [1738802745.452] [9484:9486] [DMG] Attribute = 0x0000_0004, [1738802745.452] [9484:9486] [DMG] } [1738802745.452] [9484:9486] [DMG] [1738802745.452] [9484:9486] [DMG] Data = true, [1738802745.452] [9484:9486] [DMG] }, [1738802745.452] [9484:9486] [DMG] [1738802745.452] [9484:9486] [DMG] }, [1738802745.452] [9484:9486] [DMG] [1738802745.452] [9484:9486] [DMG] ], [1738802745.452] [9484:9486] [DMG] [1738802745.452] [9484:9486] [DMG] SuppressResponse = true, [1738802745.453] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802745.453] [9484:9486] [DMG] } [1738802745.453] [9484:9486] [CTL] ----- NetworkCommissioning Features: has Thread. endpointid = 0 [1738802745.453] [9484:9486] [SVR] OnReadCommissioningInfo - vendorId=0xFFF1 productId=0x8006 [1738802745.453] [9484:9486] [SVR] OnReadCommissioningInfo ICD - IdleModeDuration=0 activeModeDuration=0 activeModeThreshold=0 [1738802745.453] [9484:9486] [CTL] Successfully finished commissioning step 'ReadCommissioningInfo2' [1738802745.454] [9484:9486] [CTL] Commissioning stage next step: 'ReadCommissioningInfo2' -> 'ArmFailSafe' [1738802745.454] [9484:9486] [CTL] Performing next commissioning step 'ArmFailSafe' [1738802745.454] [9484:9486] [CTL] Arming failsafe (60 seconds) [1738802745.454] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802745.454] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802745.454] [9484:9486] [EM] <<< [E:51491i S:24305 M:566861] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) [1738802745.454] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802745.533] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802745.534] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.534] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.535] [9484:9486] [EM] >>> [E:51491i S:24305 M:50946124] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1738802745.535] [9484:9486] [EM] Found matching exchange: 51491i, Delegate: 0xffff980081b8 [1738802745.535] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802745.535] [9484:9486] [DMG] InvokeResponseMessage = [1738802745.535] [9484:9486] [DMG] { [1738802745.535] [9484:9486] [DMG] suppressResponse = false, [1738802745.535] [9484:9486] [DMG] InvokeResponseIBs = [1738802745.535] [9484:9486] [DMG] [ [1738802745.535] [9484:9486] [DMG] InvokeResponseIB = [1738802745.536] [9484:9486] [DMG] { [1738802745.536] [9484:9486] [DMG] CommandDataIB = [1738802745.536] [9484:9486] [DMG] { [1738802745.536] [9484:9486] [DMG] CommandPathIB = [1738802745.536] [9484:9486] [DMG] { [1738802745.536] [9484:9486] [DMG] EndpointId = 0x0, [1738802745.536] [9484:9486] [DMG] ClusterId = 0x30, [1738802745.536] [9484:9486] [DMG] CommandId = 0x1, [1738802745.536] [9484:9486] [DMG] }, [1738802745.537] [9484:9486] [DMG] [1738802745.537] [9484:9486] [DMG] CommandFields = [1738802745.537] [9484:9486] [DMG] { [1738802745.537] [9484:9486] [DMG] 0x0 = 0 (unsigned), [1738802745.537] [9484:9486] [DMG] 0x1 = "" (0 chars), [1738802745.537] [9484:9486] [DMG] }, [1738802745.537] [9484:9486] [DMG] }, [1738802745.537] [9484:9486] [DMG] [1738802745.537] [9484:9486] [DMG] }, [1738802745.538] [9484:9486] [DMG] [1738802745.538] [9484:9486] [DMG] ], [1738802745.538] [9484:9486] [DMG] [1738802745.538] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802745.538] [9484:9486] [DMG] }, [1738802745.538] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1738802745.538] [9484:9486] [CTL] Received ArmFailSafe response errorCode=0 [1738802745.538] [9484:9486] [CTL] Successfully finished commissioning step 'ArmFailSafe' [1738802745.538] [9484:9486] [CTL] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1738802745.539] [9484:9486] [CTL] Performing next commissioning step 'ConfigRegulatory' [1738802745.539] [9484:9486] [CTL] Setting Regulatory Config [1738802745.539] [9484:9486] [CTL] Device does not support configurable regulatory location [1738802745.539] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802745.539] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802745.539] [9484:9486] [EM] <<< [E:51492i S:24305 M:566862] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:70) [1738802745.540] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802745.541] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802745.622] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802745.624] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.624] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.624] [9484:9486] [EM] >>> [E:51492i S:24305 M:50946125] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1738802745.625] [9484:9486] [EM] Found matching exchange: 51492i, Delegate: 0xffff98008368 [1738802745.625] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802745.625] [9484:9486] [DMG] InvokeResponseMessage = [1738802745.625] [9484:9486] [DMG] { [1738802745.625] [9484:9486] [DMG] suppressResponse = false, [1738802745.625] [9484:9486] [DMG] InvokeResponseIBs = [1738802745.625] [9484:9486] [DMG] [ [1738802745.625] [9484:9486] [DMG] InvokeResponseIB = [1738802745.625] [9484:9486] [DMG] { [1738802745.625] [9484:9486] [DMG] CommandDataIB = [1738802745.625] [9484:9486] [DMG] { [1738802745.625] [9484:9486] [DMG] CommandPathIB = [1738802745.625] [9484:9486] [DMG] { [1738802745.626] [9484:9486] [DMG] EndpointId = 0x0, [1738802745.626] [9484:9486] [DMG] ClusterId = 0x30, [1738802745.626] [9484:9486] [DMG] CommandId = 0x3, [1738802745.626] [9484:9486] [DMG] }, [1738802745.626] [9484:9486] [DMG] [1738802745.626] [9484:9486] [DMG] CommandFields = [1738802745.626] [9484:9486] [DMG] { [1738802745.626] [9484:9486] [DMG] 0x0 = 0 (unsigned), [1738802745.626] [9484:9486] [DMG] 0x1 = "" (0 chars), [1738802745.626] [9484:9486] [DMG] }, [1738802745.626] [9484:9486] [DMG] }, [1738802745.627] [9484:9486] [DMG] [1738802745.627] [9484:9486] [DMG] }, [1738802745.627] [9484:9486] [DMG] [1738802745.627] [9484:9486] [DMG] ], [1738802745.627] [9484:9486] [DMG] [1738802745.627] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802745.627] [9484:9486] [DMG] }, [1738802745.627] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1738802745.627] [9484:9486] [CTL] Received SetRegulatoryConfig response errorCode=0 [1738802745.627] [9484:9486] [CTL] Successfully finished commissioning step 'ConfigRegulatory' [1738802745.627] [9484:9486] [CTL] Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest' [1738802745.628] [9484:9486] [CTL] Performing next commissioning step 'SendPAICertificateRequest' [1738802745.628] [9484:9486] [CTL] Sending request for PAI certificate [1738802745.628] [9484:9486] [CTL] Sending Certificate Chain request to 0xffff9800b710 device [1738802745.628] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802745.628] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802745.628] [9484:9486] [EM] <<< [E:51493i S:24305 M:566863] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) [1738802745.629] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802745.629] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802745.712] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802745.716] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.716] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.850] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.851] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.938] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802745.939] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802745.939] [9484:9486] [EM] >>> [E:51493i S:24305 M:50946126] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:527) [1738802745.939] [9484:9486] [EM] Found matching exchange: 51493i, Delegate: 0xffff980081b8 [1738802745.939] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802745.939] [9484:9486] [DMG] InvokeResponseMessage = [1738802745.939] [9484:9486] [DMG] { [1738802745.939] [9484:9486] [DMG] suppressResponse = false, [1738802745.939] [9484:9486] [DMG] InvokeResponseIBs = [1738802745.940] [9484:9486] [DMG] [ [1738802745.940] [9484:9486] [DMG] InvokeResponseIB = [1738802745.940] [9484:9486] [DMG] { [1738802745.940] [9484:9486] [DMG] CommandDataIB = [1738802745.940] [9484:9486] [DMG] { [1738802745.940] [9484:9486] [DMG] CommandPathIB = [1738802745.940] [9484:9486] [DMG] { [1738802745.940] [9484:9486] [DMG] EndpointId = 0x0, [1738802745.940] [9484:9486] [DMG] ClusterId = 0x3e, [1738802745.940] [9484:9486] [DMG] CommandId = 0x3, [1738802745.941] [9484:9486] [DMG] }, [1738802745.941] [9484:9486] [DMG] [1738802745.941] [9484:9486] [DMG] CommandFields = [1738802745.941] [9484:9486] [DMG] { [1738802745.941] [9484:9486] [DMG] 0x0 = [ [1738802745.941] [9484:9486] [DMG] 0x30, 0x82, 0x01, 0xcb, 0x30, 0x82, 0x01, 0x71, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x56, 0xad, 0x82, 0x22, 0xad, 0x94, 0x5b, 0x64, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x30, 0x31, 0x18, 0x30, 0x16, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0f, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x50, 0x41, 0x41, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x41, 0x9a, 0x93, 0x15, 0xc2, 0x17, 0x3e, 0x0c, 0x8c, 0x87, 0x6d, 0x03, 0xcc, 0xfc, 0x94, 0x48, 0x52, 0x64, 0x7f, 0x7f, 0xec, 0x5e, 0x50, 0x82, 0xf4, 0x05, 0x99, 0x28, 0xec, 0xa8, 0x94, 0xc5, 0x94, 0x15, 0x13, 0x09, 0xac, 0x63, 0x1e, 0x4c, 0xb0, 0x33, 0x92, 0xaf, 0x68, 0x4b, 0x0b, 0xaf, 0xb7, 0xe6, 0x5b, 0x3b, 0x81, 0x62, 0xc2, 0xf5, 0x2b, 0xf9, 0x31, 0xb8, 0xe7, 0x7a, 0xaa, 0x82, 0xa3, 0x66, 0x30, 0x64, 0x30, 0x12, 0x06, 0x03, 0x55, 0x1d, 0x [1738802745.942] [9484:9486] [DMG] ] (463 bytes) [1738802745.942] [9484:9486] [DMG] }, [1738802745.942] [9484:9486] [DMG] }, [1738802745.942] [9484:9486] [DMG] [1738802745.942] [9484:9486] [DMG] }, [1738802745.942] [9484:9486] [DMG] [1738802745.942] [9484:9486] [DMG] ], [1738802745.942] [9484:9486] [DMG] [1738802745.942] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802745.942] [9484:9486] [DMG] }, [1738802745.943] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1738802745.943] [9484:9486] [CTL] Received certificate chain from the device [1738802745.943] [9484:9486] [CTL] Successfully finished commissioning step 'SendPAICertificateRequest' [1738802745.943] [9484:9486] [CTL] Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1738802745.943] [9484:9486] [CTL] Performing next commissioning step 'SendDACCertificateRequest' [1738802745.943] [9484:9486] [CTL] Sending request for DAC certificate [1738802745.943] [9484:9486] [CTL] Sending Certificate Chain request to 0xffff9800b710 device [1738802745.943] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802745.943] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802745.944] [9484:9486] [EM] <<< [E:51494i S:24305 M:566864] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) [1738802745.945] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802745.945] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802746.027] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802746.031] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802746.031] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802746.120] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802746.120] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802746.209] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802746.209] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802746.209] [9484:9486] [EM] >>> [E:51494i S:24305 M:50946127] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:555) [1738802746.209] [9484:9486] [EM] Found matching exchange: 51494i, Delegate: 0xffff98008368 [1738802746.210] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802746.210] [9484:9486] [DMG] InvokeResponseMessage = [1738802746.210] [9484:9486] [DMG] { [1738802746.210] [9484:9486] [DMG] suppressResponse = false, [1738802746.210] [9484:9486] [DMG] InvokeResponseIBs = [1738802746.210] [9484:9486] [DMG] [ [1738802746.210] [9484:9486] [DMG] InvokeResponseIB = [1738802746.210] [9484:9486] [DMG] { [1738802746.210] [9484:9486] [DMG] CommandDataIB = [1738802746.210] [9484:9486] [DMG] { [1738802746.210] [9484:9486] [DMG] CommandPathIB = [1738802746.211] [9484:9486] [DMG] { [1738802746.211] [9484:9486] [DMG] EndpointId = 0x0, [1738802746.211] [9484:9486] [DMG] ClusterId = 0x3e, [1738802746.211] [9484:9486] [DMG] CommandId = 0x3, [1738802746.211] [9484:9486] [DMG] }, [1738802746.211] [9484:9486] [DMG] [1738802746.211] [9484:9486] [DMG] CommandFields = [1738802746.211] [9484:9486] [DMG] { [1738802746.211] [9484:9486] [DMG] 0x0 = [ [1738802746.212] [9484:9486] [DMG] 0x30, 0x82, 0x01, 0xe7, 0x30, 0x82, 0x01, 0x8d, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x07, 0x21, 0xf6, 0x6d, 0xf5, 0x1d, 0x72, 0x58, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x53, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x2f, 0x30, 0x78, 0x38, 0x30, 0x30, 0x36, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x36, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x5e, 0x91, 0xed, 0x92, 0x50, 0x16, 0xcc, 0x82, 0x67, 0x19, 0x7a, 0x09, 0x0b, 0x75, 0x33, 0xe5, 0x54, 0xe2, 0x5c, 0x8b, 0x09, 0x3c, 0xdd, 0xca, 0x9e, 0xaf, 0xcb, 0xa7, 0x47, 0xfe, 0xb8, 0xb5, 0xb8, 0xdc, 0x78, 0x9f, 0x0f, 0x9d, 0xb0, 0xd8, 0x [1738802746.212] [9484:9486] [DMG] ] (491 bytes) [1738802746.212] [9484:9486] [DMG] }, [1738802746.212] [9484:9486] [DMG] }, [1738802746.212] [9484:9486] [DMG] [1738802746.212] [9484:9486] [DMG] }, [1738802746.212] [9484:9486] [DMG] [1738802746.213] [9484:9486] [DMG] ], [1738802746.213] [9484:9486] [DMG] [1738802746.213] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802746.213] [9484:9486] [DMG] }, [1738802746.213] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1738802746.213] [9484:9486] [CTL] Received certificate chain from the device [1738802746.213] [9484:9486] [CTL] Successfully finished commissioning step 'SendDACCertificateRequest' [1738802746.213] [9484:9486] [CTL] Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1738802746.214] [9484:9486] [CTL] Performing next commissioning step 'SendAttestationRequest' [1738802746.214] [9484:9486] [CTL] Sending Attestation Request to the device. [1738802746.214] [9484:9486] [CTL] Sending Attestation request to 0xffff9800b710 device [1738802746.214] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802746.214] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802746.214] [9484:9486] [EM] <<< [E:51495i S:24305 M:566865] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:94) [1738802746.215] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802746.215] [9484:9486] [CTL] Sent Attestation request, waiting for the Attestation Information [1738802746.215] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802746.343] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802746.346] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802746.346] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802746.435] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802746.436] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802746.526] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802746.526] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802746.526] [9484:9486] [EM] >>> [E:51495i S:24305 M:50946128] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:716) [1738802746.526] [9484:9486] [EM] Found matching exchange: 51495i, Delegate: 0xffff980081b8 [1738802746.527] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802746.527] [9484:9486] [DMG] InvokeResponseMessage = [1738802746.527] [9484:9486] [DMG] { [1738802746.527] [9484:9486] [DMG] suppressResponse = false, [1738802746.527] [9484:9486] [DMG] InvokeResponseIBs = [1738802746.527] [9484:9486] [DMG] [ [1738802746.527] [9484:9486] [DMG] InvokeResponseIB = [1738802746.527] [9484:9486] [DMG] { [1738802746.527] [9484:9486] [DMG] CommandDataIB = [1738802746.527] [9484:9486] [DMG] { [1738802746.527] [9484:9486] [DMG] CommandPathIB = [1738802746.527] [9484:9486] [DMG] { [1738802746.528] [9484:9486] [DMG] EndpointId = 0x0, [1738802746.528] [9484:9486] [DMG] ClusterId = 0x3e, [1738802746.528] [9484:9486] [DMG] CommandId = 0x1, [1738802746.528] [9484:9486] [DMG] }, [1738802746.528] [9484:9486] [DMG] [1738802746.528] [9484:9486] [DMG] CommandFields = [1738802746.528] [9484:9486] [DMG] { [1738802746.528] [9484:9486] [DMG] 0x0 = [ [1738802746.529] [9484:9486] [DMG] 0x15, 0x31, 0x01, 0x1d, 0x02, 0x30, 0x82, 0x02, 0x19, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x82, 0x02, 0x0a, 0x30, 0x82, 0x02, 0x06, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x82, 0x01, 0x71, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x82, 0x01, 0x62, 0x04, 0x82, 0x01, 0x5e, 0x15, 0x24, 0x00, 0x01, 0x25, 0x01, 0xf1, 0xff, 0x36, 0x02, 0x05, 0x00, 0x80, 0x05, 0x01, 0x80, 0x05, 0x02, 0x80, 0x05, 0x03, 0x80, 0x05, 0x04, 0x80, 0x05, 0x05, 0x80, 0x05, 0x06, 0x80, 0x05, 0x07, 0x80, 0x05, 0x08, 0x80, 0x05, 0x09, 0x80, 0x05, 0x0a, 0x80, 0x05, 0x0b, 0x80, 0x05, 0x0c, 0x80, 0x05, 0x0d, 0x80, 0x05, 0x0e, 0x80, 0x05, 0x0f, 0x80, 0x05, 0x10, 0x80, 0x05, 0x11, 0x80, 0x05, 0x12, 0x80, 0x05, 0x13, 0x80, 0x05, 0x14, 0x80, 0x05, 0x15, 0x80, 0x05, 0x16, 0x80, 0x05, 0x17, 0x80, 0x05, 0x18, 0x80, 0x05, 0x19, 0x80, 0x05, 0x1a, 0x80, 0x05, 0x1b, 0x80, 0x05, 0x1c, 0x80, 0x05, 0x1d, 0x80, 0x05, 0x1e, 0x80, 0x05, 0x1f, 0x80, 0x05, 0x20, 0x80, 0x05, 0x21, 0x80, 0x05, 0x22, 0x80, 0x05, 0x23, 0x80, 0x05, 0x24, 0x80, 0x05, 0x25, 0x80, 0x05, 0x26, 0x80, 0x05, 0x27, 0x80, 0x05, 0x28, 0x80, 0x05, 0x29, 0x80, 0x05, 0x2a, 0x80, 0x05, 0x2b, 0x80, 0x05, 0x2c, 0x80, 0x05, 0x2d, 0x80, 0x05, 0x2e, 0x80, 0x05, 0x2f, 0x80, 0x05, 0x30, 0x80, 0x05, 0x31, 0x80, 0x05, 0x32, 0x80, 0x05, 0x33, 0x80, 0x05, 0x34, 0x80, 0x05, 0x35, 0x80, 0x05, 0x36, 0x80, 0x05, 0x37, 0x80, 0x05, 0x38, 0x80, 0x05, 0x39, 0x80, 0x05, 0x3a, 0x80, 0x05, 0x3b, 0x80, 0x05, 0x3c, 0x80, 0x05, 0x3d, 0x80, 0x05, 0x3e, 0x80, 0x05, 0x3f, 0x80, 0x05, 0x40, 0x80, 0x05, 0x41, 0x80, 0x05, 0x42, 0x80, 0x05, 0x43, 0x80, 0x [1738802746.529] [9484:9486] [DMG] ] (585 bytes) [1738802746.529] [9484:9486] [DMG] 0x1 = [ [1738802746.529] [9484:9486] [DMG] 0x67, 0x66, 0xfa, 0xeb, 0xea, 0x63, 0x15, 0x96, 0x16, 0xba, 0xd9, 0x4c, 0xf8, 0x9c, 0xb8, 0x4d, 0x30, 0xba, 0xd1, 0x07, 0x1f, 0xa5, 0x51, 0xde, 0xdb, 0x45, 0x66, 0x0d, 0x9a, 0x38, 0x0e, 0x4f, 0xda, 0x08, 0x9e, 0xdf, 0x57, 0xd7, 0xf4, 0xfc, 0x3e, 0xeb, 0x42, 0xc4, 0xd4, 0xeb, 0xd7, 0x50, 0x4a, 0xf8, 0x9b, 0x05, 0xfc, 0xa3, 0xf0, 0x56, 0x4d, 0x9f, 0x03, 0x85, 0xdd, 0xef, 0x02, 0x9a, [1738802746.529] [9484:9486] [DMG] ] (64 bytes) [1738802746.529] [9484:9486] [DMG] }, [1738802746.529] [9484:9486] [DMG] }, [1738802746.529] [9484:9486] [DMG] [1738802746.529] [9484:9486] [DMG] }, [1738802746.530] [9484:9486] [DMG] [1738802746.530] [9484:9486] [DMG] ], [1738802746.530] [9484:9486] [DMG] [1738802746.530] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802746.530] [9484:9486] [DMG] }, [1738802746.530] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1738802746.530] [9484:9486] [CTL] Received Attestation Information from the device [1738802746.531] [9484:9486] [CTL] Successfully finished commissioning step 'SendAttestationRequest' [1738802746.531] [9484:9486] [CTL] AutoCommissioner setting attestationElements buffer size 585/585 [1738802746.531] [9484:9486] [CTL] Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1738802746.531] [9484:9486] [CTL] Performing next commissioning step 'AttestationVerification' [1738802746.531] [9484:9486] [CTL] Verifying attestation [1738802746.554] [9484:9486] [CTL] Successfully finished commissioning step 'AttestationVerification' [1738802746.554] [9484:9486] [CTL] Commissioning stage next step: 'AttestationVerification' -> 'AttestationRevocationCheck' [1738802746.554] [9484:9486] [CTL] Performing next commissioning step 'AttestationRevocationCheck' [1738802746.554] [9484:9486] [CTL] Verifying device's DAC chain revocation status [1738802746.554] [9484:9486] [CTL] Successfully validated 'Attestation Information' command received from the device. [1738802746.554] [9484:9486] [CTL] Successfully finished commissioning step 'AttestationRevocationCheck' [1738802746.554] [9484:9486] [CTL] Commissioning stage next step: 'AttestationRevocationCheck' -> 'SendOpCertSigningRequest' [1738802746.554] [9484:9486] [CTL] Performing next commissioning step 'SendOpCertSigningRequest' [1738802746.554] [9484:9486] [CTL] Sending CSR request to 0xffff9800b710 device [1738802746.554] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802746.554] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802746.554] [9484:9486] [EM] <<< [E:51496i S:24305 M:566866] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:94) [1738802746.555] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802746.555] [9484:9486] [CTL] Sent CSR request, waiting for the CSR [1738802746.555] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802746.657] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802746.795] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802746.796] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802746.885] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802746.885] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802746.885] [9484:9486] [EM] >>> [E:51496i S:24305 M:50946129] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:392) [1738802746.885] [9484:9486] [EM] Found matching exchange: 51496i, Delegate: 0xffff98008368 [1738802746.885] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802746.885] [9484:9486] [DMG] InvokeResponseMessage = [1738802746.885] [9484:9486] [DMG] { [1738802746.885] [9484:9486] [DMG] suppressResponse = false, [1738802746.886] [9484:9486] [DMG] InvokeResponseIBs = [1738802746.886] [9484:9486] [DMG] [ [1738802746.886] [9484:9486] [DMG] InvokeResponseIB = [1738802746.886] [9484:9486] [DMG] { [1738802746.886] [9484:9486] [DMG] CommandDataIB = [1738802746.886] [9484:9486] [DMG] { [1738802746.886] [9484:9486] [DMG] CommandPathIB = [1738802746.886] [9484:9486] [DMG] { [1738802746.886] [9484:9486] [DMG] EndpointId = 0x0, [1738802746.886] [9484:9486] [DMG] ClusterId = 0x3e, [1738802746.886] [9484:9486] [DMG] CommandId = 0x5, [1738802746.886] [9484:9486] [DMG] }, [1738802746.887] [9484:9486] [DMG] [1738802746.887] [9484:9486] [DMG] CommandFields = [1738802746.887] [9484:9486] [DMG] { [1738802746.887] [9484:9486] [DMG] 0x0 = [ [1738802746.887] [9484:9486] [DMG] 0x15, 0x30, 0x01, 0xdd, 0x30, 0x81, 0xda, 0x30, 0x81, 0x81, 0x02, 0x01, 0x00, 0x30, 0x0e, 0x31, 0x0c, 0x30, 0x0a, 0x06, 0x03, 0x55, 0x04, 0x0b, 0x0c, 0x03, 0x43, 0x53, 0x41, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0xaf, 0x4e, 0x12, 0x59, 0xb7, 0x2b, 0xaa, 0xe5, 0x69, 0xdd, 0x66, 0xb8, 0x1f, 0x4a, 0xaf, 0x80, 0xb2, 0xa8, 0x78, 0x6d, 0xa0, 0x53, 0xe9, 0x40, 0xf1, 0x65, 0x62, 0xbe, 0xbd, 0x45, 0xbb, 0x78, 0xe2, 0xde, 0x18, 0x56, 0x1b, 0x5b, 0xfc, 0x09, 0xd6, 0x80, 0x16, 0x6f, 0x0f, 0xc3, 0x1e, 0x42, 0x28, 0x0b, 0x16, 0xe1, 0xe9, 0xd7, 0x95, 0xcf, 0xed, 0xbd, 0x7a, 0xec, 0x2a, 0x21, 0xba, 0x46, 0xa0, 0x11, 0x30, 0x0f, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x09, 0x0e, 0x31, 0x02, 0x30, 0x00, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x03, 0x48, 0x00, 0x30, 0x45, 0x02, 0x21, 0x00, 0x92, 0x20, 0x43, 0x05, 0xd6, 0xdd, 0x87, 0xe7, 0x28, 0xdf, 0x4d, 0xf9, 0xd3, 0x48, 0xb5, 0x11, 0x72, 0x79, 0x0e, 0x02, 0xad, 0x87, 0x07, 0xa9, 0x41, 0x5e, 0xbe, 0x24, 0x35, 0x67, 0x05, 0xbb, 0x02, 0x20, 0x5a, 0x99, 0xd0, 0xd5, 0x35, 0x95, 0x27, 0x39, 0xd3, 0x07, 0xf5, 0xb1, 0xc1, 0xb4, 0x95, 0xca, 0x93, 0x01, 0xba, 0xe6, 0x94, 0x83, 0xbd, 0x8c, 0x85, 0x04, 0x2a, 0x3a, 0x45, 0x42, 0xae, 0xfd, 0x30, 0x02, 0x20, 0xe8, 0x3f, 0x67, 0x08, 0xc3, 0x69, 0x83, 0x22, 0xae, 0x84, 0xad, 0xac, 0x7c, 0x6b, 0xbf, 0x30, 0x9a, 0x12, 0x12, 0x02, 0xe1, 0x7a, 0x93, 0x65, 0x4f, 0xdd, 0x84, 0xd6, 0xc5, 0x41, 0xff, 0xe4, 0x18, [1738802746.887] [9484:9486] [DMG] ] (261 bytes) [1738802746.887] [9484:9486] [DMG] 0x1 = [ [1738802746.887] [9484:9486] [DMG] 0xe7, 0x05, 0xd6, 0x8a, 0x90, 0x35, 0xdf, 0x9a, 0x41, 0x3d, 0x4c, 0x83, 0xb8, 0x4b, 0x44, 0xff, 0xe5, 0x61, 0x74, 0xb8, 0xec, 0x74, 0x5d, 0xaa, 0xa6, 0x2c, 0x93, 0xa1, 0x54, 0xf1, 0x5c, 0x76, 0x49, 0xc6, 0xe2, 0x99, 0xf1, 0xd6, 0xc1, 0x0a, 0x8e, 0x4f, 0x42, 0x2e, 0x8c, 0xc0, 0x98, 0x48, 0x7f, 0x9b, 0x08, 0x77, 0x19, 0x52, 0xe7, 0x58, 0xf8, 0x39, 0x45, 0xaa, 0x27, 0x92, 0xf1, 0x64, [1738802746.888] [9484:9486] [DMG] ] (64 bytes) [1738802746.888] [9484:9486] [DMG] }, [1738802746.888] [9484:9486] [DMG] }, [1738802746.888] [9484:9486] [DMG] [1738802746.888] [9484:9486] [DMG] }, [1738802746.888] [9484:9486] [DMG] [1738802746.888] [9484:9486] [DMG] ], [1738802746.888] [9484:9486] [DMG] [1738802746.888] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802746.888] [9484:9486] [DMG] }, [1738802746.888] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1738802746.888] [9484:9486] [CTL] Received certificate signing request from the device [1738802746.889] [9484:9486] [CTL] Successfully finished commissioning step 'SendOpCertSigningRequest' [1738802746.889] [9484:9486] [CTL] Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR' [1738802746.889] [9484:9486] [CTL] Performing next commissioning step 'ValidateCSR' [1738802746.896] [9484:9486] [CTL] Successfully finished commissioning step 'ValidateCSR' [1738802746.896] [9484:9486] [CTL] Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain' [1738802746.896] [9484:9486] [CTL] Performing next commissioning step 'GenerateNOCChain' [1738802746.896] [9484:9486] [CTL] Getting certificate chain for the device from the issuer [1738802746.898] [9484:9486] [CTL] Verifying Certificate Signing Request [1738802746.900] [9484:9486] [CTL] Generating NOC [1738802746.900] [9484:9486] [CTL] Providing certificate chain to the commissioner [1738802746.900] [9484:9486] [CTL] Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:395: Success [1738802746.900] [9484:9486] [CTL] Successfully finished commissioning step 'GenerateNOCChain' [1738802746.901] [9484:9486] [CTL] Performing next commissioning step 'SendTrustedRootCert' [1738802746.901] [9484:9486] [CTL] Sending root certificate to the device [1738802746.901] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802746.901] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802746.901] [9484:9486] [EM] <<< [E:51497i S:24305 M:566867] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:293) [1738802746.901] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802746.901] [9484:9486] [CTL] Sent root certificate to the device [1738802746.902] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802746.973] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802747.107] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802747.153] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802747.154] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802747.154] [9484:9486] [EM] >>> [E:51497i S:24305 M:50946130] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:63) [1738802747.154] [9484:9486] [EM] Found matching exchange: 51497i, Delegate: 0xffff980081b8 [1738802747.154] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802747.154] [9484:9486] [DMG] InvokeResponseMessage = [1738802747.154] [9484:9486] [DMG] { [1738802747.154] [9484:9486] [DMG] suppressResponse = false, [1738802747.154] [9484:9486] [DMG] InvokeResponseIBs = [1738802747.154] [9484:9486] [DMG] [ [1738802747.155] [9484:9486] [DMG] InvokeResponseIB = [1738802747.155] [9484:9486] [DMG] { [1738802747.155] [9484:9486] [DMG] CommandStatusIB = [1738802747.155] [9484:9486] [DMG] { [1738802747.155] [9484:9486] [DMG] CommandPathIB = [1738802747.155] [9484:9486] [DMG] { [1738802747.155] [9484:9486] [DMG] EndpointId = 0x0, [1738802747.155] [9484:9486] [DMG] ClusterId = 0x3e, [1738802747.155] [9484:9486] [DMG] CommandId = 0xb, [1738802747.155] [9484:9486] [DMG] }, [1738802747.155] [9484:9486] [DMG] [1738802747.155] [9484:9486] [DMG] StatusIB = [1738802747.155] [9484:9486] [DMG] { [1738802747.156] [9484:9486] [DMG] status = 0x00 (SUCCESS), [1738802747.156] [9484:9486] [DMG] }, [1738802747.156] [9484:9486] [DMG] [1738802747.156] [9484:9486] [DMG] }, [1738802747.156] [9484:9486] [DMG] [1738802747.156] [9484:9486] [DMG] }, [1738802747.156] [9484:9486] [DMG] [1738802747.156] [9484:9486] [DMG] ], [1738802747.156] [9484:9486] [DMG] [1738802747.156] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802747.156] [9484:9486] [DMG] }, [1738802747.156] [9484:9486] [DMG] Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1738802747.156] [9484:9486] [CTL] Device confirmed that it has received the root certificate [1738802747.156] [9484:9486] [CTL] Successfully finished commissioning step 'SendTrustedRootCert' [1738802747.157] [9484:9486] [CTL] Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC' [1738802747.157] [9484:9486] [CTL] Performing next commissioning step 'SendNOC' [1738802747.157] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802747.157] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802747.157] [9484:9486] [EM] <<< [E:51498i S:24305 M:566868] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:566) [1738802747.158] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802747.158] [9484:9486] [CTL] Sent operational certificate to the device [1738802747.158] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802747.242] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802747.332] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802747.467] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802747.649] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802747.649] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802747.649] [9484:9486] [EM] >>> [E:51498i S:24305 M:50946131] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1738802747.650] [9484:9486] [EM] Found matching exchange: 51498i, Delegate: 0xffff98008368 [1738802747.650] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802747.650] [9484:9486] [DMG] InvokeResponseMessage = [1738802747.650] [9484:9486] [DMG] { [1738802747.650] [9484:9486] [DMG] suppressResponse = false, [1738802747.650] [9484:9486] [DMG] InvokeResponseIBs = [1738802747.650] [9484:9486] [DMG] [ [1738802747.650] [9484:9486] [DMG] InvokeResponseIB = [1738802747.650] [9484:9486] [DMG] { [1738802747.650] [9484:9486] [DMG] CommandDataIB = [1738802747.650] [9484:9486] [DMG] { [1738802747.650] [9484:9486] [DMG] CommandPathIB = [1738802747.650] [9484:9486] [DMG] { [1738802747.651] [9484:9486] [DMG] EndpointId = 0x0, [1738802747.651] [9484:9486] [DMG] ClusterId = 0x3e, [1738802747.651] [9484:9486] [DMG] CommandId = 0x8, [1738802747.651] [9484:9486] [DMG] }, [1738802747.651] [9484:9486] [DMG] [1738802747.651] [9484:9486] [DMG] CommandFields = [1738802747.651] [9484:9486] [DMG] { [1738802747.651] [9484:9486] [DMG] 0x0 = 0 (unsigned), [1738802747.651] [9484:9486] [DMG] 0x1 = 1 (unsigned), [1738802747.651] [9484:9486] [DMG] }, [1738802747.651] [9484:9486] [DMG] }, [1738802747.651] [9484:9486] [DMG] [1738802747.652] [9484:9486] [DMG] }, [1738802747.652] [9484:9486] [DMG] [1738802747.652] [9484:9486] [DMG] ], [1738802747.652] [9484:9486] [DMG] [1738802747.652] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802747.652] [9484:9486] [DMG] }, [1738802747.652] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1738802747.652] [9484:9486] [CTL] Device returned status 0 on receiving the NOC [1738802747.652] [9484:9486] [CTL] Operational credentials provisioned on device 0xffff9800b710 [1738802747.652] [9484:9486] [TOO] Secure Pairing Success [1738802747.652] [9484:9486] [TOO] CASE establishment successful [1738802747.652] [9484:9486] [CTL] Successfully finished commissioning step 'SendNOC' [1738802747.653] [9484:9486] [CTL] No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks [1738802747.653] [9484:9486] [CTL] Commissioning stage next step: 'SendNOC' -> 'ThreadNetworkSetup' [1738802747.653] [9484:9486] [CTL] Performing next commissioning step 'ThreadNetworkSetup' [1738802747.653] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802747.653] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802747.653] [9484:9486] [EM] <<< [E:51499i S:24305 M:566869] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:160) [1738802747.654] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802747.654] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802747.783] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802747.785] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802747.785] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802747.785] [9484:9486] [EM] >>> [E:51499i S:24305 M:50946132] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1738802747.785] [9484:9486] [EM] Found matching exchange: 51499i, Delegate: 0xffff980081b8 [1738802747.785] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802747.786] [9484:9486] [DMG] InvokeResponseMessage = [1738802747.786] [9484:9486] [DMG] { [1738802747.786] [9484:9486] [DMG] suppressResponse = false, [1738802747.786] [9484:9486] [DMG] InvokeResponseIBs = [1738802747.786] [9484:9486] [DMG] [ [1738802747.786] [9484:9486] [DMG] InvokeResponseIB = [1738802747.786] [9484:9486] [DMG] { [1738802747.786] [9484:9486] [DMG] CommandDataIB = [1738802747.786] [9484:9486] [DMG] { [1738802747.786] [9484:9486] [DMG] CommandPathIB = [1738802747.786] [9484:9486] [DMG] { [1738802747.786] [9484:9486] [DMG] EndpointId = 0x0, [1738802747.786] [9484:9486] [DMG] ClusterId = 0x31, [1738802747.787] [9484:9486] [DMG] CommandId = 0x5, [1738802747.787] [9484:9486] [DMG] }, [1738802747.787] [9484:9486] [DMG] [1738802747.787] [9484:9486] [DMG] CommandFields = [1738802747.787] [9484:9486] [DMG] { [1738802747.787] [9484:9486] [DMG] 0x0 = 0 (unsigned), [1738802747.787] [9484:9486] [DMG] 0x2 = 0 (unsigned), [1738802747.787] [9484:9486] [DMG] }, [1738802747.787] [9484:9486] [DMG] }, [1738802747.787] [9484:9486] [DMG] [1738802747.787] [9484:9486] [DMG] }, [1738802747.787] [9484:9486] [DMG] [1738802747.787] [9484:9486] [DMG] ], [1738802747.788] [9484:9486] [DMG] [1738802747.788] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802747.788] [9484:9486] [DMG] }, [1738802747.788] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005 [1738802747.788] [9484:9486] [CTL] Received NetworkConfig response, networkingStatus=0 [1738802747.788] [9484:9486] [CTL] Successfully finished commissioning step 'ThreadNetworkSetup' [1738802747.788] [9484:9486] [CTL] Commissioning stage next step: 'ThreadNetworkSetup' -> 'FailsafeBeforeThreadEnable' [1738802747.788] [9484:9486] [CTL] Performing next commissioning step 'FailsafeBeforeThreadEnable' [1738802747.788] [9484:9486] [CTL] Arming failsafe (108 seconds) [1738802747.788] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802747.789] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802747.789] [9484:9486] [EM] <<< [E:51500i S:24305 M:566870] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) [1738802747.790] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802747.790] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802747.917] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802747.919] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802747.919] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802747.920] [9484:9486] [EM] >>> [E:51500i S:24305 M:50946133] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1738802747.920] [9484:9486] [EM] Found matching exchange: 51500i, Delegate: 0xffff98008368 [1738802747.920] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802747.920] [9484:9486] [DMG] InvokeResponseMessage = [1738802747.920] [9484:9486] [DMG] { [1738802747.920] [9484:9486] [DMG] suppressResponse = false, [1738802747.920] [9484:9486] [DMG] InvokeResponseIBs = [1738802747.920] [9484:9486] [DMG] [ [1738802747.920] [9484:9486] [DMG] InvokeResponseIB = [1738802747.921] [9484:9486] [DMG] { [1738802747.921] [9484:9486] [DMG] CommandDataIB = [1738802747.921] [9484:9486] [DMG] { [1738802747.921] [9484:9486] [DMG] CommandPathIB = [1738802747.921] [9484:9486] [DMG] { [1738802747.921] [9484:9486] [DMG] EndpointId = 0x0, [1738802747.921] [9484:9486] [DMG] ClusterId = 0x30, [1738802747.921] [9484:9486] [DMG] CommandId = 0x1, [1738802747.921] [9484:9486] [DMG] }, [1738802747.921] [9484:9486] [DMG] [1738802747.921] [9484:9486] [DMG] CommandFields = [1738802747.921] [9484:9486] [DMG] { [1738802747.921] [9484:9486] [DMG] 0x0 = 0 (unsigned), [1738802747.922] [9484:9486] [DMG] 0x1 = "" (0 chars), [1738802747.922] [9484:9486] [DMG] }, [1738802747.922] [9484:9486] [DMG] }, [1738802747.922] [9484:9486] [DMG] [1738802747.922] [9484:9486] [DMG] }, [1738802747.922] [9484:9486] [DMG] [1738802747.922] [9484:9486] [DMG] ], [1738802747.922] [9484:9486] [DMG] [1738802747.922] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802747.922] [9484:9486] [DMG] }, [1738802747.922] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1738802747.923] [9484:9486] [CTL] Received ArmFailSafe response errorCode=0 [1738802747.923] [9484:9486] [CTL] Successfully finished commissioning step 'FailsafeBeforeThreadEnable' [1738802747.923] [9484:9486] [CTL] Commissioning stage next step: 'FailsafeBeforeThreadEnable' -> 'ThreadNetworkEnable' [1738802747.923] [9484:9486] [CTL] Performing next commissioning step 'ThreadNetworkEnable' [1738802747.923] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802747.923] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802747.923] [9484:9486] [EM] <<< [E:51501i S:24305 M:566871] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:73) [1738802747.924] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802747.924] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802748.007] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802749.404] [9484:9485] [DL] Indication received, conn = 0xffffa00bd5f0 [1738802749.404] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16391 [1738802749.405] [9484:9486] [EM] >>> [E:51501i S:24305 M:50946134] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:65) [1738802749.405] [9484:9486] [EM] Found matching exchange: 51501i, Delegate: 0xffff980081b8 [1738802749.405] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802749.405] [9484:9486] [DMG] InvokeResponseMessage = [1738802749.405] [9484:9486] [DMG] { [1738802749.405] [9484:9486] [DMG] suppressResponse = false, [1738802749.405] [9484:9486] [DMG] InvokeResponseIBs = [1738802749.405] [9484:9486] [DMG] [ [1738802749.405] [9484:9486] [DMG] InvokeResponseIB = [1738802749.405] [9484:9486] [DMG] { [1738802749.405] [9484:9486] [DMG] CommandDataIB = [1738802749.405] [9484:9486] [DMG] { [1738802749.405] [9484:9486] [DMG] CommandPathIB = [1738802749.406] [9484:9486] [DMG] { [1738802749.406] [9484:9486] [DMG] EndpointId = 0x0, [1738802749.406] [9484:9486] [DMG] ClusterId = 0x31, [1738802749.406] [9484:9486] [DMG] CommandId = 0x7, [1738802749.406] [9484:9486] [DMG] }, [1738802749.406] [9484:9486] [DMG] [1738802749.406] [9484:9486] [DMG] CommandFields = [1738802749.406] [9484:9486] [DMG] { [1738802749.406] [9484:9486] [DMG] 0x0 = 0 (unsigned), [1738802749.406] [9484:9486] [DMG] 0x2 = NULL [1738802749.406] [9484:9486] [DMG] }, [1738802749.407] [9484:9486] [DMG] }, [1738802749.407] [9484:9486] [DMG] [1738802749.407] [9484:9486] [DMG] }, [1738802749.407] [9484:9486] [DMG] [1738802749.407] [9484:9486] [DMG] ], [1738802749.407] [9484:9486] [DMG] [1738802749.407] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802749.407] [9484:9486] [DMG] }, [1738802749.407] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007 [1738802749.407] [9484:9486] [CTL] Received ConnectNetwork response, networkingStatus=0 [1738802749.407] [9484:9486] [CTL] Successfully finished commissioning step 'ThreadNetworkEnable' [1738802749.407] [9484:9486] [CTL] Commissioning stage next step: 'ThreadNetworkEnable' -> 'kEvictPreviousCaseSessions' [1738802749.407] [9484:9486] [CTL] Performing next commissioning step 'kEvictPreviousCaseSessions' [1738802749.408] [9484:9486] [IN] Expiring all sessions for node <0000000000000010, 1>!! [1738802749.408] [9484:9486] [CTL] Successfully finished commissioning step 'kEvictPreviousCaseSessions' [1738802749.408] [9484:9486] [CTL] Commissioning stage next step: 'kEvictPreviousCaseSessions' -> 'kFindOperationalForStayActive' [1738802749.408] [9484:9486] [CTL] Performing next commissioning step 'kFindOperationalForStayActive' [1738802749.408] [9484:9486] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000010] [1738802749.408] [9484:9486] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1738802749.408] [9484:9486] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 1 --> 2 [1738802749.408] [9484:9486] [DIS] Resolving CC0161AC8CF43C47:0000000000000010 ... [1738802749.410] [9484:9486] [DIS] Lookup started for CC0161AC8CF43C47-0000000000000010 [1738802749.410] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802749.609] [9484:9486] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 201 ms [1738802750.940] [9484:9486] [DL] Avahi resolve found [1738802750.941] [9484:9486] [DIS] Node ID resolved for CC0161AC8CF43C47-0000000000000010 [1738802750.941] [9484:9486] [DIS] Hostname: 523E0F663D7E3165 [1738802750.941] [9484:9486] [DIS] IP Address #1: fd11:22::2d89:b999:cf04:c2b9 [1738802750.941] [9484:9486] [DIS] Port: 5540 [1738802750.941] [9484:9486] [DIS] Mrp Interval idle: 3000 ms [1738802750.941] [9484:9486] [DIS] Mrp Interval active: 2200 ms [1738802750.941] [9484:9486] [DIS] Mrp Active Threshold: 300 ms [1738802750.941] [9484:9486] [DIS] TCP Client Supported: 0 [1738802750.941] [9484:9486] [DIS] TCP Server Supported: 0 [1738802750.941] [9484:9486] [DIS] ICD: not present [1738802750.942] [9484:9486] [DIS] Lookup clearing interface for non LL address [1738802750.942] [9484:9486] [DIS] UDP:[fd11:22::2d89:b999:cf04:c2b9%eth0]:5540: new best score: 5 (for CC0161AC8CF43C47-0000000000000010) [1738802750.942] [9484:9486] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 1535 ms [1738802750.944] [9484:9486] [DIS] OperationalSessionSetup[1:0000000000000010]: Updating device address to UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540 while in state 2 [1738802750.944] [9484:9486] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 2 --> 3 [1738802750.944] [9484:9486] [IN] SecureSession[0xffffa0030af0]: Allocated Type:2 LSID:24306 [1738802750.945] [9484:9486] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000010 [1738802750.946] [9484:9486] [EM] <<< [E:51502i S:0 M:95916618] (U) Msg TX from 33AEC79CC0AAAC47 to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) [1738802750.946] [9484:9486] [EM] ??1 [E:51502i S:0 M:95916618] (U) Msg Retransmission to 0:0000000000000000 in 3323ms [State:Idle II:3000 AI:2200 AT:300] [1738802750.946] [9484:9486] [SC] Sent Sigma1 msg to <0000000000000010, 1> [II:500ms AI:300ms AT:4000ms] [1738802750.946] [9484:9486] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 3 --> 4 [1738802751.107] [9484:9486] [EM] >>> [E:51502i S:0 M:123259091 (Ack:95916618)] (U) Msg RX from 0:0000000000000000 [0000] to 33AEC79CC0AAAC47 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802751.107] [9484:9486] [EM] Found matching exchange: 51502i, Delegate: 0xffff98010c88 [1738802751.107] [9484:9486] [EM] Rxd Ack; Removing MessageCounter:95916618 from Retrans Table on exchange 51502i [1738802751.307] [9484:9486] [EM] >>> [E:51502i S:0 M:123259092 (Ack:95916618)] (U) Msg RX from 0:0000000000000000 [0000] to 33AEC79CC0AAAC47 --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751) [1738802751.307] [9484:9486] [EM] Found matching exchange: 51502i, Delegate: 0xffff98010c88 [1738802751.307] [9484:9486] [EM] CHIP MessageCounter:95916618 not in RetransTable on exchange 51502i [1738802751.307] [9484:9486] [SC] Received Sigma2 msg [1738802751.307] [9484:9486] [SC] Peer assigned session session ID 57841 [1738802751.315] [9484:9486] [SC] Found MRP parameters in the message [1738802751.315] [9484:9486] [SC] Sending Sigma3 [1738802751.317] [9484:9486] [EM] <<< [E:51502i S:0 M:95916619 (Ack:123259092)] (U) Msg TX from 33AEC79CC0AAAC47 to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1738802751.317] [9484:9486] [EM] ??1 [E:51502i S:0 M:95916619] (U) Msg Retransmission to 0:0000000000000000 in 2664ms [State:Active II:3000 AI:2200 AT:300] [1738802751.317] [9484:9486] [SC] Sent Sigma3 msg [1738802751.914] [9484:9486] [EM] >>> [E:51502i S:0 M:123259093 (Ack:95916619)] (U) Msg RX from 0:0000000000000000 [0000] to 33AEC79CC0AAAC47 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802751.914] [9484:9486] [EM] Found matching exchange: 51502i, Delegate: 0xffff98010c88 [1738802751.914] [9484:9486] [EM] Rxd Ack; Removing MessageCounter:95916619 from Retrans Table on exchange 51502i [1738802751.966] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16389 [1738802752.151] [9484:9486] [EM] >>> [E:51502i S:0 M:123259094 (Ack:95916619)] (U) Msg RX from 0:0000000000000000 [0000] to 33AEC79CC0AAAC47 --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1738802752.152] [9484:9486] [EM] Found matching exchange: 51502i, Delegate: 0xffff98010c88 [1738802752.152] [9484:9486] [EM] CHIP MessageCounter:95916619 not in RetransTable on exchange 51502i [1738802752.152] [9484:9486] [SC] Success status report received. Session was established [1738802752.158] [9484:9486] [SC] SecureSession[0xffffa0030af0, LSID:24306]: State change 'kEstablishing' --> 'kActive' [1738802752.158] [9484:9486] [IN] SecureSession[0xffffa0030af0]: Activated - Type:2 LSID:24306 [1738802752.158] [9484:9486] [IN] New secure session activated for device <0000000000000010, 1>, LSID:24306 PSID:57841! [1738802752.158] [9484:9486] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 4 --> 5 [1738802752.158] [9484:9486] [CTL] Successfully finished commissioning step 'kFindOperationalForStayActive' [1738802752.158] [9484:9486] [CTL] Commissioning stage next step: 'kFindOperationalForStayActive' -> 'ICDSendStayActive' [1738802752.158] [9484:9486] [CTL] Performing next commissioning step 'ICDSendStayActive' [1738802752.158] [9484:9486] [CTL] Skipping kICDSendStayActive [1738802752.158] [9484:9486] [CTL] Successfully finished commissioning step 'ICDSendStayActive' [1738802752.158] [9484:9486] [CTL] Commissioning stage next step: 'ICDSendStayActive' -> 'kFindOperationalForCommissioningComplete' [1738802752.159] [9484:9486] [CTL] Performing next commissioning step 'kFindOperationalForCommissioningComplete' [1738802752.159] [9484:9486] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000010] [1738802752.159] [9484:9486] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1738802752.159] [9484:9486] [DIS] Found an existing secure session to [1:0000000000000010]! [1738802752.159] [9484:9486] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 1 --> 5 [1738802752.159] [9484:9486] [CTL] Successfully finished commissioning step 'kFindOperationalForCommissioningComplete' [1738802752.159] [9484:9486] [CTL] Commissioning stage next step: 'kFindOperationalForCommissioningComplete' -> 'SendComplete' [1738802752.159] [9484:9486] [CTL] Performing next commissioning step 'SendComplete' [1738802752.159] [9484:9486] [DMG] ICR moving to [AddingComm] [1738802752.159] [9484:9486] [DMG] ICR moving to [AddedComma] [1738802752.160] [9484:9486] [EM] <<< [E:51503i S:24306 M:29319770] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:59) [1738802752.160] [9484:9486] [EM] ??1 [E:51503i S:24306 M:29319770] (S) Msg Retransmission to 1:0000000000000010 in 2702ms [State:Active II:3000 AI:2200 AT:300] [1738802752.160] [9484:9486] [DMG] ICR moving to [AwaitingRe] [1738802752.160] [9484:9486] [EM] <<< [E:51502i S:0 M:95916620 (Ack:123259094)] (U) Msg TX from 33AEC79CC0AAAC47 to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802752.161] [9484:9486] [EM] Flushed pending ack for MessageCounter:123259094 on exchange 51502i [1738802752.161] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 32792 [1738802752.401] [9484:9486] [EM] >>> [E:51503i S:24306 M:42767995 (Ack:29319770)] (S) Msg RX from 1:0000000000000010 [3C47] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) [1738802752.401] [9484:9486] [EM] Found matching exchange: 51503i, Delegate: 0xffff980081b8 [1738802752.401] [9484:9486] [EM] Rxd Ack; Removing MessageCounter:29319770 from Retrans Table on exchange 51503i [1738802752.401] [9484:9486] [DMG] ICR moving to [ResponseRe] [1738802752.401] [9484:9486] [DMG] InvokeResponseMessage = [1738802752.401] [9484:9486] [DMG] { [1738802752.402] [9484:9486] [DMG] suppressResponse = false, [1738802752.402] [9484:9486] [DMG] InvokeResponseIBs = [1738802752.402] [9484:9486] [DMG] [ [1738802752.402] [9484:9486] [DMG] InvokeResponseIB = [1738802752.402] [9484:9486] [DMG] { [1738802752.402] [9484:9486] [DMG] CommandDataIB = [1738802752.402] [9484:9486] [DMG] { [1738802752.402] [9484:9486] [DMG] CommandPathIB = [1738802752.402] [9484:9486] [DMG] { [1738802752.402] [9484:9486] [DMG] EndpointId = 0x0, [1738802752.402] [9484:9486] [DMG] ClusterId = 0x30, [1738802752.402] [9484:9486] [DMG] CommandId = 0x5, [1738802752.402] [9484:9486] [DMG] }, [1738802752.403] [9484:9486] [DMG] [1738802752.403] [9484:9486] [DMG] CommandFields = [1738802752.403] [9484:9486] [DMG] { [1738802752.403] [9484:9486] [DMG] 0x0 = 0 (unsigned), [1738802752.403] [9484:9486] [DMG] 0x1 = "" (0 chars), [1738802752.403] [9484:9486] [DMG] }, [1738802752.403] [9484:9486] [DMG] }, [1738802752.403] [9484:9486] [DMG] [1738802752.403] [9484:9486] [DMG] }, [1738802752.403] [9484:9486] [DMG] [1738802752.403] [9484:9486] [DMG] ], [1738802752.403] [9484:9486] [DMG] [1738802752.404] [9484:9486] [DMG] InteractionModelRevision = 11 [1738802752.404] [9484:9486] [DMG] }, [1738802752.404] [9484:9486] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0005 [1738802752.404] [9484:9486] [CTL] Received CommissioningComplete response, errorCode=0 [1738802752.404] [9484:9486] [CTL] Successfully finished commissioning step 'SendComplete' [1738802752.404] [9484:9486] [CTL] Commissioning stage next step: 'SendComplete' -> 'Cleanup' [1738802752.404] [9484:9486] [CTL] Performing next commissioning step 'Cleanup' [1738802752.404] [9484:9486] [CTL] Successfully finished commissioning step 'Cleanup' [1738802752.404] [9484:9486] [DIS] Closing all BLE connections [1738802752.404] [9484:9486] [IN] Clearing BLE pending packets. [1738802752.405] [9484:9486] [BLE] Auto-closing end point's BLE connection. [1738802752.405] [9484:9486] [DL] Closing BLE GATT connection (con 0xffffa00bd5f0) [1738802752.405] [9484:9485] [DL] Close BLE connection: peer=D4:36:BC:7A:7A:25 [1738802752.734] [9484:9486] [IN] SecureSession[0xffff9800c290]: MarkForEviction Type:1 LSID:24305 [1738802752.734] [9484:9486] [SC] SecureSession[0xffff9800c290, LSID:24305]: State change 'kActive' --> 'kPendingEviction' [1738802752.735] [9484:9486] [IN] SecureSession[0xffff9800c290]: Released - Type:1 LSID:24305 [1738802752.735] [9484:9486] [CTL] Commissioning complete for node ID 0x0000000000000010: success [1738802752.735] [9484:9486] [TOO] Device commissioning completed with success [1738802752.735] [9484:9486] [DMG] ICR moving to [AwaitingDe] [1738802752.735] [9484:9486] [EM] <<< [E:51503i S:24306 M:29319771 (Ack:42767995)] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1738802752.735] [9484:9485] [DL] BLE connection closed: conn=0xffffa00bd5f0 [1738802752.736] [9484:9486] [EM] Flushed pending ack for MessageCounter:42767995 on exchange 51503i [1738802752.736] [9484:9486] [DL] HandlePlatformSpecificBLEEvent 16390 [1738802752.736] [9484:9486] [BLE] No endpoint for unsubscribe complete [1738802752.736] [9484:9486] [BLE] No endpoint for connection error [1738802752.736] [9484:9484] [CTL] Shutting down the commissioner [1738802752.737] [9484:9484] [CTL] Shutting down the controller [1738802752.737] [9484:9484] [IN] Expiring all sessions for fabric 0x1!! [1738802752.737] [9484:9484] [IN] SecureSession[0xffffa0030af0]: MarkForEviction Type:2 LSID:24306 [1738802752.737] [9484:9484] [SC] SecureSession[0xffffa0030af0, LSID:24306]: State change 'kActive' --> 'kPendingEviction' [1738802752.737] [9484:9484] [IN] SecureSession[0xffffa0030af0]: Released - Type:2 LSID:24306 [1738802752.737] [9484:9484] [FP] Forgetting fabric 0x1 [1738802752.737] [9484:9484] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802752.737] [9484:9484] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802752.737] [9484:9484] [TS] Reverted Last Known Good Time to previous value [1738802752.738] [9484:9484] [CTL] Shutting down the commissioner [1738802752.738] [9484:9484] [CTL] Shutting down the controller [1738802752.738] [9484:9484] [CTL] Shutting down the System State, this will teardown the CHIP Stack [1738802752.738] [9484:9484] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1738802752.738] [9484:9484] [FP] Shutting down FabricTable [1738802752.738] [9484:9484] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802752.738] [9484:9484] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802752.738] [9484:9484] [TS] Reverted Last Known Good Time to previous value [1738802752.747] [9484:9484] [DL] Wrote settings to /tmp/chip_counters.ini [1738802752.747] [9484:9484] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1738802752.747] [9484:9484] [DL] Inet Layer shutdown [1738802752.747] [9484:9484] [DL] BLE Layer shutdown [1738802752.750] [9484:9484] [DL] System Layer shutdown ubuntu@ubuntu:~/apps$ ./chip-tool generalcommissioning arm-fail-safe 900 0 0x10 0 [1738802776.051] [9494:9494] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1738802776.052] [9494:9494] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1738802776.058] [9494:9494] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1738802776.058] [9494:9494] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1738802776.058] [9494:9494] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1738802776.065] [9494:9494] [DL] Wrote settings to /tmp/chip_counters.ini [1738802776.066] [9494:9494] [DL] NVS set: chip-counters/reboot-count = 14 (0xE) [1738802776.067] [9494:9494] [DL] Got Ethernet interface: eth0 [1738802776.067] [9494:9494] [DL] Found the primary Ethernet interface:eth0 [1738802776.068] [9494:9494] [DL] Got WiFi interface: wlan0 [1738802776.068] [9494:9494] [DL] Failed to reset WiFi statistic counts [1738802776.068] [9494:9494] [IN] UDP::Init bind&listen port=0 [1738802776.068] [9494:9494] [IN] UDP::Init bound to port=35226 [1738802776.068] [9494:9494] [IN] BLEBase::Init - setting/overriding transport [1738802776.068] [9494:9494] [IN] TransportMgr initialized [1738802776.068] [9494:9494] [FP] Initializing FabricTable from persistent storage [1738802776.068] [9494:9494] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802776.071] [9494:9494] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xCC0161AC8CF43C47, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1738802776.074] [9494:9494] [ZCL] Using ZAP configuration... [1738802776.080] [9494:9494] [DL] Avahi client registered [1738802776.081] [9494:9494] [IM] WARNING ┌──────────────────────────────────────────────────── [1738802776.081] [9494:9494] [IM] WARNING │ Interaction Model Engine running in 'Checked' mode. [1738802776.081] [9494:9494] [IM] WARNING │ This executes BOTH ember and data-model code paths. [1738802776.081] [9494:9494] [IM] WARNING │ which is inefficient and consumes more flash space. [1738802776.081] [9494:9494] [IM] WARNING │ This should be done for testing only. [1738802776.081] [9494:9494] [IM] WARNING └──────────────────────────────────────────────────── [1738802776.081] [9494:9494] [CTL] System State Initialized... [1738802776.081] [9494:9494] [CTL] Setting attestation nonce to random value [1738802776.081] [9494:9494] [CTL] Setting CSR nonce to random value [1738802776.081] [9494:9494] [IN] UDP::Init bind&listen port=5550 [1738802776.081] [9494:9494] [IN] UDP::Init bound to port=5550 [1738802776.081] [9494:9494] [IN] TransportMgr initialized [1738802776.082] [9494:9496] [DL] CHIP task running [1738802776.082] [9494:9496] [DL] HandlePlatformSpecificBLEEvent 32786 [1738802776.082] [9494:9496] [CTL] Setting attestation nonce to random value [1738802776.083] [9494:9496] [CTL] Setting CSR nonce to random value [1738802776.084] [9494:9496] [CTL] Generating NOC [1738802776.085] [9494:9496] [FP] Validating NOC chain [1738802776.087] [9494:9496] [FP] NOC chain validation successful [1738802776.087] [9494:9496] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1738802776.087] [9494:9496] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802776.087] [9494:9496] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1738802776.087] [9494:9496] [TS] Retaining current Last Known Good Time [1738802776.091] [9494:9496] [FP] Metadata for Fabric 0x1 persisted to storage. [1738802776.093] [9494:9496] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1738802776.095] [9494:9496] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: CC0161AC8CF43C47) [1738802776.095] [9494:9496] [IN] UDP::Init bind&listen port=5550 [1738802776.095] [9494:9496] [IN] UDP::Init bound to port=5550 [1738802776.095] [9494:9496] [IN] TransportMgr initialized [1738802776.110] [9494:9496] [TOO] Sending command to node 0x10 [1738802776.111] [9494:9496] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000010] [1738802776.111] [9494:9496] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1738802776.111] [9494:9496] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 1 --> 2 [1738802776.111] [9494:9496] [DIS] Resolving CC0161AC8CF43C47:0000000000000010 ... [1738802776.112] [9494:9496] [DIS] Lookup started for CC0161AC8CF43C47-0000000000000010 [1738802776.113] [9494:9496] [DL] Avahi resolve found [1738802776.113] [9494:9496] [DIS] Node ID resolved for CC0161AC8CF43C47-0000000000000010 [1738802776.113] [9494:9496] [DIS] Hostname: 523E0F663D7E3165 [1738802776.113] [9494:9496] [DIS] IP Address #1: fd11:22::2d89:b999:cf04:c2b9 [1738802776.113] [9494:9496] [DIS] Port: 5540 [1738802776.113] [9494:9496] [DIS] Mrp Interval idle: 3000 ms [1738802776.113] [9494:9496] [DIS] Mrp Interval active: 2200 ms [1738802776.113] [9494:9496] [DIS] Mrp Active Threshold: 300 ms [1738802776.113] [9494:9496] [DIS] TCP Client Supported: 0 [1738802776.113] [9494:9496] [DIS] TCP Server Supported: 0 [1738802776.113] [9494:9496] [DIS] ICD: not present [1738802776.114] [9494:9496] [DIS] Lookup clearing interface for non LL address [1738802776.114] [9494:9496] [DIS] UDP:[fd11:22::2d89:b999:cf04:c2b9%vethcedf77b]:5540: new best score: 5 (for CC0161AC8CF43C47-0000000000000010) [1738802776.114] [9494:9496] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 3 ms [1738802776.114] [9494:9496] [DIS] Keeping DNSSD lookup active [1738802776.312] [9494:9496] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 201 ms [1738802776.312] [9494:9496] [DIS] OperationalSessionSetup[1:0000000000000010]: Updating device address to UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540 while in state 2 [1738802776.312] [9494:9496] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 2 --> 3 [1738802776.312] [9494:9496] [IN] SecureSession[0xffff8400bc40]: Allocated Type:2 LSID:152 [1738802776.312] [9494:9496] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000010 [1738802776.315] [9494:9496] [EM] <<< [E:64291i S:0 M:162643492] (U) Msg TX from 5ADF9D1736AC429F to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:195) [1738802776.315] [9494:9496] [EM] ??1 [E:64291i S:0 M:162643492] (U) Msg Retransmission to 0:0000000000000000 in 3565ms [State:Idle II:3000 AI:2200 AT:300] [1738802776.315] [9494:9496] [SC] Sent Sigma1 msg to <0000000000000010, 1> [II:500ms AI:300ms AT:4000ms] [1738802776.315] [9494:9496] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 3 --> 4 [1738802776.955] [9494:9496] [EM] >>> [E:64291i S:0 M:123259095 (Ack:162643492)] (U) Msg RX from 0:0000000000000000 [0000] to 5ADF9D1736AC429F --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802776.955] [9494:9496] [EM] Found matching exchange: 64291i, Delegate: 0xffff8400b958 [1738802776.955] [9494:9496] [EM] Rxd Ack; Removing MessageCounter:162643492 from Retrans Table on exchange 64291i [1738802777.138] [9494:9496] [EM] >>> [E:64291i S:0 M:123259096 (Ack:162643492)] (U) Msg RX from 0:0000000000000000 [0000] to 5ADF9D1736AC429F --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751) [1738802777.138] [9494:9496] [EM] Found matching exchange: 64291i, Delegate: 0xffff8400b958 [1738802777.138] [9494:9496] [EM] CHIP MessageCounter:162643492 not in RetransTable on exchange 64291i [1738802777.139] [9494:9496] [SC] Received Sigma2 msg [1738802777.139] [9494:9496] [SC] Peer assigned session session ID 57843 [1738802777.147] [9494:9496] [SC] Found MRP parameters in the message [1738802777.147] [9494:9496] [SC] Sending Sigma3 [1738802777.148] [9494:9496] [EM] <<< [E:64291i S:0 M:162643493 (Ack:123259096)] (U) Msg TX from 5ADF9D1736AC429F to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1738802777.148] [9494:9496] [EM] ??1 [E:64291i S:0 M:162643493] (U) Msg Retransmission to 0:0000000000000000 in 2898ms [State:Active II:3000 AI:2200 AT:300] [1738802777.148] [9494:9496] [SC] Sent Sigma3 msg [1738802777.544] [9494:9496] [EM] >>> [E:64291i S:0 M:123259097 (Ack:162643493)] (U) Msg RX from 0:0000000000000000 [0000] to 5ADF9D1736AC429F --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802777.544] [9494:9496] [EM] Found matching exchange: 64291i, Delegate: 0xffff8400b958 [1738802777.544] [9494:9496] [EM] Rxd Ack; Removing MessageCounter:162643493 from Retrans Table on exchange 64291i [1738802777.774] [9494:9496] [EM] >>> [E:64291i S:0 M:123259098 (Ack:162643493)] (U) Msg RX from 0:0000000000000000 [0000] to 5ADF9D1736AC429F --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1738802777.774] [9494:9496] [EM] Found matching exchange: 64291i, Delegate: 0xffff8400b958 [1738802777.774] [9494:9496] [EM] CHIP MessageCounter:162643493 not in RetransTable on exchange 64291i [1738802777.774] [9494:9496] [SC] Success status report received. Session was established [1738802777.778] [9494:9496] [SC] SecureSession[0xffff8400bc40, LSID:152]: State change 'kEstablishing' --> 'kActive' [1738802777.778] [9494:9496] [IN] SecureSession[0xffff8400bc40]: Activated - Type:2 LSID:152 [1738802777.778] [9494:9496] [IN] New secure session activated for device <0000000000000010, 1>, LSID:152 PSID:57843! [1738802777.778] [9494:9496] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 4 --> 5 [1738802777.778] [9494:9496] [TOO] Sending cluster (0x00000030) command (0x00000000) on endpoint 0 [1738802777.778] [9494:9496] [DMG] ICR moving to [AddingComm] [1738802777.779] [9494:9496] [DMG] ICR moving to [AddedComma] [1738802777.779] [9494:9496] [EM] <<< [E:64292i S:152 M:159263079] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:66) [1738802777.779] [9494:9496] [EM] ??1 [E:64292i S:152 M:159263079] (S) Msg Retransmission to 1:0000000000000010 in 2643ms [State:Active II:3000 AI:2200 AT:300] [1738802777.779] [9494:9496] [DMG] ICR moving to [AwaitingRe] [1738802777.779] [9494:9496] [EM] <<< [E:64291i S:0 M:162643494 (Ack:123259098)] (U) Msg TX from 5ADF9D1736AC429F to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802777.779] [9494:9496] [EM] Flushed pending ack for MessageCounter:123259098 on exchange 64291i [1738802777.780] [9494:9496] [DL] HandlePlatformSpecificBLEEvent 32792 [1738802777.965] [9494:9496] [EM] >>> [E:64292i S:152 M:236534280 (Ack:159263079)] (S) Msg RX from 1:0000000000000010 [3C47] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) [1738802777.965] [9494:9496] [EM] Found matching exchange: 64292i, Delegate: 0xffff840081b8 [1738802777.966] [9494:9496] [EM] Rxd Ack; Removing MessageCounter:159263079 from Retrans Table on exchange 64292i [1738802777.966] [9494:9496] [DMG] ICR moving to [ResponseRe] [1738802777.966] [9494:9496] [DMG] InvokeResponseMessage = [1738802777.966] [9494:9496] [DMG] { [1738802777.966] [9494:9496] [DMG] suppressResponse = false, [1738802777.966] [9494:9496] [DMG] InvokeResponseIBs = [1738802777.966] [9494:9496] [DMG] [ [1738802777.966] [9494:9496] [DMG] InvokeResponseIB = [1738802777.966] [9494:9496] [DMG] { [1738802777.966] [9494:9496] [DMG] CommandDataIB = [1738802777.966] [9494:9496] [DMG] { [1738802777.966] [9494:9496] [DMG] CommandPathIB = [1738802777.967] [9494:9496] [DMG] { [1738802777.967] [9494:9496] [DMG] EndpointId = 0x0, [1738802777.967] [9494:9496] [DMG] ClusterId = 0x30, [1738802777.967] [9494:9496] [DMG] CommandId = 0x1, [1738802777.967] [9494:9496] [DMG] }, [1738802777.967] [9494:9496] [DMG] [1738802777.967] [9494:9496] [DMG] CommandFields = [1738802777.967] [9494:9496] [DMG] { [1738802777.967] [9494:9496] [DMG] 0x0 = 0 (unsigned), [1738802777.967] [9494:9496] [DMG] 0x1 = "" (0 chars), [1738802777.967] [9494:9496] [DMG] }, [1738802777.968] [9494:9496] [DMG] }, [1738802777.968] [9494:9496] [DMG] [1738802777.968] [9494:9496] [DMG] }, [1738802777.968] [9494:9496] [DMG] [1738802777.968] [9494:9496] [DMG] ], [1738802777.968] [9494:9496] [DMG] [1738802777.968] [9494:9496] [DMG] InteractionModelRevision = 11 [1738802777.968] [9494:9496] [DMG] }, [1738802777.968] [9494:9496] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1738802777.968] [9494:9496] [TOO] Endpoint: 0 Cluster: 0x0000_0030 Command 0x0000_0001 [1738802777.969] [9494:9496] [TOO] ArmFailSafeResponse: { [1738802777.969] [9494:9496] [TOO] errorCode: 0 [1738802777.969] [9494:9496] [TOO] debugText: [1738802777.969] [9494:9496] [TOO] } [1738802777.969] [9494:9496] [DMG] ICR moving to [AwaitingDe] [1738802777.969] [9494:9496] [EM] <<< [E:64292i S:152 M:159263080 (Ack:236534280)] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1738802777.970] [9494:9496] [EM] Flushed pending ack for MessageCounter:236534280 on exchange 64292i [1738802777.970] [9494:9494] [CTL] Shutting down the commissioner [1738802777.971] [9494:9494] [CTL] Shutting down the controller [1738802777.971] [9494:9494] [IN] Expiring all sessions for fabric 0x1!! [1738802777.971] [9494:9494] [IN] SecureSession[0xffff8400bc40]: MarkForEviction Type:2 LSID:152 [1738802777.971] [9494:9494] [SC] SecureSession[0xffff8400bc40, LSID:152]: State change 'kActive' --> 'kPendingEviction' [1738802777.971] [9494:9494] [IN] SecureSession[0xffff8400bc40]: Released - Type:2 LSID:152 [1738802777.971] [9494:9494] [FP] Forgetting fabric 0x1 [1738802777.971] [9494:9494] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802777.971] [9494:9494] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802777.972] [9494:9494] [TS] Reverted Last Known Good Time to previous value [1738802777.972] [9494:9494] [CTL] Shutting down the commissioner [1738802777.972] [9494:9494] [CTL] Shutting down the controller [1738802777.972] [9494:9494] [CTL] Shutting down the System State, this will teardown the CHIP Stack [1738802777.973] [9494:9494] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1738802777.973] [9494:9494] [FP] Shutting down FabricTable [1738802777.973] [9494:9494] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802777.973] [9494:9494] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802777.973] [9494:9494] [TS] Reverted Last Known Good Time to previous value [1738802777.982] [9494:9494] [DL] Wrote settings to /tmp/chip_counters.ini [1738802777.982] [9494:9494] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1738802777.982] [9494:9494] [DL] Inet Layer shutdown [1738802777.982] [9494:9494] [DL] BLE Layer shutdown [1738802777.983] [9494:9494] [DL] System Layer shutdown ubuntu@ubuntu:~/apps$ ./chip-tool networkcommissioning connect-network hex:1111111122222222 0x10 0 --Breadcrumb 1 [1738802787.150] [9497:9497] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1738802787.151] [9497:9497] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1738802787.157] [9497:9497] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1738802787.157] [9497:9497] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1738802787.158] [9497:9497] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1738802787.167] [9497:9497] [DL] Wrote settings to /tmp/chip_counters.ini [1738802787.167] [9497:9497] [DL] NVS set: chip-counters/reboot-count = 15 (0xF) [1738802787.168] [9497:9497] [DL] Got Ethernet interface: eth0 [1738802787.169] [9497:9497] [DL] Found the primary Ethernet interface:eth0 [1738802787.170] [9497:9497] [DL] Got WiFi interface: wlan0 [1738802787.170] [9497:9497] [DL] Failed to reset WiFi statistic counts [1738802787.170] [9497:9497] [IN] UDP::Init bind&listen port=0 [1738802787.170] [9497:9497] [IN] UDP::Init bound to port=44644 [1738802787.170] [9497:9497] [IN] BLEBase::Init - setting/overriding transport [1738802787.171] [9497:9497] [IN] TransportMgr initialized [1738802787.171] [9497:9497] [FP] Initializing FabricTable from persistent storage [1738802787.171] [9497:9497] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802787.173] [9497:9497] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xCC0161AC8CF43C47, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1738802787.176] [9497:9497] [ZCL] Using ZAP configuration... [1738802787.181] [9497:9497] [DL] Avahi client registered [1738802787.182] [9497:9497] [IM] WARNING ┌──────────────────────────────────────────────────── [1738802787.182] [9497:9497] [IM] WARNING │ Interaction Model Engine running in 'Checked' mode. [1738802787.182] [9497:9497] [IM] WARNING │ This executes BOTH ember and data-model code paths. [1738802787.182] [9497:9497] [IM] WARNING │ which is inefficient and consumes more flash space. [1738802787.182] [9497:9497] [IM] WARNING │ This should be done for testing only. [1738802787.182] [9497:9497] [IM] WARNING └──────────────────────────────────────────────────── [1738802787.182] [9497:9497] [CTL] System State Initialized... [1738802787.182] [9497:9497] [CTL] Setting attestation nonce to random value [1738802787.182] [9497:9497] [CTL] Setting CSR nonce to random value [1738802787.182] [9497:9497] [IN] UDP::Init bind&listen port=5550 [1738802787.182] [9497:9497] [IN] UDP::Init bound to port=5550 [1738802787.182] [9497:9497] [IN] TransportMgr initialized [1738802787.183] [9497:9499] [DL] CHIP task running [1738802787.183] [9497:9499] [DL] HandlePlatformSpecificBLEEvent 32786 [1738802787.183] [9497:9499] [CTL] Setting attestation nonce to random value [1738802787.184] [9497:9499] [CTL] Setting CSR nonce to random value [1738802787.185] [9497:9499] [CTL] Generating NOC [1738802787.186] [9497:9499] [FP] Validating NOC chain [1738802787.188] [9497:9499] [FP] NOC chain validation successful [1738802787.188] [9497:9499] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1738802787.188] [9497:9499] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802787.188] [9497:9499] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1738802787.188] [9497:9499] [TS] Retaining current Last Known Good Time [1738802787.193] [9497:9499] [FP] Metadata for Fabric 0x1 persisted to storage. [1738802787.195] [9497:9499] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1738802787.196] [9497:9499] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: CC0161AC8CF43C47) [1738802787.196] [9497:9499] [IN] UDP::Init bind&listen port=5550 [1738802787.196] [9497:9499] [IN] UDP::Init bound to port=5550 [1738802787.197] [9497:9499] [IN] TransportMgr initialized [1738802787.213] [9497:9499] [TOO] Sending command to node 0x10 [1738802787.213] [9497:9499] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000010] [1738802787.213] [9497:9499] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1738802787.213] [9497:9499] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 1 --> 2 [1738802787.213] [9497:9499] [DIS] Resolving CC0161AC8CF43C47:0000000000000010 ... [1738802787.215] [9497:9499] [DIS] Lookup started for CC0161AC8CF43C47-0000000000000010 [1738802787.215] [9497:9499] [DL] Avahi resolve found [1738802787.215] [9497:9499] [DIS] Node ID resolved for CC0161AC8CF43C47-0000000000000010 [1738802787.215] [9497:9499] [DIS] Hostname: 523E0F663D7E3165 [1738802787.215] [9497:9499] [DIS] IP Address #1: fd11:22::2d89:b999:cf04:c2b9 [1738802787.215] [9497:9499] [DIS] Port: 5540 [1738802787.215] [9497:9499] [DIS] Mrp Interval idle: 3000 ms [1738802787.216] [9497:9499] [DIS] Mrp Interval active: 2200 ms [1738802787.216] [9497:9499] [DIS] Mrp Active Threshold: 300 ms [1738802787.216] [9497:9499] [DIS] TCP Client Supported: 0 [1738802787.216] [9497:9499] [DIS] TCP Server Supported: 0 [1738802787.216] [9497:9499] [DIS] ICD: not present [1738802787.216] [9497:9499] [DIS] Lookup clearing interface for non LL address [1738802787.216] [9497:9499] [DIS] UDP:[fd11:22::2d89:b999:cf04:c2b9%vethcedf77b]:5540: new best score: 5 (for CC0161AC8CF43C47-0000000000000010) [1738802787.216] [9497:9499] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 3 ms [1738802787.216] [9497:9499] [DIS] Keeping DNSSD lookup active [1738802787.414] [9497:9499] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 200 ms [1738802787.414] [9497:9499] [DIS] OperationalSessionSetup[1:0000000000000010]: Updating device address to UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540 while in state 2 [1738802787.414] [9497:9499] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 2 --> 3 [1738802787.415] [9497:9499] [IN] SecureSession[0xffff8000bc40]: Allocated Type:2 LSID:12035 [1738802787.415] [9497:9499] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000010 [1738802787.417] [9497:9499] [EM] <<< [E:64784i S:0 M:24988284] (U) Msg TX from CA89F608FB3CDE1F to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) [1738802787.418] [9497:9499] [EM] ??1 [E:64784i S:0 M:24988284] (U) Msg Retransmission to 0:0000000000000000 in 3565ms [State:Idle II:3000 AI:2200 AT:300] [1738802787.418] [9497:9499] [SC] Sent Sigma1 msg to <0000000000000010, 1> [II:500ms AI:300ms AT:4000ms] [1738802787.418] [9497:9499] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 3 --> 4 [1738802787.681] [9497:9499] [EM] >>> [E:64784i S:0 M:123259099 (Ack:24988284)] (U) Msg RX from 0:0000000000000000 [0000] to CA89F608FB3CDE1F --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802787.681] [9497:9499] [EM] Found matching exchange: 64784i, Delegate: 0xffff8000b958 [1738802787.681] [9497:9499] [EM] Rxd Ack; Removing MessageCounter:24988284 from Retrans Table on exchange 64784i [1738802787.862] [9497:9499] [EM] >>> [E:64784i S:0 M:123259100 (Ack:24988284)] (U) Msg RX from 0:0000000000000000 [0000] to CA89F608FB3CDE1F --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751) [1738802787.862] [9497:9499] [EM] Found matching exchange: 64784i, Delegate: 0xffff8000b958 [1738802787.862] [9497:9499] [EM] CHIP MessageCounter:24988284 not in RetransTable on exchange 64784i [1738802787.862] [9497:9499] [SC] Received Sigma2 msg [1738802787.862] [9497:9499] [SC] Peer assigned session session ID 57844 [1738802787.871] [9497:9499] [SC] Found MRP parameters in the message [1738802787.871] [9497:9499] [SC] Sending Sigma3 [1738802787.872] [9497:9499] [EM] <<< [E:64784i S:0 M:24988285 (Ack:123259100)] (U) Msg TX from CA89F608FB3CDE1F to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1738802787.873] [9497:9499] [EM] ??1 [E:64784i S:0 M:24988285] (U) Msg Retransmission to 0:0000000000000000 in 2567ms [State:Active II:3000 AI:2200 AT:300] [1738802787.873] [9497:9499] [SC] Sent Sigma3 msg [1738802788.170] [9497:9499] [EM] >>> [E:64784i S:0 M:123259101 (Ack:24988285)] (U) Msg RX from 0:0000000000000000 [0000] to CA89F608FB3CDE1F --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802788.170] [9497:9499] [EM] Found matching exchange: 64784i, Delegate: 0xffff8000b958 [1738802788.170] [9497:9499] [EM] Rxd Ack; Removing MessageCounter:24988285 from Retrans Table on exchange 64784i [1738802788.404] [9497:9499] [EM] >>> [E:64784i S:0 M:123259102 (Ack:24988285)] (U) Msg RX from 0:0000000000000000 [0000] to CA89F608FB3CDE1F --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1738802788.404] [9497:9499] [EM] Found matching exchange: 64784i, Delegate: 0xffff8000b958 [1738802788.404] [9497:9499] [EM] CHIP MessageCounter:24988285 not in RetransTable on exchange 64784i [1738802788.404] [9497:9499] [SC] Success status report received. Session was established [1738802788.411] [9497:9499] [SC] SecureSession[0xffff8000bc40, LSID:12035]: State change 'kEstablishing' --> 'kActive' [1738802788.411] [9497:9499] [IN] SecureSession[0xffff8000bc40]: Activated - Type:2 LSID:12035 [1738802788.411] [9497:9499] [IN] New secure session activated for device <0000000000000010, 1>, LSID:12035 PSID:57844! [1738802788.411] [9497:9499] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 4 --> 5 [1738802788.411] [9497:9499] [TOO] Sending cluster (0x00000031) command (0x00000006) on endpoint 0 [1738802788.411] [9497:9499] [DMG] ICR moving to [AddingComm] [1738802788.412] [9497:9499] [DMG] ICR moving to [AddedComma] [1738802788.412] [9497:9499] [EM] <<< [E:64785i S:12035 M:161860208] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:73) [1738802788.413] [9497:9499] [EM] ??1 [E:64785i S:12035 M:161860208] (S) Msg Retransmission to 1:0000000000000010 in 2645ms [State:Active II:3000 AI:2200 AT:300] [1738802788.413] [9497:9499] [DMG] ICR moving to [AwaitingRe] [1738802788.413] [9497:9499] [EM] <<< [E:64784i S:0 M:24988286 (Ack:123259102)] (U) Msg TX from CA89F608FB3CDE1F to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802788.414] [9497:9499] [EM] Flushed pending ack for MessageCounter:123259102 on exchange 64784i [1738802788.414] [9497:9499] [DL] HandlePlatformSpecificBLEEvent 32792 [1738802790.375] [9497:9499] [EM] >>> [E:64785i S:12035 M:35663601 (Ack:161860208)] (S) Msg RX from 1:0000000000000010 [3C47] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:69) [1738802790.376] [9497:9499] [EM] Found matching exchange: 64785i, Delegate: 0xffff800081b8 [1738802790.376] [9497:9499] [EM] Rxd Ack; Removing MessageCounter:161860208 from Retrans Table on exchange 64785i [1738802790.376] [9497:9499] [DMG] ICR moving to [ResponseRe] [1738802790.376] [9497:9499] [DMG] InvokeResponseMessage = [1738802790.376] [9497:9499] [DMG] { [1738802790.376] [9497:9499] [DMG] suppressResponse = false, [1738802790.376] [9497:9499] [DMG] InvokeResponseIBs = [1738802790.376] [9497:9499] [DMG] [ [1738802790.376] [9497:9499] [DMG] InvokeResponseIB = [1738802790.376] [9497:9499] [DMG] { [1738802790.376] [9497:9499] [DMG] CommandDataIB = [1738802790.376] [9497:9499] [DMG] { [1738802790.376] [9497:9499] [DMG] CommandPathIB = [1738802790.376] [9497:9499] [DMG] { [1738802790.376] [9497:9499] [DMG] EndpointId = 0x0, [1738802790.376] [9497:9499] [DMG] ClusterId = 0x31, [1738802790.376] [9497:9499] [DMG] CommandId = 0x7, [1738802790.376] [9497:9499] [DMG] }, [1738802790.376] [9497:9499] [DMG] [1738802790.376] [9497:9499] [DMG] CommandFields = [1738802790.376] [9497:9499] [DMG] { [1738802790.376] [9497:9499] [DMG] 0x0 = 0 (unsigned), [1738802790.377] [9497:9499] [DMG] 0x2 = NULL [1738802790.377] [9497:9499] [DMG] }, [1738802790.377] [9497:9499] [DMG] }, [1738802790.377] [9497:9499] [DMG] [1738802790.377] [9497:9499] [DMG] }, [1738802790.377] [9497:9499] [DMG] [1738802790.377] [9497:9499] [DMG] ], [1738802790.377] [9497:9499] [DMG] [1738802790.377] [9497:9499] [DMG] InteractionModelRevision = 11 [1738802790.377] [9497:9499] [DMG] }, [1738802790.377] [9497:9499] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007 [1738802790.377] [9497:9499] [TOO] Endpoint: 0 Cluster: 0x0000_0031 Command 0x0000_0007 [1738802790.377] [9497:9499] [TOO] ConnectNetworkResponse: { [1738802790.377] [9497:9499] [TOO] networkingStatus: 0 [1738802790.377] [9497:9499] [TOO] errorValue: null [1738802790.377] [9497:9499] [TOO] } [1738802790.377] [9497:9499] [DMG] ICR moving to [AwaitingDe] [1738802790.378] [9497:9499] [EM] <<< [E:64785i S:12035 M:161860209 (Ack:35663601)] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1738802790.378] [9497:9499] [EM] Flushed pending ack for MessageCounter:35663601 on exchange 64785i [1738802790.378] [9497:9497] [CTL] Shutting down the commissioner [1738802790.378] [9497:9497] [CTL] Shutting down the controller [1738802790.378] [9497:9497] [IN] Expiring all sessions for fabric 0x1!! [1738802790.378] [9497:9497] [IN] SecureSession[0xffff8000bc40]: MarkForEviction Type:2 LSID:12035 [1738802790.378] [9497:9497] [SC] SecureSession[0xffff8000bc40, LSID:12035]: State change 'kActive' --> 'kPendingEviction' [1738802790.378] [9497:9497] [IN] SecureSession[0xffff8000bc40]: Released - Type:2 LSID:12035 [1738802790.378] [9497:9497] [FP] Forgetting fabric 0x1 [1738802790.378] [9497:9497] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802790.379] [9497:9497] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802790.379] [9497:9497] [TS] Reverted Last Known Good Time to previous value [1738802790.379] [9497:9497] [CTL] Shutting down the commissioner [1738802790.379] [9497:9497] [CTL] Shutting down the controller [1738802790.379] [9497:9497] [CTL] Shutting down the System State, this will teardown the CHIP Stack [1738802790.379] [9497:9497] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1738802790.379] [9497:9497] [FP] Shutting down FabricTable [1738802790.379] [9497:9497] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802790.380] [9497:9497] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802790.380] [9497:9497] [TS] Reverted Last Known Good Time to previous value [1738802790.386] [9497:9497] [DL] Wrote settings to /tmp/chip_counters.ini [1738802790.386] [9497:9497] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1738802790.386] [9497:9497] [DL] Inet Layer shutdown [1738802790.386] [9497:9497] [DL] BLE Layer shutdown [1738802790.386] [9497:9497] [DL] System Layer shutdown ubuntu@ubuntu:~/apps$ ./chip-tool networkcommissioning remove-network hex:1111111122222222 0x10 0 --Breadcrumb 1 [1738802803.581] [9500:9500] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1738802803.582] [9500:9500] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1738802803.588] [9500:9500] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1738802803.588] [9500:9500] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1738802803.588] [9500:9500] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1738802803.598] [9500:9500] [DL] Wrote settings to /tmp/chip_counters.ini [1738802803.598] [9500:9500] [DL] NVS set: chip-counters/reboot-count = 16 (0x10) [1738802803.599] [9500:9500] [DL] Got Ethernet interface: eth0 [1738802803.599] [9500:9500] [DL] Found the primary Ethernet interface:eth0 [1738802803.600] [9500:9500] [DL] Got WiFi interface: wlan0 [1738802803.600] [9500:9500] [DL] Failed to reset WiFi statistic counts [1738802803.600] [9500:9500] [IN] UDP::Init bind&listen port=0 [1738802803.600] [9500:9500] [IN] UDP::Init bound to port=56659 [1738802803.600] [9500:9500] [IN] BLEBase::Init - setting/overriding transport [1738802803.600] [9500:9500] [IN] TransportMgr initialized [1738802803.601] [9500:9500] [FP] Initializing FabricTable from persistent storage [1738802803.601] [9500:9500] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802803.602] [9500:9500] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xCC0161AC8CF43C47, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1738802803.605] [9500:9500] [ZCL] Using ZAP configuration... [1738802803.612] [9500:9500] [DL] Avahi client registered [1738802803.613] [9500:9500] [IM] WARNING ┌──────────────────────────────────────────────────── [1738802803.613] [9500:9500] [IM] WARNING │ Interaction Model Engine running in 'Checked' mode. [1738802803.613] [9500:9500] [IM] WARNING │ This executes BOTH ember and data-model code paths. [1738802803.613] [9500:9500] [IM] WARNING │ which is inefficient and consumes more flash space. [1738802803.613] [9500:9500] [IM] WARNING │ This should be done for testing only. [1738802803.613] [9500:9500] [IM] WARNING └──────────────────────────────────────────────────── [1738802803.613] [9500:9500] [CTL] System State Initialized... [1738802803.613] [9500:9500] [CTL] Setting attestation nonce to random value [1738802803.613] [9500:9500] [CTL] Setting CSR nonce to random value [1738802803.613] [9500:9500] [IN] UDP::Init bind&listen port=5550 [1738802803.613] [9500:9500] [IN] UDP::Init bound to port=5550 [1738802803.613] [9500:9500] [IN] TransportMgr initialized [1738802803.614] [9500:9502] [DL] CHIP task running [1738802803.614] [9500:9502] [DL] HandlePlatformSpecificBLEEvent 32786 [1738802803.615] [9500:9502] [CTL] Setting attestation nonce to random value [1738802803.615] [9500:9502] [CTL] Setting CSR nonce to random value [1738802803.616] [9500:9502] [CTL] Generating NOC [1738802803.617] [9500:9502] [FP] Validating NOC chain [1738802803.619] [9500:9502] [FP] NOC chain validation successful [1738802803.619] [9500:9502] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1738802803.619] [9500:9502] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802803.619] [9500:9502] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1738802803.619] [9500:9502] [TS] Retaining current Last Known Good Time [1738802803.624] [9500:9502] [FP] Metadata for Fabric 0x1 persisted to storage. [1738802803.626] [9500:9502] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1738802803.628] [9500:9502] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: CC0161AC8CF43C47) [1738802803.628] [9500:9502] [IN] UDP::Init bind&listen port=5550 [1738802803.628] [9500:9502] [IN] UDP::Init bound to port=5550 [1738802803.628] [9500:9502] [IN] TransportMgr initialized [1738802803.642] [9500:9502] [TOO] Sending command to node 0x10 [1738802803.643] [9500:9502] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000010] [1738802803.643] [9500:9502] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1738802803.643] [9500:9502] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 1 --> 2 [1738802803.643] [9500:9502] [DIS] Resolving CC0161AC8CF43C47:0000000000000010 ... [1738802803.644] [9500:9502] [DIS] Lookup started for CC0161AC8CF43C47-0000000000000010 [1738802803.645] [9500:9502] [DL] Avahi resolve found [1738802803.645] [9500:9502] [DIS] Node ID resolved for CC0161AC8CF43C47-0000000000000010 [1738802803.645] [9500:9502] [DIS] Hostname: 523E0F663D7E3165 [1738802803.645] [9500:9502] [DIS] IP Address #1: fd11:22::2d89:b999:cf04:c2b9 [1738802803.645] [9500:9502] [DIS] Port: 5540 [1738802803.645] [9500:9502] [DIS] Mrp Interval idle: 3000 ms [1738802803.645] [9500:9502] [DIS] Mrp Interval active: 2200 ms [1738802803.645] [9500:9502] [DIS] Mrp Active Threshold: 300 ms [1738802803.645] [9500:9502] [DIS] TCP Client Supported: 0 [1738802803.645] [9500:9502] [DIS] TCP Server Supported: 0 [1738802803.645] [9500:9502] [DIS] ICD: not present [1738802803.646] [9500:9502] [DIS] Lookup clearing interface for non LL address [1738802803.646] [9500:9502] [DIS] UDP:[fd11:22::2d89:b999:cf04:c2b9%vethcedf77b]:5540: new best score: 5 (for CC0161AC8CF43C47-0000000000000010) [1738802803.646] [9500:9502] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 3 ms [1738802803.646] [9500:9502] [DIS] Keeping DNSSD lookup active [1738802803.844] [9500:9502] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 201 ms [1738802803.844] [9500:9502] [DIS] OperationalSessionSetup[1:0000000000000010]: Updating device address to UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540 while in state 2 [1738802803.844] [9500:9502] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 2 --> 3 [1738802803.844] [9500:9502] [IN] SecureSession[0xffff9c00bc40]: Allocated Type:2 LSID:54423 [1738802803.844] [9500:9502] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000010 [1738802803.845] [9500:9502] [EM] <<< [E:45334i S:0 M:236919311] (U) Msg TX from 57B83632CBD6F56C to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) [1738802803.845] [9500:9502] [EM] ??1 [E:45334i S:0 M:236919311] (U) Msg Retransmission to 0:0000000000000000 in 3475ms [State:Idle II:3000 AI:2200 AT:300] [1738802803.846] [9500:9502] [SC] Sent Sigma1 msg to <0000000000000010, 1> [II:500ms AI:300ms AT:4000ms] [1738802803.846] [9500:9502] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 3 --> 4 [1738802804.145] [9500:9502] [EM] >>> [E:45334i S:0 M:123259103 (Ack:236919311)] (U) Msg RX from 0:0000000000000000 [0000] to 57B83632CBD6F56C --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802804.145] [9500:9502] [EM] Found matching exchange: 45334i, Delegate: 0xffff9c00b958 [1738802804.145] [9500:9502] [EM] Rxd Ack; Removing MessageCounter:236919311 from Retrans Table on exchange 45334i [1738802804.332] [9500:9502] [EM] >>> [E:45334i S:0 M:123259104 (Ack:236919311)] (U) Msg RX from 0:0000000000000000 [0000] to 57B83632CBD6F56C --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751) [1738802804.333] [9500:9502] [EM] Found matching exchange: 45334i, Delegate: 0xffff9c00b958 [1738802804.333] [9500:9502] [EM] CHIP MessageCounter:236919311 not in RetransTable on exchange 45334i [1738802804.333] [9500:9502] [SC] Received Sigma2 msg [1738802804.333] [9500:9502] [SC] Peer assigned session session ID 57845 [1738802804.342] [9500:9502] [SC] Found MRP parameters in the message [1738802804.342] [9500:9502] [SC] Sending Sigma3 [1738802804.343] [9500:9502] [EM] <<< [E:45334i S:0 M:236919312 (Ack:123259104)] (U) Msg TX from 57B83632CBD6F56C to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1738802804.343] [9500:9502] [EM] ??1 [E:45334i S:0 M:236919312] (U) Msg Retransmission to 0:0000000000000000 in 2924ms [State:Active II:3000 AI:2200 AT:300] [1738802804.343] [9500:9502] [SC] Sent Sigma3 msg [1738802804.623] [9500:9502] [EM] >>> [E:45334i S:0 M:123259105 (Ack:236919312)] (U) Msg RX from 0:0000000000000000 [0000] to 57B83632CBD6F56C --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802804.623] [9500:9502] [EM] Found matching exchange: 45334i, Delegate: 0xffff9c00b958 [1738802804.623] [9500:9502] [EM] Rxd Ack; Removing MessageCounter:236919312 from Retrans Table on exchange 45334i [1738802804.850] [9500:9502] [EM] >>> [E:45334i S:0 M:123259106 (Ack:236919312)] (U) Msg RX from 0:0000000000000000 [0000] to 57B83632CBD6F56C --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1738802804.850] [9500:9502] [EM] Found matching exchange: 45334i, Delegate: 0xffff9c00b958 [1738802804.851] [9500:9502] [EM] CHIP MessageCounter:236919312 not in RetransTable on exchange 45334i [1738802804.851] [9500:9502] [SC] Success status report received. Session was established [1738802804.856] [9500:9502] [SC] SecureSession[0xffff9c00bc40, LSID:54423]: State change 'kEstablishing' --> 'kActive' [1738802804.856] [9500:9502] [IN] SecureSession[0xffff9c00bc40]: Activated - Type:2 LSID:54423 [1738802804.856] [9500:9502] [IN] New secure session activated for device <0000000000000010, 1>, LSID:54423 PSID:57845! [1738802804.856] [9500:9502] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 4 --> 5 [1738802804.856] [9500:9502] [TOO] Sending cluster (0x00000031) command (0x00000004) on endpoint 0 [1738802804.856] [9500:9502] [DMG] ICR moving to [AddingComm] [1738802804.856] [9500:9502] [DMG] ICR moving to [AddedComma] [1738802804.857] [9500:9502] [EM] <<< [E:45335i S:54423 M:65287232] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:73) [1738802804.857] [9500:9502] [EM] ??1 [E:45335i S:54423 M:65287232] (S) Msg Retransmission to 1:0000000000000010 in 2560ms [State:Active II:3000 AI:2200 AT:300] [1738802804.857] [9500:9502] [DMG] ICR moving to [AwaitingRe] [1738802804.857] [9500:9502] [EM] <<< [E:45334i S:0 M:236919313 (Ack:123259106)] (U) Msg TX from 57B83632CBD6F56C to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802804.858] [9500:9502] [EM] Flushed pending ack for MessageCounter:123259106 on exchange 45334i [1738802804.858] [9500:9502] [DL] HandlePlatformSpecificBLEEvent 32792 [1738802805.035] [9500:9502] [EM] >>> [E:45335i S:54423 M:14913334 (Ack:65287232)] (S) Msg RX from 1:0000000000000010 [3C47] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) [1738802805.035] [9500:9502] [EM] Found matching exchange: 45335i, Delegate: 0xffff9c0081b8 [1738802805.035] [9500:9502] [EM] Rxd Ack; Removing MessageCounter:65287232 from Retrans Table on exchange 45335i [1738802805.035] [9500:9502] [DMG] ICR moving to [ResponseRe] [1738802805.035] [9500:9502] [DMG] InvokeResponseMessage = [1738802805.035] [9500:9502] [DMG] { [1738802805.035] [9500:9502] [DMG] suppressResponse = false, [1738802805.035] [9500:9502] [DMG] InvokeResponseIBs = [1738802805.036] [9500:9502] [DMG] [ [1738802805.036] [9500:9502] [DMG] InvokeResponseIB = [1738802805.036] [9500:9502] [DMG] { [1738802805.036] [9500:9502] [DMG] CommandDataIB = [1738802805.036] [9500:9502] [DMG] { [1738802805.036] [9500:9502] [DMG] CommandPathIB = [1738802805.036] [9500:9502] [DMG] { [1738802805.036] [9500:9502] [DMG] EndpointId = 0x0, [1738802805.036] [9500:9502] [DMG] ClusterId = 0x31, [1738802805.036] [9500:9502] [DMG] CommandId = 0x5, [1738802805.036] [9500:9502] [DMG] }, [1738802805.036] [9500:9502] [DMG] [1738802805.037] [9500:9502] [DMG] CommandFields = [1738802805.037] [9500:9502] [DMG] { [1738802805.037] [9500:9502] [DMG] 0x0 = 0 (unsigned), [1738802805.037] [9500:9502] [DMG] 0x2 = 0 (unsigned), [1738802805.037] [9500:9502] [DMG] }, [1738802805.037] [9500:9502] [DMG] }, [1738802805.037] [9500:9502] [DMG] [1738802805.037] [9500:9502] [DMG] }, [1738802805.037] [9500:9502] [DMG] [1738802805.037] [9500:9502] [DMG] ], [1738802805.037] [9500:9502] [DMG] [1738802805.037] [9500:9502] [DMG] InteractionModelRevision = 11 [1738802805.038] [9500:9502] [DMG] }, [1738802805.038] [9500:9502] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005 [1738802805.038] [9500:9502] [TOO] Endpoint: 0 Cluster: 0x0000_0031 Command 0x0000_0005 [1738802805.038] [9500:9502] [TOO] NetworkConfigResponse: { [1738802805.038] [9500:9502] [TOO] networkingStatus: 0 [1738802805.038] [9500:9502] [TOO] networkIndex: 0 [1738802805.039] [9500:9502] [TOO] } [1738802805.039] [9500:9502] [DMG] ICR moving to [AwaitingDe] [1738802805.039] [9500:9502] [EM] <<< [E:45335i S:54423 M:65287233 (Ack:14913334)] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1738802805.039] [9500:9502] [EM] Flushed pending ack for MessageCounter:14913334 on exchange 45335i [1738802805.040] [9500:9500] [CTL] Shutting down the commissioner [1738802805.040] [9500:9500] [CTL] Shutting down the controller [1738802805.040] [9500:9500] [IN] Expiring all sessions for fabric 0x1!! [1738802805.040] [9500:9500] [IN] SecureSession[0xffff9c00bc40]: MarkForEviction Type:2 LSID:54423 [1738802805.040] [9500:9500] [SC] SecureSession[0xffff9c00bc40, LSID:54423]: State change 'kActive' --> 'kPendingEviction' [1738802805.040] [9500:9500] [IN] SecureSession[0xffff9c00bc40]: Released - Type:2 LSID:54423 [1738802805.040] [9500:9500] [FP] Forgetting fabric 0x1 [1738802805.040] [9500:9500] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802805.041] [9500:9500] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802805.041] [9500:9500] [TS] Reverted Last Known Good Time to previous value [1738802805.041] [9500:9500] [CTL] Shutting down the commissioner [1738802805.041] [9500:9500] [CTL] Shutting down the controller [1738802805.041] [9500:9500] [CTL] Shutting down the System State, this will teardown the CHIP Stack [1738802805.041] [9500:9500] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1738802805.041] [9500:9500] [FP] Shutting down FabricTable [1738802805.041] [9500:9500] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802805.042] [9500:9500] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802805.042] [9500:9500] [TS] Reverted Last Known Good Time to previous value [1738802805.050] [9500:9500] [DL] Wrote settings to /tmp/chip_counters.ini [1738802805.050] [9500:9500] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1738802805.050] [9500:9500] [DL] Inet Layer shutdown [1738802805.050] [9500:9500] [DL] BLE Layer shutdown [1738802805.050] [9500:9500] [DL] System Layer shutdown ubuntu@ubuntu:~/apps$ ./chip-tool networkcommissioning remove-network hex:1111111122222222 0x10 0 --Breadcrumb 1 [1738802814.288] [9504:9504] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1738802814.289] [9504:9504] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1738802814.295] [9504:9504] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1738802814.295] [9504:9504] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1738802814.295] [9504:9504] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1738802814.305] [9504:9504] [DL] Wrote settings to /tmp/chip_counters.ini [1738802814.305] [9504:9504] [DL] NVS set: chip-counters/reboot-count = 17 (0x11) [1738802814.306] [9504:9504] [DL] Got Ethernet interface: eth0 [1738802814.306] [9504:9504] [DL] Found the primary Ethernet interface:eth0 [1738802814.307] [9504:9504] [DL] Got WiFi interface: wlan0 [1738802814.307] [9504:9504] [DL] Failed to reset WiFi statistic counts [1738802814.307] [9504:9504] [IN] UDP::Init bind&listen port=0 [1738802814.307] [9504:9504] [IN] UDP::Init bound to port=49553 [1738802814.308] [9504:9504] [IN] BLEBase::Init - setting/overriding transport [1738802814.308] [9504:9504] [IN] TransportMgr initialized [1738802814.308] [9504:9504] [FP] Initializing FabricTable from persistent storage [1738802814.308] [9504:9504] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802814.311] [9504:9504] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xCC0161AC8CF43C47, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1738802814.315] [9504:9504] [ZCL] Using ZAP configuration... [1738802814.321] [9504:9504] [DL] Avahi client registered [1738802814.321] [9504:9504] [IM] WARNING ┌──────────────────────────────────────────────────── [1738802814.321] [9504:9504] [IM] WARNING │ Interaction Model Engine running in 'Checked' mode. [1738802814.321] [9504:9504] [IM] WARNING │ This executes BOTH ember and data-model code paths. [1738802814.321] [9504:9504] [IM] WARNING │ which is inefficient and consumes more flash space. [1738802814.322] [9504:9504] [IM] WARNING │ This should be done for testing only. [1738802814.322] [9504:9504] [IM] WARNING └──────────────────────────────────────────────────── [1738802814.322] [9504:9504] [CTL] System State Initialized... [1738802814.322] [9504:9504] [CTL] Setting attestation nonce to random value [1738802814.322] [9504:9504] [CTL] Setting CSR nonce to random value [1738802814.322] [9504:9504] [IN] UDP::Init bind&listen port=5550 [1738802814.322] [9504:9504] [IN] UDP::Init bound to port=5550 [1738802814.322] [9504:9504] [IN] TransportMgr initialized [1738802814.323] [9504:9506] [DL] CHIP task running [1738802814.323] [9504:9506] [DL] HandlePlatformSpecificBLEEvent 32786 [1738802814.323] [9504:9506] [CTL] Setting attestation nonce to random value [1738802814.323] [9504:9506] [CTL] Setting CSR nonce to random value [1738802814.324] [9504:9506] [CTL] Generating NOC [1738802814.325] [9504:9506] [FP] Validating NOC chain [1738802814.327] [9504:9506] [FP] NOC chain validation successful [1738802814.327] [9504:9506] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1738802814.327] [9504:9506] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1738802814.327] [9504:9506] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1738802814.327] [9504:9506] [TS] Retaining current Last Known Good Time [1738802814.332] [9504:9506] [FP] Metadata for Fabric 0x1 persisted to storage. [1738802814.333] [9504:9506] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1738802814.335] [9504:9506] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: CC0161AC8CF43C47) [1738802814.335] [9504:9506] [IN] UDP::Init bind&listen port=5550 [1738802814.335] [9504:9506] [IN] UDP::Init bound to port=5550 [1738802814.335] [9504:9506] [IN] TransportMgr initialized [1738802814.352] [9504:9506] [TOO] Sending command to node 0x10 [1738802814.353] [9504:9506] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000010] [1738802814.353] [9504:9506] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1738802814.353] [9504:9506] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 1 --> 2 [1738802814.353] [9504:9506] [DIS] Resolving CC0161AC8CF43C47:0000000000000010 ... [1738802814.354] [9504:9506] [DIS] Lookup started for CC0161AC8CF43C47-0000000000000010 [1738802814.355] [9504:9506] [DL] Avahi resolve found [1738802814.355] [9504:9506] [DIS] Node ID resolved for CC0161AC8CF43C47-0000000000000010 [1738802814.355] [9504:9506] [DIS] Hostname: 523E0F663D7E3165 [1738802814.355] [9504:9506] [DIS] IP Address #1: fd11:22::2d89:b999:cf04:c2b9 [1738802814.355] [9504:9506] [DIS] Port: 5540 [1738802814.355] [9504:9506] [DIS] Mrp Interval idle: 3000 ms [1738802814.355] [9504:9506] [DIS] Mrp Interval active: 2200 ms [1738802814.355] [9504:9506] [DIS] Mrp Active Threshold: 300 ms [1738802814.355] [9504:9506] [DIS] TCP Client Supported: 0 [1738802814.355] [9504:9506] [DIS] TCP Server Supported: 0 [1738802814.355] [9504:9506] [DIS] ICD: not present [1738802814.356] [9504:9506] [DIS] Lookup clearing interface for non LL address [1738802814.356] [9504:9506] [DIS] UDP:[fd11:22::2d89:b999:cf04:c2b9%vethcedf77b]:5540: new best score: 5 (for CC0161AC8CF43C47-0000000000000010) [1738802814.356] [9504:9506] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 3 ms [1738802814.356] [9504:9506] [DIS] Keeping DNSSD lookup active [1738802814.553] [9504:9506] [DIS] Checking node lookup status for CC0161AC8CF43C47-0000000000000010 after 201 ms [1738802814.554] [9504:9506] [DIS] OperationalSessionSetup[1:0000000000000010]: Updating device address to UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540 while in state 2 [1738802814.554] [9504:9506] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 2 --> 3 [1738802814.554] [9504:9506] [IN] SecureSession[0xffffac00bc40]: Allocated Type:2 LSID:809 [1738802814.554] [9504:9506] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000010 [1738802814.556] [9504:9506] [EM] <<< [E:19938i S:0 M:152129138] (U) Msg TX from E06670FA24DFD2F1 to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) [1738802814.557] [9504:9506] [EM] ??1 [E:19938i S:0 M:152129138] (U) Msg Retransmission to 0:0000000000000000 in 3613ms [State:Idle II:3000 AI:2200 AT:300] [1738802814.557] [9504:9506] [SC] Sent Sigma1 msg to <0000000000000010, 1> [II:500ms AI:300ms AT:4000ms] [1738802814.557] [9504:9506] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 3 --> 4 [1738802814.779] [9504:9506] [EM] >>> [E:19938i S:0 M:123259107 (Ack:152129138)] (U) Msg RX from 0:0000000000000000 [0000] to E06670FA24DFD2F1 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802814.779] [9504:9506] [EM] Found matching exchange: 19938i, Delegate: 0xffffac00b958 [1738802814.779] [9504:9506] [EM] Rxd Ack; Removing MessageCounter:152129138 from Retrans Table on exchange 19938i [1738802814.973] [9504:9506] [EM] >>> [E:19938i S:0 M:123259108 (Ack:152129138)] (U) Msg RX from 0:0000000000000000 [0000] to E06670FA24DFD2F1 --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751) [1738802814.973] [9504:9506] [EM] Found matching exchange: 19938i, Delegate: 0xffffac00b958 [1738802814.973] [9504:9506] [EM] CHIP MessageCounter:152129138 not in RetransTable on exchange 19938i [1738802814.973] [9504:9506] [SC] Received Sigma2 msg [1738802814.973] [9504:9506] [SC] Peer assigned session session ID 57846 [1738802814.982] [9504:9506] [SC] Found MRP parameters in the message [1738802814.982] [9504:9506] [SC] Sending Sigma3 [1738802814.983] [9504:9506] [EM] <<< [E:19938i S:0 M:152129139 (Ack:123259108)] (U) Msg TX from E06670FA24DFD2F1 to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1738802814.984] [9504:9506] [EM] ??1 [E:19938i S:0 M:152129139] (U) Msg Retransmission to 0:0000000000000000 in 2872ms [State:Active II:3000 AI:2200 AT:300] [1738802814.984] [9504:9506] [SC] Sent Sigma3 msg [1738802815.262] [9504:9506] [EM] >>> [E:19938i S:0 M:123259109 (Ack:152129139)] (U) Msg RX from 0:0000000000000000 [0000] to E06670FA24DFD2F1 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802815.262] [9504:9506] [EM] Found matching exchange: 19938i, Delegate: 0xffffac00b958 [1738802815.262] [9504:9506] [EM] Rxd Ack; Removing MessageCounter:152129139 from Retrans Table on exchange 19938i [1738802815.491] [9504:9506] [EM] >>> [E:19938i S:0 M:123259110 (Ack:152129139)] (U) Msg RX from 0:0000000000000000 [0000] to E06670FA24DFD2F1 --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1738802815.491] [9504:9506] [EM] Found matching exchange: 19938i, Delegate: 0xffffac00b958 [1738802815.491] [9504:9506] [EM] CHIP MessageCounter:152129139 not in RetransTable on exchange 19938i [1738802815.492] [9504:9506] [SC] Success status report received. Session was established [1738802815.497] [9504:9506] [SC] SecureSession[0xffffac00bc40, LSID:809]: State change 'kEstablishing' --> 'kActive' [1738802815.497] [9504:9506] [IN] SecureSession[0xffffac00bc40]: Activated - Type:2 LSID:809 [1738802815.497] [9504:9506] [IN] New secure session activated for device <0000000000000010, 1>, LSID:809 PSID:57846! [1738802815.497] [9504:9506] [DIS] OperationalSessionSetup[1:0000000000000010]: State change 4 --> 5 [1738802815.498] [9504:9506] [TOO] Sending cluster (0x00000031) command (0x00000004) on endpoint 0 [1738802815.498] [9504:9506] [DMG] ICR moving to [AddingComm] [1738802815.498] [9504:9506] [DMG] ICR moving to [AddedComma] [1738802815.498] [9504:9506] [EM] <<< [E:19939i S:809 M:75589669] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:73) [1738802815.499] [9504:9506] [EM] ??1 [E:19939i S:809 M:75589669] (S) Msg Retransmission to 1:0000000000000010 in 2603ms [State:Active II:3000 AI:2200 AT:300] [1738802815.499] [9504:9506] [DMG] ICR moving to [AwaitingRe] [1738802815.499] [9504:9506] [EM] <<< [E:19938i S:0 M:152129140 (Ack:123259110)] (U) Msg TX from E06670FA24DFD2F1 to 0:0000000000000000 [0000] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1738802815.499] [9504:9506] [EM] Flushed pending ack for MessageCounter:123259110 on exchange 19938i [1738802815.499] [9504:9506] [DL] HandlePlatformSpecificBLEEvent 32792 [1738802815.673] [9504:9506] [EM] >>> [E:19939i S:809 M:164193700 (Ack:75589669)] (S) Msg RX from 1:0000000000000010 [3C47] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:67) [1738802815.673] [9504:9506] [EM] Found matching exchange: 19939i, Delegate: 0xffffac0081b8 [1738802815.673] [9504:9506] [EM] Rxd Ack; Removing MessageCounter:75589669 from Retrans Table on exchange 19939i [1738802815.673] [9504:9506] [DMG] ICR moving to [ResponseRe] [1738802815.673] [9504:9506] [DMG] InvokeResponseMessage = [1738802815.673] [9504:9506] [DMG] { [1738802815.673] [9504:9506] [DMG] suppressResponse = false, [1738802815.673] [9504:9506] [DMG] InvokeResponseIBs = [1738802815.673] [9504:9506] [DMG] [ [1738802815.673] [9504:9506] [DMG] InvokeResponseIB = [1738802815.673] [9504:9506] [DMG] { [1738802815.673] [9504:9506] [DMG] CommandDataIB = [1738802815.673] [9504:9506] [DMG] { [1738802815.673] [9504:9506] [DMG] CommandPathIB = [1738802815.673] [9504:9506] [DMG] { [1738802815.673] [9504:9506] [DMG] EndpointId = 0x0, [1738802815.673] [9504:9506] [DMG] ClusterId = 0x31, [1738802815.673] [9504:9506] [DMG] CommandId = 0x5, [1738802815.673] [9504:9506] [DMG] }, [1738802815.673] [9504:9506] [DMG] [1738802815.673] [9504:9506] [DMG] CommandFields = [1738802815.673] [9504:9506] [DMG] { [1738802815.674] [9504:9506] [DMG] 0x0 = 3 (unsigned), [1738802815.674] [9504:9506] [DMG] }, [1738802815.674] [9504:9506] [DMG] }, [1738802815.674] [9504:9506] [DMG] [1738802815.674] [9504:9506] [DMG] }, [1738802815.674] [9504:9506] [DMG] [1738802815.674] [9504:9506] [DMG] ], [1738802815.674] [9504:9506] [DMG] [1738802815.674] [9504:9506] [DMG] InteractionModelRevision = 11 [1738802815.674] [9504:9506] [DMG] }, [1738802815.674] [9504:9506] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005 [1738802815.674] [9504:9506] [TOO] Endpoint: 0 Cluster: 0x0000_0031 Command 0x0000_0005 [1738802815.674] [9504:9506] [TOO] NetworkConfigResponse: { [1738802815.674] [9504:9506] [TOO] networkingStatus: 3 [1738802815.674] [9504:9506] [TOO] } [1738802815.674] [9504:9506] [DMG] ICR moving to [AwaitingDe] [1738802815.674] [9504:9506] [EM] <<< [E:19939i S:809 M:75589670 (Ack:164193700)] (S) Msg TX from 000000000001B669 to 1:0000000000000010 [3C47] [UDP:[fd11:22::2d89:b999:cf04:c2b9]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1738802815.675] [9504:9506] [EM] Flushed pending ack for MessageCounter:164193700 on exchange 19939i [1738802815.675] [9504:9504] [CTL] Shutting down the commissioner [1738802815.675] [9504:9504] [CTL] Shutting down the controller [1738802815.675] [9504:9504] [IN] Expiring all sessions for fabric 0x1!! [1738802815.675] [9504:9504] [IN] SecureSession[0xffffac00bc40]: MarkForEviction Type:2 LSID:809 [1738802815.675] [9504:9504] [SC] SecureSession[0xffffac00bc40, LSID:809]: State change 'kActive' --> 'kPendingEviction' [1738802815.675] [9504:9504] [IN] SecureSession[0xffffac00bc40]: Released - Type:2 LSID:809 [1738802815.675] [9504:9504] [FP] Forgetting fabric 0x1 [1738802815.675] [9504:9504] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802815.675] [9504:9504] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802815.676] [9504:9504] [TS] Reverted Last Known Good Time to previous value [1738802815.676] [9504:9504] [CTL] Shutting down the commissioner [1738802815.676] [9504:9504] [CTL] Shutting down the controller [1738802815.676] [9504:9504] [CTL] Shutting down the System State, this will teardown the CHIP Stack [1738802815.676] [9504:9504] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1738802815.676] [9504:9504] [FP] Shutting down FabricTable [1738802815.676] [9504:9504] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1738802815.676] [9504:9504] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1738802815.676] [9504:9504] [TS] Reverted Last Known Good Time to previous value [1738802815.684] [9504:9504] [DL] Wrote settings to /tmp/chip_counters.ini [1738802815.685] [9504:9504] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1738802815.685] [9504:9504] [DL] Inet Layer shutdown [1738802815.685] [9504:9504] [DL] BLE Layer shutdown [1738802815.685] [9504:9504] [DL] System Layer shutdown ubuntu@ubuntu:~/apps$