root@ubuntu:~/python_testing/scripts/sdk# python3 TC_DeviceConformance.py --manual-code 34970112332 --storage-path admin_storage.json --tests test_TC_IDM_10_2 --paa-trust-store-path /credentials/development/paa-root-certs/ [1718105087.880118][12:12] CHIP:CTL: Setting attestation nonce to random value [1718105087.891201][12:12] CHIP:CTL: Setting CSR nonce to random value [1718105087.980033][12:12] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1718105087.994031][12:12] CHIP:DL: writing settings to file (/tmp/chip_kvs-Zy0wny) [1718105087.994419][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1718105087.994914][12:12] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1718105087.995540][12:12] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-teAHei) [1718105087.995978][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1718105087.996337][12:12] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1718105087.996932][12:12] CHIP:DL: writing settings to file (/tmp/chip_config.ini-kMBkAs) [1718105087.997430][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1718105087.997784][12:12] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1718105087.998343][12:12] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-d3walg) [1718105087.999228][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1718105088.008353][12:12] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-qSzOB5) [1718105088.010165][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1718105088.010260][12:12] CHIP:DL: NVS set: chip-factory/unique-id = "2230C23273897406" [1718105088.010639][12:12] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-JNGsYJ) [1718105088.012085][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1718105088.012175][12:12] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1) [1718105088.012628][12:12] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-2U0p4E) [1718105088.013784][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1718105088.013899][12:12] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001) [1718105088.014343][12:12] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-5EWc1P) [1718105088.015125][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1718105088.015341][12:12] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1) [1718105088.015942][12:12] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-xm3GW5) [1718105088.017904][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1718105088.018018][12:12] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1718105088.018481][12:12] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-PNKt9e) [1718105088.019377][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1718105088.019492][12:12] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0) [1718105088.020006][12:12] CHIP:DL: writing settings to file (/tmp/chip_config.ini-fL1sv1) [1718105088.020877][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1718105088.021019][12:12] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0) [1718105088.021464][12:12] CHIP:DL: writing settings to file (/tmp/chip_config.ini-sRLTOG) [1718105088.022364][12:12] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1718105088.022473][12:12] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2) [1718105088.023981][12:12] CHIP:DL: Got Ethernet interface: eth0 [1718105088.024740][12:12] CHIP:DL: Found the primary Ethernet interface:eth0 [1718105088.025510][12:12] CHIP:DL: Got WiFi interface: wlan0 [1718105088.025644][12:12] CHIP:DL: Failed to reset WiFi statistic counts Initializing persistent storage from file: admin_storage.json Loading configuration from admin_storage.json... [1718105088.029708][12:12] CHIP:IN: UDP::Init bind&listen port=0 [1718105088.029919][12:12] CHIP:IN: UDP::Init bound to port=43817 [1718105088.030006][12:12] CHIP:IN: UDP::Init bind&listen port=0 [1718105088.030195][12:12] CHIP:IN: UDP::Init bound to port=33624 [1718105088.030227][12:12] CHIP:IN: BLEBase::Init - setting/overriding transport [1718105088.030247][12:12] CHIP:IN: TransportMgr initialized [1718105088.033615][12:12] CHIP:FP: Initializing FabricTable from persistent storage [1718105088.037152][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = g/lkgt, Value = 0xfffff8f2f8c0 (18) [1718105088.037318][12:12] CHIP:CTL: Key Found 8 [1718105088.037432][12:12] CHIP:TS: Last Known Good Time: 2023-10-14T01:16:48 [1718105088.037495][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = g/fidx, Value = 0xfffff8f2faa8 (44) [1718105088.037590][12:12] CHIP:CTL: Key Found 40 [1718105088.037696][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/1/n, Value = 0xfffff8f2f5a8 (400) [1718105088.037857][12:12] CHIP:CTL: Key Found 250 [1718105088.037915][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/1/r, Value = 0xfffff8f2f738 (400) [1718105088.038133][12:12] CHIP:CTL: Key Found 400 [1718105088.040485][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/1/m, Value = 0xfffff8f2f468 (44) [1718105088.041065][12:12] CHIP:CTL: Key Found 9 [1718105088.043418][12:12] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x64DB79957D01B18A, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1718105088.043492][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/2/n, Value = 0xfffff8f2f5a8 (400) [1718105088.043755][12:12] CHIP:CTL: Key Found 347 [1718105088.043814][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/2/r, Value = 0xfffff8f2f738 (400) [1718105088.044035][12:12] CHIP:CTL: Key Found 400 [1718105088.044685][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/2/m, Value = 0xfffff8f2f468 (44) [1718105088.044896][12:12] CHIP:CTL: Key Found 9 [1718105088.044996][12:12] CHIP:FP: Fabric index 0x2 was retrieved from storage. Compressed FabricId 0x64DB79957D01B18A, FabricId 0x0000000000000001, NodeId 0x00000000000000C8, VendorId 0xFFF1 [1718105088.045065][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/3/n, Value = 0xfffff8f2f5a8 (400) [1718105088.045349][12:12] CHIP:CTL: Key Found 347 [1718105088.045423][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/3/r, Value = 0xfffff8f2f738 (400) [1718105088.045875][12:12] CHIP:CTL: Key Found 400 [1718105088.046581][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/3/m, Value = 0xfffff8f2f468 (44) [1718105088.046810][12:12] CHIP:CTL: Key Found 9 [1718105088.046921][12:12] CHIP:FP: Fabric index 0x3 was retrieved from storage. Compressed FabricId 0x64DB79957D01B18A, FabricId 0x0000000000000001, NodeId 0x000000000000012C, VendorId 0xFFF1 [1718105088.046986][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/4/n, Value = 0xfffff8f2f5a8 (400) [1718105088.047197][12:12] CHIP:CTL: Key Found 250 [1718105088.047261][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/4/r, Value = 0xfffff8f2f738 (400) [1718105088.047433][12:12] CHIP:CTL: Key Found 231 [1718105088.048033][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/4/m, Value = 0xfffff8f2f468 (44) [1718105088.048182][12:12] CHIP:CTL: Key Found 9 [1718105088.048284][12:12] CHIP:FP: Fabric index 0x4 was retrieved from storage. Compressed FabricId 0x4904FCDE3D37A7B7, FabricId 0x0000000000000002, NodeId 0x000000000001B669, VendorId 0xFFF1 [1718105088.048343][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/5/n, Value = 0xfffff8f2f5a8 (400) [1718105088.048666][12:12] CHIP:CTL: Key Found 250 [1718105088.048777][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/5/r, Value = 0xfffff8f2f738 (400) [1718105088.049016][12:12] CHIP:CTL: Key Found 231 [1718105088.049844][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/5/m, Value = 0xfffff8f2f468 (44) [1718105088.050089][12:12] CHIP:CTL: Key Found 9 [1718105088.050264][12:12] CHIP:FP: Fabric index 0x5 was retrieved from storage. Compressed FabricId 0xE38DB2B968D7D0FA, FabricId 0x0000000000000002, NodeId 0x000000000001B669, VendorId 0xFFF1 [1718105088.050327][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/6/n, Value = 0xfffff8f2f5a8 (400) [1718105088.050542][12:12] CHIP:CTL: Key Found 247 [1718105088.050604][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/6/r, Value = 0xfffff8f2f738 (400) [1718105088.050843][12:12] CHIP:CTL: Key Found 231 [1718105088.051352][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/6/m, Value = 0xfffff8f2f468 (44) [1718105088.051497][12:12] CHIP:CTL: Key Found 9 [1718105088.051587][12:12] CHIP:FP: Fabric index 0x6 was retrieved from storage. Compressed FabricId 0xE38DB2B968D7D0FA, FabricId 0x0000000000000002, NodeId 0x00000000000000C8, VendorId 0xFFF1 [1718105088.051639][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/7/n, Value = 0xfffff8f2f5a8 (400) [1718105088.051827][12:12] CHIP:CTL: Key Found 248 [1718105088.051970][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/7/r, Value = 0xfffff8f2f738 (400) [1718105088.052241][12:12] CHIP:CTL: Key Found 231 [1718105088.052769][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/7/m, Value = 0xfffff8f2f468 (44) [1718105088.053001][12:12] CHIP:CTL: Key Found 9 [1718105088.053110][12:12] CHIP:FP: Fabric index 0x7 was retrieved from storage. Compressed FabricId 0xE38DB2B968D7D0FA, FabricId 0x0000000000000002, NodeId 0x000000000000012C, VendorId 0xFFF1 [1718105088.053179][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/8/n, Value = 0xfffff8f2f5a8 (400) [1718105088.053405][12:12] CHIP:CTL: Key Found 250 [1718105088.053464][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/8/r, Value = 0xfffff8f2f738 (400) [1718105088.053704][12:12] CHIP:CTL: Key Found 231 [1718105088.054231][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/8/m, Value = 0xfffff8f2f468 (44) [1718105088.054382][12:12] CHIP:CTL: Key Found 9 [1718105088.054482][12:12] CHIP:FP: Fabric index 0x8 was retrieved from storage. Compressed FabricId 0x6F7085C3A38F6AAC, FabricId 0x0000000000000003, NodeId 0x000000000001B669, VendorId 0xFFF1 [1718105088.054539][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/9/n, Value = 0xfffff8f2f5a8 (400) [1718105088.054824][12:12] CHIP:CTL: Key Found 247 [1718105088.054900][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/9/r, Value = 0xfffff8f2f738 (400) [1718105088.055085][12:12] CHIP:CTL: Key Found 231 [1718105088.055838][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/9/m, Value = 0xfffff8f2f468 (44) [1718105088.055972][12:12] CHIP:CTL: Key Found 9 [1718105088.056048][12:12] CHIP:FP: Fabric index 0x9 was retrieved from storage. Compressed FabricId 0x6F7085C3A38F6AAC, FabricId 0x0000000000000003, NodeId 0x00000000000000C8, VendorId 0xFFF1 [1718105088.056085][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/a/n, Value = 0xfffff8f2f5a8 (400) [1718105088.056206][12:12] CHIP:CTL: Key Found 248 [1718105088.056252][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/a/r, Value = 0xfffff8f2f738 (400) [1718105088.056432][12:12] CHIP:CTL: Key Found 231 [1718105088.056878][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/a/m, Value = 0xfffff8f2f468 (44) [1718105088.057001][12:12] CHIP:CTL: Key Found 9 [1718105088.057078][12:12] CHIP:FP: Fabric index 0xa was retrieved from storage. Compressed FabricId 0x6F7085C3A38F6AAC, FabricId 0x0000000000000003, NodeId 0x000000000000012C, VendorId 0xFFF1 [1718105088.057121][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/b/n, Value = 0xfffff8f2f5a8 (400) [1718105088.057267][12:12] CHIP:CTL: Key Found 250 [1718105088.057319][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/b/r, Value = 0xfffff8f2f738 (400) [1718105088.057442][12:12] CHIP:CTL: Key Found 231 [1718105088.058011][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/b/m, Value = 0xfffff8f2f468 (44) [1718105088.058157][12:12] CHIP:CTL: Key Found 9 [1718105088.058238][12:12] CHIP:FP: Fabric index 0xb was retrieved from storage. Compressed FabricId 0x28B813923DAC4C77, FabricId 0x0000000000000004, NodeId 0x000000000001B669, VendorId 0xFFF1 [1718105088.058282][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/c/n, Value = 0xfffff8f2f5a8 (400) [1718105088.058425][12:12] CHIP:CTL: Key Found 247 [1718105088.058474][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/c/r, Value = 0xfffff8f2f738 (400) [1718105088.058593][12:12] CHIP:CTL: Key Found 231 [1718105088.059295][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/c/m, Value = 0xfffff8f2f468 (44) [1718105088.059442][12:12] CHIP:CTL: Key Found 9 [1718105088.059525][12:12] CHIP:FP: Fabric index 0xc was retrieved from storage. Compressed FabricId 0x28B813923DAC4C77, FabricId 0x0000000000000004, NodeId 0x00000000000000C8, VendorId 0xFFF1 [1718105088.059574][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/d/n, Value = 0xfffff8f2f5a8 (400) [1718105088.059797][12:12] CHIP:CTL: Key Found 248 [1718105088.059862][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/d/r, Value = 0xfffff8f2f738 (400) [1718105088.059993][12:12] CHIP:CTL: Key Found 231 [1718105088.060423][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/d/m, Value = 0xfffff8f2f468 (44) [1718105088.060662][12:12] CHIP:CTL: Key Found 9 [1718105088.060745][12:12] CHIP:FP: Fabric index 0xd was retrieved from storage. Compressed FabricId 0x28B813923DAC4C77, FabricId 0x0000000000000004, NodeId 0x000000000000012C, VendorId 0xFFF1 [1718105088.060794][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/e/n, Value = 0xfffff8f2f5a8 (400) [1718105088.060940][12:12] CHIP:CTL: Key Found 250 [1718105088.060991][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/e/r, Value = 0xfffff8f2f738 (400) [1718105088.061113][12:12] CHIP:CTL: Key Found 231 [1718105088.061532][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/e/m, Value = 0xfffff8f2f468 (44) [1718105088.061664][12:12] CHIP:CTL: Key Found 9 [1718105088.061808][12:12] CHIP:FP: Fabric index 0xe was retrieved from storage. Compressed FabricId 0xBE2CC9256DCD655B, FabricId 0x0000000000000005, NodeId 0x000000000001B669, VendorId 0xFFF1 [1718105088.061857][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/f/n, Value = 0xfffff8f2f5a8 (400) [1718105088.062006][12:12] CHIP:CTL: Key Found 247 [1718105088.062058][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/f/r, Value = 0xfffff8f2f738 (400) [1718105088.062183][12:12] CHIP:CTL: Key Found 231 [1718105088.062593][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/f/m, Value = 0xfffff8f2f468 (44) [1718105088.062795][12:12] CHIP:CTL: Key Found 9 [1718105088.062877][12:12] CHIP:FP: Fabric index 0xf was retrieved from storage. Compressed FabricId 0xBE2CC9256DCD655B, FabricId 0x0000000000000005, NodeId 0x00000000000000C8, VendorId 0xFFF1 [1718105088.062925][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/10/n, Value = 0xfffff8f2f5a8 (400) [1718105088.063064][12:12] CHIP:CTL: Key Found 248 [1718105088.063116][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/10/r, Value = 0xfffff8f2f738 (400) [1718105088.063241][12:12] CHIP:CTL: Key Found 231 [1718105088.063661][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = f/10/m, Value = 0xfffff8f2f468 (44) [1718105088.063887][12:12] CHIP:CTL: Key Found 9 [1718105088.063967][12:12] CHIP:FP: Fabric index 0x10 was retrieved from storage. Compressed FabricId 0xBE2CC9256DCD655B, FabricId 0x0000000000000005, NodeId 0x000000000000012C, VendorId 0xFFF1 [1718105088.064004][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = g/fs/c, Value = 0xfffff8f2f950 (36) [1718105088.064047][12:12] CHIP:CTL: Key Not Found [1718105088.064234][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = g/gcc, Value = 0xfffff8f2f9dc (4) [1718105088.064323][12:12] CHIP:CTL: Key Found 4 [1718105088.064363][12:12] CHIP:CTL: StorageAdapter::GetKeyValue: Key = g/gdc, Value = 0xfffff8f2f9dc (4) [1718105088.064408][12:12] CHIP:CTL: Key Found 4 [1718105088.064442][12:12] CHIP:CTL: StorageAdapter::SetKeyValue: Key = g/gcc, Value = 0xfffff8f2f9dc (4) [1718105088.066599][12:12] CHIP:CTL: StorageAdapter::SetKeyValue: Key = g/gdc, Value = 0xfffff8f2f9dc (4) [1718105088.071593][12:12] CHIP:ZCL: Using ZAP configuration... [1718105088.075988][12:12] CHIP:DL: Avahi client registered [1718105088.076153][12:12] CHIP:IN: CASE Server enabling CASE session setups [1718105088.076400][12:12] CHIP:IN: SecureSession[0xaaaaf6bf7d40]: Allocated Type:2 LSID:28612 [1718105088.076491][12:12] CHIP:SC: Allocated SecureSession (0xaaaaf6bf7d40) - waiting for Sigma1 msg [1718105088.076625][12:12] CHIP:CTL: System State Initialized... [1718105088.077140][12:15] CHIP:DL: CHIP task running [1718105088.077633][12:15] CHIP:DL: HandlePlatformSpecificBLEEvent 32786 Loading certificate authorities from storage... New CertificateAuthority at index 1 Loading fabric admins from storage... New FabricAdmin: FabricId: 0x0000000000000001, VendorId = 0xFFF1 New CertificateAuthority at index 2 Loading fabric admins from storage... New FabricAdmin: FabricId: 0x0000000000000002, VendorId = 0xFFF1 New CertificateAuthority at index 3 Loading fabric admins from storage... New FabricAdmin: FabricId: 0x0000000000000002, VendorId = 0xFFF1 New CertificateAuthority at index 4 Loading fabric admins from storage... New FabricAdmin: FabricId: 0x0000000000000003, VendorId = 0xFFF1 New CertificateAuthority at index 5 Loading fabric admins from storage... New FabricAdmin: FabricId: 0x0000000000000004, VendorId = 0xFFF1 New CertificateAuthority at index 6 Loading fabric admins from storage... New FabricAdmin: FabricId: 0x0000000000000005, VendorId = 0xFFF1 New CertificateAuthority at index 7 Loading fabric admins from storage... New FabricAdmin: FabricId: 0x0000000000000002, VendorId = 0xFFF1 Allocating new controller with CaIndex: 1, FabricId: 0x0000000000000001, NodeId: 0x000000000001B669, CatTags: [] Long dispatch time: 1063 ms, for event type 2 [MatterTest] 06-11 11:24:49.403 INFO Starting test set, running 1 tests [MatterTest] 06-11 11:24:49.410 INFO ==========> TC_DeviceConformance <========== [MatterTest] 06-11 11:24:49.416 INFO Starting commissioning discovery over BLE [MatterTest] 06-11 11:24:49.416 INFO Starting commissioning discovery over DNS-SD [MatterTest] 06-11 11:24:49.482 INFO BLE removing known devices. [MatterTest] 06-11 11:24:49.485 INFO BLE initiating scan. [MatterTest] 06-11 11:24:49.491 INFO Avahi browse: cache new [MatterTest] 06-11 11:24:49.492 INFO Avahi browse: cache exhausted [MatterTest] 06-11 11:24:49.735 INFO Avahi group established [MatterTest] 06-11 11:24:49.759 INFO Avahi group established [MatterTest] 06-11 11:24:49.779 INFO Avahi group established [MatterTest] 06-11 11:24:49.811 INFO Avahi group established [MatterTest] 06-11 11:24:49.825 INFO Avahi group established [MatterTest] 06-11 11:24:49.849 INFO Avahi group established [MatterTest] 06-11 11:24:49.878 INFO Avahi group established [MatterTest] 06-11 11:24:49.919 INFO Avahi group established [MatterTest] 06-11 11:24:49.939 INFO Avahi group established [MatterTest] 06-11 11:24:49.960 INFO Avahi group established [MatterTest] 06-11 11:24:50.004 INFO Avahi group established [MatterTest] 06-11 11:24:50.026 INFO Avahi group established [MatterTest] 06-11 11:24:50.073 INFO Avahi group established [MatterTest] 06-11 11:24:50.096 INFO Avahi group established [MatterTest] 06-11 11:24:50.150 INFO Avahi group established [MatterTest] 06-11 11:24:50.209 INFO Avahi group established [MatterTest] 06-11 11:24:50.275 INFO Avahi group established [MatterTest] 06-11 11:24:50.400 INFO New device scanned: E4:5F:01:0F:28:79 [MatterTest] 06-11 11:24:50.401 INFO Device discriminator match. Attempting to connect. [MatterTest] 06-11 11:24:50.430 INFO Avahi browse: all for now [MatterTest] 06-11 11:24:50.435 INFO Avahi resolve found [MatterTest] 06-11 11:24:50.438 INFO Discovered device to be commissioned over DNS-SD [MatterTest] 06-11 11:24:50.438 INFO Attempting PASE connection to UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540 [MatterTest] 06-11 11:24:50.440 INFO <<< [E:7400i S:0 M:137235224] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) [MatterTest] 06-11 11:24:50.443 INFO >>> [E:7400i S:0 M:235480049 (Ack:137235224)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) [MatterTest] 06-11 11:24:50.452 INFO <<< [E:7400i S:0 M:137235225 (Ack:235480049)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0000:22 (SecureChannel:PASE_Pake1) [MatterTest] 06-11 11:24:50.460 INFO >>> [E:7400i S:0 M:235480050 (Ack:137235225)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) [MatterTest] 06-11 11:24:50.463 INFO <<< [E:7400i S:0 M:137235226 (Ack:235480050)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0000:24 (SecureChannel:PASE_Pake3) [MatterTest] 06-11 11:24:50.465 INFO >>> [E:7400i S:0 M:235480051 (Ack:137235226)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [MatterTest] 06-11 11:24:50.467 INFO SecureSession[0xffff78016af0, LSID:28613]: State change 'kEstablishing' --> 'kActive' [MatterTest] 06-11 11:24:50.468 INFO Pairing with commissionee successful, stopping discovery [MatterTest] 06-11 11:24:50.470 ERROR FAIL: ConnectDevice: Operation was cancelled (19) Established secure session with Device [MatterTest] 06-11 11:24:50.471 INFO <<< [E:7400i S:0 M:137235227 (Ack:235480051)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) [MatterTest] 06-11 11:24:50.472 ERROR Device connection failed: src/platform/Linux/bluez/BluezEndpoint.cpp:749: CHIP Error 0x000000AC: Internal error Using PASE connection [MatterTest] 06-11 11:24:50.475 ERROR Commissioning discovery over BLE failed: src/platform/Linux/bluez/BluezEndpoint.cpp:748: CHIP Error 0x000000AC: Internal error [MatterTest] 06-11 11:24:50.477 ERROR src/platform/Linux/bluez/BluezEndpoint.cpp:748: CHIP Error 0x000000AC: Internal error at src/controller/SetUpCodePairer.cpp:324 [MatterTest] 06-11 11:24:50.479 INFO <<< [E:7401i S:28613 M:224689091] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:02 (IM:ReadRequest) [MatterTest] 06-11 11:24:50.493 INFO >>> [E:7401i S:28613 M:266659669 (Ack:224689091)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:50.588 INFO <<< [E:7401i S:28613 M:224689092 (Ack:266659669)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:50.601 INFO >>> [E:7401i S:28613 M:266659670 (Ack:224689092)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:50.691 INFO <<< [E:7401i S:28613 M:224689093 (Ack:266659670)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:50.704 INFO >>> [E:7401i S:28613 M:266659671 (Ack:224689093)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:50.793 INFO <<< [E:7401i S:28613 M:224689094 (Ack:266659671)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:50.812 INFO >>> [E:7401i S:28613 M:266659672 (Ack:224689094)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:50.913 INFO <<< [E:7401i S:28613 M:224689095 (Ack:266659672)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:50.931 INFO >>> [E:7401i S:28613 M:266659673 (Ack:224689095)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.033 INFO <<< [E:7401i S:28613 M:224689096 (Ack:266659673)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:51.047 INFO >>> [E:7401i S:28613 M:266659674 (Ack:224689096)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.151 INFO <<< [E:7401i S:28613 M:224689097 (Ack:266659674)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:51.175 INFO >>> [E:7401i S:28613 M:266659675 (Ack:224689097)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.274 INFO <<< [E:7401i S:28613 M:224689098 (Ack:266659675)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:51.289 INFO >>> [E:7401i S:28613 M:266659676 (Ack:224689098)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.384 INFO <<< [E:7401i S:28613 M:224689099 (Ack:266659676)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:51.396 INFO >>> [E:7401i S:28613 M:266659677 (Ack:224689099)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.487 INFO <<< [E:7401i S:28613 M:224689100 (Ack:266659677)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:51.499 INFO >>> [E:7401i S:28613 M:266659678 (Ack:224689100)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.589 INFO <<< [E:7401i S:28613 M:224689101 (Ack:266659678)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:51.601 INFO >>> [E:7401i S:28613 M:266659679 (Ack:224689101)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.686 INFO <<< [E:7401i S:28613 M:224689102 (Ack:266659679)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:51.698 INFO >>> [E:7401i S:28613 M:266659680 (Ack:224689102)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.789 INFO <<< [E:7401i S:28613 M:224689103 (Ack:266659680)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:51.800 INFO >>> [E:7401i S:28613 M:266659681 (Ack:224689103)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.887 INFO <<< [E:7401i S:28613 M:224689104 (Ack:266659681)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:51.899 INFO >>> [E:7401i S:28613 M:266659682 (Ack:224689104)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:51.991 INFO <<< [E:7401i S:28613 M:224689105 (Ack:266659682)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.002 INFO >>> [E:7401i S:28613 M:266659683 (Ack:224689105)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.087 INFO <<< [E:7401i S:28613 M:224689106 (Ack:266659683)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.099 INFO >>> [E:7401i S:28613 M:266659684 (Ack:224689106)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.189 INFO <<< [E:7401i S:28613 M:224689107 (Ack:266659684)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.202 INFO >>> [E:7401i S:28613 M:266659685 (Ack:224689107)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.298 INFO <<< [E:7401i S:28613 M:224689108 (Ack:266659685)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.311 INFO >>> [E:7401i S:28613 M:266659686 (Ack:224689108)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.398 INFO <<< [E:7401i S:28613 M:224689109 (Ack:266659686)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.411 INFO >>> [E:7401i S:28613 M:266659687 (Ack:224689109)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.499 INFO <<< [E:7401i S:28613 M:224689110 (Ack:266659687)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.514 INFO >>> [E:7401i S:28613 M:266659688 (Ack:224689110)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.594 INFO <<< [E:7401i S:28613 M:224689111 (Ack:266659688)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.607 INFO >>> [E:7401i S:28613 M:266659689 (Ack:224689111)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.703 INFO <<< [E:7401i S:28613 M:224689112 (Ack:266659689)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.714 INFO >>> [E:7401i S:28613 M:266659690 (Ack:224689112)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.799 INFO <<< [E:7401i S:28613 M:224689113 (Ack:266659690)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.810 INFO >>> [E:7401i S:28613 M:266659691 (Ack:224689113)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.895 INFO <<< [E:7401i S:28613 M:224689114 (Ack:266659691)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:52.908 INFO >>> [E:7401i S:28613 M:266659692 (Ack:224689114)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:52.999 INFO <<< [E:7401i S:28613 M:224689115 (Ack:266659692)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.012 INFO >>> [E:7401i S:28613 M:266659693 (Ack:224689115)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:53.107 INFO <<< [E:7401i S:28613 M:224689116 (Ack:266659693)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.121 INFO >>> [E:7401i S:28613 M:266659694 (Ack:224689116)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:53.220 INFO <<< [E:7401i S:28613 M:224689117 (Ack:266659694)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.233 INFO >>> [E:7401i S:28613 M:266659695 (Ack:224689117)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:53.333 INFO <<< [E:7401i S:28613 M:224689118 (Ack:266659695)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.343 INFO >>> [E:7401i S:28613 M:266659696 (Ack:224689118)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:53.431 INFO <<< [E:7401i S:28613 M:224689119 (Ack:266659696)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.445 INFO >>> [E:7401i S:28613 M:266659697 (Ack:224689119)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:53.544 INFO <<< [E:7401i S:28613 M:224689120 (Ack:266659697)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.557 INFO >>> [E:7401i S:28613 M:266659698 (Ack:224689120)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:53.646 INFO <<< [E:7401i S:28613 M:224689121 (Ack:266659698)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.659 INFO >>> [E:7401i S:28613 M:266659699 (Ack:224689121)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:53.749 INFO <<< [E:7401i S:28613 M:224689122 (Ack:266659699)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.760 INFO >>> [E:7401i S:28613 M:266659700 (Ack:224689122)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:53.853 INFO <<< [E:7401i S:28613 M:224689123 (Ack:266659700)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.866 INFO >>> [E:7401i S:28613 M:266659701 (Ack:224689123)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:53.955 INFO <<< [E:7401i S:28613 M:224689124 (Ack:266659701)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:53.968 INFO >>> [E:7401i S:28613 M:266659702 (Ack:224689124)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:54.060 INFO <<< [E:7401i S:28613 M:224689125 (Ack:266659702)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:54.073 INFO >>> [E:7401i S:28613 M:266659703 (Ack:224689125)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:54.163 INFO <<< [E:7401i S:28613 M:224689126 (Ack:266659703)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:54.174 INFO >>> [E:7401i S:28613 M:266659704 (Ack:224689126)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:54.247 INFO <<< [E:7401i S:28613 M:224689127 (Ack:266659704)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:54.251 INFO >>> [E:7401i S:28613 M:266659705 (Ack:224689127)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:54.258 INFO <<< [E:7401i S:28613 M:224689128 (Ack:266659705)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:54.262 INFO >>> [E:7401i S:28613 M:266659706 (Ack:224689128)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:54.277 INFO <<< [E:7401i S:28613 M:224689129 (Ack:266659706)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:54.290 INFO >>> [E:7401i S:28613 M:266659707 (Ack:224689129)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:54.380 INFO <<< [E:7401i S:28613 M:224689130 (Ack:266659707)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:54.390 INFO >>> [E:7401i S:28613 M:266659708 (Ack:224689130)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:54.467 INFO <<< [E:7401i S:28613 M:224689131 (Ack:266659708)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:54.479 INFO >>> [E:7401i S:28613 M:266659709 (Ack:224689131)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:54.572 INFO <<< [E:7401i S:28613 M:224689132 (Ack:266659709)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0001:01 (IM:StatusResponse) [MatterTest] 06-11 11:24:54.576 INFO >>> [E:7401i S:28613 M:266659710 (Ack:224689132)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:56.622 INFO <<< [E:7401i S:28613 M:224689133 (Ack:266659710)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) [MatterTest] 06-11 11:24:56.664 INFO >>> [E:7401i S:28613 M:266659710 (Ack:224689132)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:56.707 INFO <<< [E:7401i S:28613 M:224689134 (Ack:266659710)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) [MatterTest] 06-11 11:24:56.739 INFO >>> [E:7401i S:28613 M:266659710 (Ack:224689132)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:56.753 INFO ########################################################### [MatterTest] 06-11 11:24:56.754 INFO Start of actual tests [MatterTest] 06-11 11:24:56.755 INFO ########################################################### [MatterTest] 06-11 11:24:56.755 INFO <<< [E:7401i S:28613 M:224689135 (Ack:266659710)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) [MatterTest] 06-11 11:24:56.758 INFO >>> [E:7401i S:28613 M:266659710 (Ack:224689132)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [MatterTest] 06-11 11:24:56.759 INFO <<< [E:7401i S:28613 M:224689136 (Ack:266659710)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::e65f:1ff:fe0f:2877%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) [MatterTest] 06-11 11:24:56.761 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/TemperatureControl.xml [MatterTest] 06-11 11:24:56.764 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/PowerSourceCluster.xml [MatterTest] 06-11 11:24:56.771 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DiagnosticsWiFi.xml [MatterTest] 06-11 11:24:56.775 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Group-Key-Management-Cluster.xml [MatterTest] 06-11 11:24:56.778 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DiagnosticsThread.xml [MatterTest] 06-11 11:24:56.785 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/OnOff.xml [MatterTest] 06-11 11:24:56.788 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/LaundryDryerControls.xml [MatterTest] 06-11 11:24:56.790 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Scenes.xml [MatterTest] 06-11 11:24:56.794 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DiagnosticsGeneral.xml [MatterTest] 06-11 11:24:56.799 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/LevelControl.xml [MatterTest] 06-11 11:24:56.804 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ModeSelect.xml [MatterTest] 06-11 11:24:56.806 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ValveConfigurationControl.xml [MatterTest] 06-11 11:24:56.809 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Label-Cluster-FixedLabelCluster.xml [MatterTest] 06-11 11:24:56.811 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/AdminCommissioningCluster.xml [MatterTest] 06-11 11:24:56.814 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/OTAProvider.xml [MatterTest] 06-11 11:24:56.817 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/LocalizationConfiguration.xml [MatterTest] 06-11 11:24:56.819 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ModeBase.xml [MatterTest] 06-11 11:24:56.821 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/OperationalState.xml [MatterTest] 06-11 11:24:56.824 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/WiFiNetworkManagement.xml [MatterTest] 06-11 11:24:56.826 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_Dishwasher.xml [MatterTest] 06-11 11:24:56.828 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/MicrowaveOvenControl.xml [MatterTest] 06-11 11:24:56.831 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/OccupancySensing.xml [MatterTest] 06-11 11:24:56.835 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/PowerSourceConfigurationCluster.xml [MatterTest] 06-11 11:24:56.837 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ResourceMonitoring.xml [MatterTest] 06-11 11:24:56.840 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/BooleanState.xml [MatterTest] 06-11 11:24:56.842 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ACL-Cluster.xml [MatterTest] 06-11 11:24:56.845 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_LaundryWasher.xml [MatterTest] 06-11 11:24:56.847 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/bridge-clusters-BridgedDeviceBasicInformationCluster.xml [MatterTest] 06-11 11:24:56.849 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DiagnosticsSoftware.xml [MatterTest] 06-11 11:24:56.852 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ThermostatUserInterfaceConfiguration.xml [MatterTest] 06-11 11:24:56.854 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_MicrowaveOven.xml [MatterTest] 06-11 11:24:56.856 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Messages.xml [MatterTest] 06-11 11:24:56.860 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/BooleanStateConfiguration.xml [MatterTest] 06-11 11:24:56.863 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/OperationalCredentialCluster.xml [MatterTest] 06-11 11:24:56.867 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/NetworkCommissioningCluster.xml [MatterTest] 06-11 11:24:56.872 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_WaterHeater.xml [MatterTest] 06-11 11:24:56.874 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/OTASoftwareUpdate.xml [MatterTest] 06-11 11:24:56.876 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Binding-Cluster.xml [MatterTest] 06-11 11:24:56.878 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/SmokeCOAlarm.xml [MatterTest] 06-11 11:24:56.881 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/AlarmBase.xml [MatterTest] 06-11 11:24:56.884 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/TemperatureMeasurement.xml [MatterTest] 06-11 11:24:56.886 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/WiFiPerDeviceCredentials.xml [MatterTest] 06-11 11:24:56.888 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Groups.xml [MatterTest] 06-11 11:24:56.890 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/TimeSync.xml [MatterTest] 06-11 11:24:56.895 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/EnergyCalendar.xml [MatterTest] 06-11 11:24:56.899 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DemandResponseLoadControl.xml [MatterTest] 06-11 11:24:56.903 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ICDManagement.xml [MatterTest] 06-11 11:24:56.907 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/WaterHeaterManagement.xml [MatterTest] 06-11 11:24:56.910 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DeviceEnergyManagement.xml [MatterTest] 06-11 11:24:56.915 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_Refrigerator.xml [MatterTest] 06-11 11:24:56.917 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/TargetNavigator.xml [MatterTest] 06-11 11:24:56.920 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ElectricalPowerMeasurement.xml [MatterTest] 06-11 11:24:56.924 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/WaterContentMeasurement.xml [MatterTest] 06-11 11:24:56.926 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/IlluminanceMeasurement.xml [MatterTest] 06-11 11:24:56.928 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/AudioOutput.xml [MatterTest] 06-11 11:24:56.931 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/LaundryWasherControls.xml [MatterTest] 06-11 11:24:56.933 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/bridge-clusters-ActionsCluster.xml [MatterTest] 06-11 11:24:56.937 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/BasicInformationCluster.xml [MatterTest] 06-11 11:24:56.941 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/AccountLogin.xml [MatterTest] 06-11 11:24:56.944 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ConcentrationMeasurement.xml [MatterTest] 06-11 11:24:56.953 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/BallastConfiguration.xml [MatterTest] 06-11 11:24:56.956 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Descriptor-Cluster.xml [MatterTest] 06-11 11:24:56.958 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DiagnosticLogsCluster.xml [MatterTest] 06-11 11:24:56.960 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/PumpConfigurationControl.xml [MatterTest] 06-11 11:24:56.965 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/LocalizationTimeFormat.xml [MatterTest] 06-11 11:24:56.968 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/energy_management.xml [MatterTest] 06-11 11:24:56.969 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/GeneralCommissioningCluster.xml [MatterTest] 06-11 11:24:56.972 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DiagnosticsEthernet.xml [MatterTest] 06-11 11:24:56.975 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/OperationalState_Oven.xml [MatterTest] 06-11 11:24:56.977 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ColorControl.xml [MatterTest] 06-11 11:24:56.987 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/FanControl.xml [MatterTest] 06-11 11:24:56.992 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/NetworkIdentityManagement.xml [MatterTest] 06-11 11:24:56.994 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/OperationalState_RVC.xml [MatterTest] 06-11 11:24:56.997 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/MediaInput.xml [MatterTest] 06-11 11:24:56.999 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ThreadBorderRouterDiagnostics.xml [MatterTest] 06-11 11:24:57.001 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Channel.xml [MatterTest] 06-11 11:24:57.005 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ApplicationBasic.xml [MatterTest] 06-11 11:24:57.008 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Label-Cluster-UserLabelCluster.xml [MatterTest] 06-11 11:24:57.010 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/LocalizationUnit.xml [MatterTest] 06-11 11:24:57.012 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/network_infrastructure.xml [MatterTest] 06-11 11:24:57.014 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/EnergyPrice.xml [MatterTest] 06-11 11:24:57.017 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ApplicationLauncher.xml [MatterTest] 06-11 11:24:57.019 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_Oven.xml [MatterTest] 06-11 11:24:57.021 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_RVCRun.xml [MatterTest] 06-11 11:24:57.023 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Identify.xml [MatterTest] 06-11 11:24:57.025 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/WakeOnLAN.xml [MatterTest] 06-11 11:24:57.027 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ContentControl.xml [MatterTest] 06-11 11:24:57.032 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/PressureMeasurement.xml [MatterTest] 06-11 11:24:57.035 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/LowPower.xml [MatterTest] 06-11 11:24:57.037 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ElectricalEnergyMeasurement.xml [MatterTest] 06-11 11:24:57.040 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/PowerTopology.xml [MatterTest] 06-11 11:24:57.042 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DoorLock.xml [MatterTest] 06-11 11:24:57.059 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/OTARequestor.xml [MatterTest] 06-11 11:24:57.063 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Label-Cluster-LabelCluster.xml [MatterTest] 06-11 11:24:57.065 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/EnergyPreference.xml [MatterTest] 06-11 11:24:57.067 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/KeypadInput.xml [MatterTest] 06-11 11:24:57.070 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/AirQuality.xml [MatterTest] 06-11 11:24:57.072 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Thermostat.xml [MatterTest] 06-11 11:24:57.081 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/MediaPlayback.xml [MatterTest] 06-11 11:24:57.087 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ContentLauncher.xml [MatterTest] 06-11 11:24:57.091 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/ContentAppObserver.xml [MatterTest] 06-11 11:24:57.093 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/RefrigeratorAlarm.xml [MatterTest] 06-11 11:24:57.095 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_DeviceEnergyManagement.xml [MatterTest] 06-11 11:24:57.097 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/EnergyEVSE.xml [MatterTest] 06-11 11:24:57.102 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_RVCClean.xml [MatterTest] 06-11 11:24:57.105 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Mode_EVSE.xml [MatterTest] 06-11 11:24:57.106 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/WindowCovering.xml [MatterTest] 06-11 11:24:57.113 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/FlowMeasurement.xml [MatterTest] 06-11 11:24:57.115 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/Switch.xml [MatterTest] 06-11 11:24:57.118 INFO Parsing file /root/python_testing/scripts/sdk/../../data_model/clusters/DishwasherAlarm.xml [MatterTest] 06-11 11:24:57.140 INFO [Test] test_TC_IDM_10_2 [MatterTest] 06-11 11:24:57.140 INFO Starting test from /root/python_testing/scripts/sdk/TC_DeviceConformance.py: test_TC_IDM_10_2 - 1 steps [MatterTest] 06-11 11:24:57.141 INFO ***** Test Step 1 : Run entire test success = False [MatterTest] 06-11 11:24:57.162 ERROR Exception occurred in test_TC_IDM_10_2. Traceback (most recent call last): File "/usr/local/lib/python3.10/dist-packages/mobly/base_test.py", line 783, in exec_one_test test_method() File "/root/python_testing/scripts/sdk/TC_DeviceConformance.py", line 230, in test_TC_IDM_10_2 self.fail_current_test("Problems with conformance") File "/root/python_testing/scripts/sdk/basic_composition_support.py", line 149, in fail_current_test asserts.fail(msg) File "/usr/local/lib/python3.10/dist-packages/mobly/asserts.py", line 475, in fail raise signals.TestFailure(msg, extras) mobly.signals.TestFailure: Details=Problems with conformance, Extras=None [MatterTest] 06-11 11:24:57.169 INFO ***** Test Failure : [MatterTest] 06-11 11:24:57.169 INFO Finished test in 28ms [MatterTest] 06-11 11:24:57.170 INFO [Test] test_TC_IDM_10_2 FAIL [MatterTest] 06-11 11:24:57.177 INFO ########################################################### [MatterTest] 06-11 11:24:57.177 INFO Problems found: [MatterTest] 06-11 11:24:57.177 INFO =============== [MatterTest] 06-11 11:24:57.178 INFO Problem: ProblemSeverity.WARNING test_name: Spec XML parsing location: Endpoint: 0, Cluster: 257 (0x101) DoorLock, Command: 32 (0x20) problem: Command with unknown direction spec_location: [MatterTest] 06-11 11:24:57.178 INFO Problem: ProblemSeverity.WARNING test_name: Spec XML parsing location: Endpoint: 0, Cluster: 257 (0x101) DoorLock, Command: 33 (0x21) problem: Command with unknown direction spec_location: [MatterTest] 06-11 11:24:57.178 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 0, Cluster: 49 (0x31) NetworkCommissioning, Attribute:2 (0x02) problem: Attribute 0x02 is included, but is disallowed by conformance. Conformance: WI | TH, implemented features: ET spec_location: [MatterTest] 06-11 11:24:57.179 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 0, Cluster: 49 (0x31) NetworkCommissioning, Attribute:3 (0x03) problem: Attribute 0x03 is included, but is disallowed by conformance. Conformance: WI | TH, implemented features: ET spec_location: [MatterTest] 06-11 11:24:57.179 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 0, Cluster: 49 (0x31) NetworkCommissioning, Attribute:8 (0x08) problem: Attribute 0x08 is included, but is disallowed by conformance. Conformance: WI, implemented features: ET spec_location: [MatterTest] 06-11 11:24:57.180 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 0, Cluster: 49 (0x31) NetworkCommissioning, Attribute:9 (0x09) problem: Attribute 0x09 is included, but is disallowed by conformance. Conformance: TH, implemented features: ET spec_location: [MatterTest] 06-11 11:24:57.180 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 0, Cluster: 49 (0x31) NetworkCommissioning, Attribute:10 (0x0a) problem: Attribute 0x0a is included, but is disallowed by conformance. Conformance: TH, implemented features: ET spec_location: [MatterTest] 06-11 11:24:57.181 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 0, Cluster: 60 (0x3c) AdministratorCommissioning, Command: 1 (0x01) problem: Command 0x01 is included, but disallowed by conformance. Conformance: BC, implemented features: spec_location: [MatterTest] 06-11 11:24:57.181 INFO Problem: ProblemSeverity.WARNING test_name: IDM-10.2 location: Endpoint: 1, Cluster: 7 (0x07) OnOffSwitchConfiguration problem: Standard cluster found on device, but is not present in spec data spec_location: [MatterTest] 06-11 11:24:57.182 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 8 (0x08) LevelControl, Attribute:4 (0x04) problem: Attribute 0x04 is included, but is disallowed by conformance. Conformance: FQ, implemented features: OO,LT spec_location: [MatterTest] 06-11 11:24:57.182 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 8 (0x08) LevelControl, Attribute:5 (0x05) problem: Attribute 0x05 is included, but is disallowed by conformance. Conformance: FQ, implemented features: OO,LT spec_location: [MatterTest] 06-11 11:24:57.183 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 8 (0x08) LevelControl, Attribute:6 (0x06) problem: Attribute 0x06 is included, but is disallowed by conformance. Conformance: FQ, implemented features: OO,LT spec_location: [MatterTest] 06-11 11:24:57.183 INFO Problem: ProblemSeverity.WARNING test_name: IDM-10.2 location: Endpoint: 1, Cluster: 15 (0x0f) BinaryInputBasic problem: Standard cluster found on device, but is not present in spec data spec_location: [MatterTest] 06-11 11:24:57.184 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 81 (0x51) LaundryWasherMode, Attribute:2 (0x02) problem: Attribute 0x02 is included, but is disallowed by conformance. Conformance: P, implemented features: DEPONOFF spec_location: [MatterTest] 06-11 11:24:57.184 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 81 (0x51) LaundryWasherMode, Attribute:3 (0x03) problem: Attribute 0x03 is included, but is disallowed by conformance. Conformance: P, implemented features: DEPONOFF spec_location: [MatterTest] 06-11 11:24:57.185 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 82 (0x52) RefrigeratorAndTemperatureControlledCabinetMode, Attribute:2 (0x02) problem: Attribute 0x02 is included, but is disallowed by conformance. Conformance: P, implemented features: DEPONOFF spec_location: [MatterTest] 06-11 11:24:57.185 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 82 (0x52) RefrigeratorAndTemperatureControlledCabinetMode, Attribute:3 (0x03) problem: Attribute 0x03 is included, but is disallowed by conformance. Conformance: P, implemented features: DEPONOFF spec_location: [MatterTest] 06-11 11:24:57.186 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 89 (0x59) DishwasherMode, Attribute:2 (0x02) problem: Attribute 0x02 is included, but is disallowed by conformance. Conformance: P, implemented features: DEPONOFF spec_location: [MatterTest] 06-11 11:24:57.186 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 89 (0x59) DishwasherMode, Attribute:3 (0x03) problem: Attribute 0x03 is included, but is disallowed by conformance. Conformance: P, implemented features: DEPONOFF spec_location: [MatterTest] 06-11 11:24:57.186 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 97 (0x61) RvcOperationalState, Command: 128 (0x80) problem: Command 0x80 is included, but disallowed by conformance. Conformance: P, O, implemented features: spec_location: [MatterTest] 06-11 11:24:57.187 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 152 (0x98) DeviceEnergyManagement, Attribute:0 (0x00) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.187 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 152 (0x98) DeviceEnergyManagement, Attribute:1 (0x01) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.188 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 152 (0x98) DeviceEnergyManagement, Attribute:2 (0x02) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.188 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 152 (0x98) DeviceEnergyManagement, Attribute:3 (0x03) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.189 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 152 (0x98) DeviceEnergyManagement, Attribute:4 (0x04) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.189 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 152 (0x98) DeviceEnergyManagement, Attribute:5 (0x05) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.190 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 152 (0x98) DeviceEnergyManagement, Attribute:6 (0x06) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.190 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 152 (0x98) DeviceEnergyManagement, Attribute:7 (0x07) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.191 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 153 (0x99) EnergyEvse, Attribute:65532 (0xfffc) problem: Disallowed feature with mask 0x01 spec_location: [MatterTest] 06-11 11:24:57.191 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Attribute:1 (0x01) problem: Attribute 0x01 is included, but is disallowed by conformance. Conformance: [LF & PA_LF & ABS], implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.192 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Attribute:2 (0x02) problem: Attribute 0x02 is included, but is disallowed by conformance. Conformance: [TL & PA_TL & ABS], implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.192 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Attribute:3 (0x03) problem: Attribute 0x03 is included, but is disallowed by conformance. Conformance: [LF & PA_LF & ABS], implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.192 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Attribute:4 (0x04) problem: Attribute 0x04 is included, but is disallowed by conformance. Conformance: [TL & PA_TL & ABS], implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.193 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Attribute:16 (0x10) problem: Attribute 0x10 is included, but is disallowed by conformance. Conformance: LF & PA_LF & ABS, implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.193 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Attribute:17 (0x11) problem: Attribute 0x11 is included, but is disallowed by conformance. Conformance: LF & PA_LF & ABS, implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.194 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Attribute:18 (0x12) problem: Attribute 0x12 is included, but is disallowed by conformance. Conformance: TL & PA_TL & ABS, implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.194 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Attribute:19 (0x13) problem: Attribute 0x13 is included, but is disallowed by conformance. Conformance: TL & PA_TL & ABS, implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.195 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Command: 4 (0x04) problem: Command 0x04 is included, but disallowed by conformance. Conformance: [LF & ABS], implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.195 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 258 (0x102) WindowCovering, Command: 7 (0x07) problem: Command 0x07 is included, but disallowed by conformance. Conformance: [TL & ABS], implemented features: LF,TL,PA_LF,PA_TL spec_location: [MatterTest] 06-11 11:24:57.195 INFO Problem: ProblemSeverity.WARNING test_name: IDM-10.2 location: Endpoint: 1, Cluster: 259 (0x103) BarrierControl problem: Standard cluster found on device, but is not present in spec data spec_location: [MatterTest] 06-11 11:24:57.196 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:72 (0x48) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.196 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:73 (0x49) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.197 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:74 (0x4a) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.197 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:75 (0x4b) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.198 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:77 (0x4d) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.198 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:78 (0x4e) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.199 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:79 (0x4f) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.199 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:80 (0x50) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.199 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:81 (0x51) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.200 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:82 (0x52) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.200 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:83 (0x53) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.201 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:84 (0x54) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.201 INFO Problem: ProblemSeverity.ERROR test_name: IDM-10.2 location: Endpoint: 1, Cluster: 513 (0x201) Thermostat, Attribute:85 (0x55) problem: Standard attribute found on device, but not in spec spec_location: [MatterTest] 06-11 11:24:57.201 INFO Problem: ProblemSeverity.WARNING test_name: IDM-10.2 location: Endpoint: 1, Cluster: 2820 (0xb04) ElectricalMeasurement problem: Standard cluster found on device, but is not present in spec data spec_location: [MatterTest] 06-11 11:24:57.202 INFO ########################################################### [MatterTest] 06-11 11:24:57.203 INFO Summary for test class TC_DeviceConformance: Error 0, Executed 1, Failed 1, Passed 0, Requested 1, Skipped 0 [MatterTest] 06-11 11:24:57.205 INFO Summary for test run MatterTest@06-11-2024_11-24-49-401: Total time elapsed 7.801435647998005s Artifacts are saved in "/tmp/matter_testing/logs/MatterTest/06-11-2024_11-24-49-401" Test summary saved in "/tmp/matter_testing/logs/MatterTest/06-11-2024_11-24-49-401/test_summary.yaml" Test results: Error 0, Executed 1, Failed 1, Passed 0, Requested 1, Skipped 0 INFO:root:Finished test set, ran for 7802468.0ms DEBUG:chip.native.CTL:Shutting down the commissioner DEBUG:chip.native.IN:SecureSession[0xffff78016af0]: MarkForEviction Type:1 LSID:28613 INFO:chip.native.SC:SecureSession[0xffff78016af0, LSID:28613]: State change 'kActive' --> 'kPendingEviction' DEBUG:chip.native.IN:SecureSession[0xffff78016af0]: Released - Type:1 LSID:28613 DEBUG:chip.native.CTL:Shutting down the controller DEBUG:chip.native.IN:Expiring all sessions for fabric 0x1!! INFO:chip.native.FP:Forgetting fabric 0x1 INFO:chip.native.TS:Pending Last Known Good Time: 2023-10-14T01:16:48 DEBUG:chip.native.CTL:StorageAdapter::GetKeyValue: Key = g/lkgt, Value = 0xffff8644d820 (18) DEBUG:chip.native.CTL:Key Found 8 INFO:chip.native.TS:Previous Last Known Good Time: 2023-10-14T01:16:48 INFO:chip.native.TS:Reverted Last Known Good Time to previous value ERROR:chip.native.CTL:Shutting down the stack... DEBUG:chip.native.CTL:Shutting down the System State, this will teardown the CHIP Stack DEBUG:chip.native.IN:SecureSession[0xaaaaf6bf7d40]: Released - Type:2 LSID:28612 DEBUG:chip.native.DMG:All ReadHandler-s are clean, clear GlobalDirtySet INFO:chip.native.FP:Shutting down FabricTable INFO:chip.native.TS:Pending Last Known Good Time: 2023-10-14T01:16:48 DEBUG:chip.native.CTL:StorageAdapter::GetKeyValue: Key = g/lkgt, Value = 0xfffff8f30500 (18) DEBUG:chip.native.CTL:Key Found 8 INFO:chip.native.TS:Previous Last Known Good Time: 2023-10-14T01:16:48 INFO:chip.native.TS:Reverted Last Known Good Time to previous value INFO:chip.native.DL:writing settings to file (/tmp/chip_counters.ini-0Z29GY) INFO:chip.native.DL:renamed tmp file to file (/tmp/chip_counters.ini) INFO:chip.native.DL:NVS set: chip-counters/total-operational-hours = 0 (0x0) ERROR:chip.native.DL:Inet Layer shutdown ERROR:chip.native.DL:BLE shutdown ERROR:chip.native.DL:System Layer shutdown ERROR:root:Final result: FAIL ! root@ubuntu:~/python_testing/scripts/sdk#