INFO | 2025-10-07 11:10:26.158 | app.log:__configure_notifier_handler:111 | Exception notifier not enabled INFO | 2025-10-07 11:10:26.689 | __main__:main:50 | Initializing service INFO | 2025-10-07 11:10:26.689 | tenacity.before:log_it:27 | Starting call to '__main__.init', this is the 1st time calling it. INFO | 2025-10-07 11:10:26.705 | __main__:main:52 | Service finished initializing INFO | 2025-10-07 11:10:29.401 | app.log:__configure_notifier_handler:111 | Exception notifier not enabled FAILED: Can't locate revision identified by '0a251edfd975' INFO | 2025-10-07 11:10:32.039 | app.log:__configure_notifier_handler:111 | Exception notifier not enabled INFO | 2025-10-07 11:10:34.170 | __main__:main:31 | Creating initial data INFO | 2025-10-07 11:10:34.170 | __main__:main:33 | Initial data created Running prestart script: ./test_collections/matter/prestart.sh Current version of test yaml are up to date with SDK: 0c90d7299f7ab70b325e8a7febd0f210ae629ce4 Processing ./sdk_checkout/sdk_runner/matter_chip_tool_adapter-0.0.1-py3-none-any.whl Processing ./sdk_checkout/sdk_runner/matter_idl-0.0.1-py3-none-any.whl Processing ./sdk_checkout/sdk_runner/matter_placeholder_adapter-0.0.1-py3-none-any.whl Processing ./sdk_checkout/sdk_runner/matter_yamltests-0.0.1-py3-none-any.whl Collecting lark Downloading lark-1.3.0-py3-none-any.whl (113 kB) ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 113.0/113.0 KB 559.4 kB/s eta 0:00:00 Collecting jinja2 Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 134.9/134.9 KB 1.5 MB/s eta 0:00:00 Collecting websockets Downloading websockets-15.0.1-cp310-cp310-manylinux_2_17_aarch64.manylinux2014_aarch64.whl (182 kB) ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 182.3/182.3 KB 908.9 kB/s eta 0:00:00 Collecting diskcache Downloading diskcache-5.6.3-py3-none-any.whl (45 kB) ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.5/45.5 KB 1.2 MB/s eta 0:00:00 Collecting MarkupSafe>=2.0 Downloading markupsafe-3.0.3-cp310-cp310-manylinux2014_aarch64.manylinux_2_17_aarch64.manylinux_2_28_aarch64.whl (22 kB) Installing collected packages: matter-placeholder-adapter, matter-chip-tool-adapter, websockets, MarkupSafe, lark, diskcache, matter-yamltests, jinja2, matter-idl Attempting uninstall: websockets Found existing installation: websockets 11.0.3 Uninstalling websockets-11.0.3: Successfully uninstalled websockets-11.0.3 Attempting uninstall: MarkupSafe Found existing installation: MarkupSafe 2.1.3 Uninstalling MarkupSafe-2.1.3: Successfully uninstalled MarkupSafe-2.1.3 Attempting uninstall: lark Found existing installation: lark 1.2.2 Uninstalling lark-1.2.2: Successfully uninstalled lark-1.2.2 Attempting uninstall: jinja2 Found existing installation: Jinja2 3.1.2 Uninstalling Jinja2-3.1.2: Successfully uninstalled Jinja2-3.1.2 Successfully installed MarkupSafe-3.0.3 diskcache-5.6.3 jinja2-3.1.6 lark-1.3.0 matter-chip-tool-adapter-0.0.1 matter-idl-0.0.1 matter-placeholder-adapter-0.0.1 matter-yamltests-0.0.1 websockets-15.0.1 Prestart Complete INFO | 2025-10-07 11:10:42.852 | app.log:__configure_notifier_handler:111 | Exception notifier not enabled INFO | 2025-10-07 11:10:45.553 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_commands_reported_are_executable]. INFO | 2025-10-07 11:10:45.554 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_endpoints_have_valid_composition]. INFO | 2025-10-07 11:10:45.555 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_event_strings_valid]. INFO | 2025-10-07 11:10:45.555 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_schema_mandatory_elements_present]. INFO | 2025-10-07 11:10:45.555 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_schema_scalars]. INFO | 2025-10-07 11:10:45.556 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_dump_all_pics_for_all_endpoints]. INFO | 2025-10-07 11:10:45.571 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_active_mode_test_event_trigger]. INFO | 2025-10-07 11:10:45.589 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_zeros_key]. INFO | 2025-10-07 11:10:45.591 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_correct_key_invalid_code]. INFO | 2025-10-07 11:10:45.592 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_correct_key_valid_code]. INFO | 2025-10-07 11:10:45.593 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_incorrect_key]. INFO | 2025-10-07 11:10:45.593 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_multiple_command_request_feature_present_if_needed]. INFO | 2025-10-07 11:10:45.642 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_commands_reported_are_executable]. INFO | 2025-10-07 11:10:45.643 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_endpoints_have_valid_composition]. INFO | 2025-10-07 11:10:45.644 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_event_strings_valid]. INFO | 2025-10-07 11:10:45.646 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_schema_mandatory_elements_present]. INFO | 2025-10-07 11:10:45.647 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_schema_scalars]. INFO | 2025-10-07 11:10:45.647 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_dump_all_pics_for_all_endpoints]. INFO | 2025-10-07 11:10:45.661 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_active_mode_test_event_trigger]. INFO | 2025-10-07 11:10:45.677 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_all_zeros_key]. INFO | 2025-10-07 11:10:45.679 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_correct_key_invalid_code]. INFO | 2025-10-07 11:10:45.680 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_correct_key_valid_code]. INFO | 2025-10-07 11:10:45.681 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_incorrect_key]. INFO | 2025-10-07 11:10:45.681 | test_collections.matter.sdk_tests.support.python_testing.models.python_test_parser:parse_python_script:75 | Failed to parse test name [test_multiple_command_request_feature_present_if_needed]. INFO | 2025-10-07 11:10:45.704 | app.container_manager.container_manager:get_container:57 | Did not find container by id or name: th-sdk. INFO | 2025-10-07 11:10:49.488 | app.container_manager.container_manager:create_container:44 | Container running for connectedhomeip/chip-cert-bins:0c90d7299f7ab70b325e8a7febd0f210ae629ce4 INFO | 2025-10-07 11:10:49.489 | test_collections.matter.sdk_tests.support.sdk_container:start:179 | th-sdk container started with configuration: {'privileged': True, 'detach': True, 'network': 'host', 'name': 'th-sdk', 'command': 'tail -f /dev/null', 'volumes': {'/var/run/dbus/system_bus_socket': {'bind': '/var/run/dbus/system_bus_socket', 'mode': 'rw'}, PosixPath('/var/tmp'): {'bind': '/logs', 'mode': 'rw'}, PosixPath('/var/paa-root-certs'): {'bind': '/paa-root-certs', 'mode': 'ro'}, PosixPath('/var/credentials/development'): {'bind': '/credentials/development', 'mode': 'ro'}, PosixPath('/home/ubuntu/certification-tool/backend/test_collections/matter/sdk_tests/sdk_checkout/python_testing'): {'bind': '/root/python_testing', 'mode': 'rw'}, 'mapped_data_model_volume': {'bind': '/root/python_testing/data_model', 'mode': 'rw'}, PosixPath('/home/ubuntu/certification-tool/backend/test_collections/matter/sdk_tests/support/python_testing/models/rpc_client/test_harness_client.py'): {'bind': '/root/python_testing/scripts/sdk/test_harness_client.py', 'mode': 'rw'}}} ########################################################################### ############################### REPORT ################################ ########################################################################### >>>>>>>> Output JSON file: /app/test_collections/matter/sdk_tests/custom_python_tests_info.json >>>>>>>> Total of test functions: 0 >>>>>>>> Total of invalid test functions (don't start with 'test_TC_'): 0 >>>>>>>> Total of scripts with error: 0 ########################################################################### INFO | 2025-10-07 11:10:49.950 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/clusters-extensions.xml... INFO | 2025-10-07 11:10:49.954 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/application-basic-cluster.xml... INFO | 2025-10-07 11:10:49.960 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/bridged-device-basic-information.xml... INFO | 2025-10-07 11:10:49.974 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/thread-border-router-management-cluster.xml... INFO | 2025-10-07 11:10:49.989 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/ballast-configuration-cluster.xml... INFO | 2025-10-07 11:10:49.997 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/channel-cluster.xml... INFO | 2025-10-07 11:10:50.015 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/fan-control-cluster.xml... INFO | 2025-10-07 11:10:50.027 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/valve-configuration-and-control-cluster.xml... INFO | 2025-10-07 11:10:50.036 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/commissioner-control-cluster.xml... INFO | 2025-10-07 11:10:50.042 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/thread-network-directory-cluster.xml... INFO | 2025-10-07 11:10:50.050 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/door-lock-cluster.xml... INFO | 2025-10-07 11:10:50.054 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.056 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.057 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.058 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.060 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.061 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.062 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.063 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.064 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.065 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.066 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.067 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.067 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.069 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.070 | matter_idl.zapxml.handlers.handlers:GetNextProcessor:490 | Ignoring featurebit tag for global attribute 0xFFFC (65532) INFO | 2025-10-07 11:10:50.159 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/network-commissioning-cluster.xml... INFO | 2025-10-07 11:10:50.192 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/power-topology-cluster.xml... INFO | 2025-10-07 11:10:50.199 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/dishwasher-mode-cluster.xml... INFO | 2025-10-07 11:10:50.217 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/media-playback-cluster.xml... INFO | 2025-10-07 11:10:50.238 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/operational-state-oven-cluster.xml... INFO | 2025-10-07 11:10:50.250 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/thermostat-cluster.xml... INFO | 2025-10-07 11:10:50.298 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/water-heater-mode-cluster.xml... INFO | 2025-10-07 11:10:50.306 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/washer-controls-cluster.xml... INFO | 2025-10-07 11:10:50.311 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/laundry-washer-mode-cluster.xml... INFO | 2025-10-07 11:10:50.320 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/onoff-cluster.xml... INFO | 2025-10-07 11:10:50.332 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/descriptor-cluster.xml... INFO | 2025-10-07 11:10:50.347 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/proxy-configuration-cluster.xml... INFO | 2025-10-07 11:10:50.354 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/content-control-cluster.xml... INFO | 2025-10-07 11:10:50.373 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/temperature-measurement-cluster.xml... INFO | 2025-10-07 11:10:50.377 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/matter-devices.xml... INFO | 2025-10-07 11:10:50.595 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/boolean-state-cluster.xml... INFO | 2025-10-07 11:10:50.598 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/fixed-label-cluster.xml... INFO | 2025-10-07 11:10:50.602 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/global-enums.xml... INFO | 2025-10-07 11:10:50.606 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/concentration-measurement-cluster.xml... INFO | 2025-10-07 11:10:50.659 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/actions-cluster.xml... INFO | 2025-10-07 11:10:50.676 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/occupancy-sensing-cluster.xml... INFO | 2025-10-07 11:10:50.690 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/oven-mode-cluster.xml... INFO | 2025-10-07 11:10:50.697 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/global-bitmaps.xml... INFO | 2025-10-07 11:10:50.700 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/application-launcher-cluster.xml... INFO | 2025-10-07 11:10:50.707 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/binding-cluster.xml... INFO | 2025-10-07 11:10:50.712 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/wake-on-lan-cluster.xml... INFO | 2025-10-07 11:10:50.715 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/wifi-network-management-cluster.xml... INFO | 2025-10-07 11:10:50.720 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/device-energy-management-cluster.xml... INFO | 2025-10-07 11:10:50.746 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/timer-cluster.xml... INFO | 2025-10-07 11:10:50.752 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/target-navigator-cluster.xml... INFO | 2025-10-07 11:10:50.758 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/semantic-tag-namespace-enums.xml... INFO | 2025-10-07 11:10:50.785 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/access-control-definitions.xml... INFO | 2025-10-07 11:10:50.789 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/resource-monitoring-cluster.xml... INFO | 2025-10-07 11:10:50.799 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/ecosystem-information-cluster.xml... INFO | 2025-10-07 11:10:50.806 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/icd-management-cluster.xml... INFO | 2025-10-07 11:10:50.821 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/messages-cluster.xml... INFO | 2025-10-07 11:10:50.836 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/wifi-network-diagnostics-cluster.xml... INFO | 2025-10-07 11:10:50.846 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/media-input-cluster.xml... INFO | 2025-10-07 11:10:50.854 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/keypad-input-cluster.xml... INFO | 2025-10-07 11:10:50.869 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/mode-base-cluster.xml... INFO | 2025-10-07 11:10:50.876 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/localization-configuration-cluster.xml... INFO | 2025-10-07 11:10:50.881 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/account-login-cluster.xml... INFO | 2025-10-07 11:10:50.889 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/refrigerator-alarm.xml... INFO | 2025-10-07 11:10:50.894 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/sample-mei-cluster.xml... INFO | 2025-10-07 11:10:50.900 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/audio-output-cluster.xml... INFO | 2025-10-07 11:10:50.906 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/group-key-mgmt-cluster.xml... INFO | 2025-10-07 11:10:50.917 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/proxy-discovery-cluster.xml... INFO | 2025-10-07 11:10:50.924 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/temperature-control-cluster.xml... INFO | 2025-10-07 11:10:50.931 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/device-energy-management-mode-cluster.xml... INFO | 2025-10-07 11:10:50.936 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/pwm-cluster.xml... INFO | 2025-10-07 11:10:50.938 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/air-quality-cluster.xml... INFO | 2025-10-07 11:10:50.943 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/global-structs.xml... INFO | 2025-10-07 11:10:50.946 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/refrigerator-and-temperature-controlled-cabinet-mode-cluster.xml... INFO | 2025-10-07 11:10:50.954 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/power-source-cluster.xml... INFO | 2025-10-07 11:10:50.980 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/content-app-observer-cluster.xml... INFO | 2025-10-07 11:10:50.985 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/mode-select-cluster.xml... INFO | 2025-10-07 11:10:50.990 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/test-cluster.xml... INFO | 2025-10-07 11:10:51.038 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/measurement-and-sensing.xml... INFO | 2025-10-07 11:10:51.044 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/rvc-run-mode-cluster.xml... INFO | 2025-10-07 11:10:51.050 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/diagnostic-logs-cluster.xml... INFO | 2025-10-07 11:10:51.057 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/dishwasher-alarm-cluster.xml... INFO | 2025-10-07 11:10:51.063 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/groups-cluster.xml... INFO | 2025-10-07 11:10:51.074 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/electrical-energy-measurement-cluster.xml... INFO | 2025-10-07 11:10:51.083 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/window-covering.xml... INFO | 2025-10-07 11:10:51.104 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/switch-cluster.xml... INFO | 2025-10-07 11:10:51.115 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/power-source-configuration-cluster.xml... INFO | 2025-10-07 11:10:51.118 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/administrator-commissioning-cluster.xml... INFO | 2025-10-07 11:10:51.126 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/laundry-dryer-controls-cluster.xml... INFO | 2025-10-07 11:10:51.130 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/operational-credentials-cluster.xml... INFO | 2025-10-07 11:10:51.146 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/level-control-cluster.xml... INFO | 2025-10-07 11:10:51.162 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/operational-state-cluster.xml... INFO | 2025-10-07 11:10:51.175 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/pump-configuration-and-control-cluster.xml... INFO | 2025-10-07 11:10:51.199 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/energy-preference-cluster.xml... INFO | 2025-10-07 11:10:51.206 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/general-commissioning-cluster.xml... INFO | 2025-10-07 11:10:51.220 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/flow-measurement-cluster.xml... INFO | 2025-10-07 11:10:51.225 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/water-heater-management-cluster.xml... INFO | 2025-10-07 11:10:51.235 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/low-power-cluster.xml... INFO | 2025-10-07 11:10:51.239 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/drlc-cluster.xml... INFO | 2025-10-07 11:10:51.266 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/proxy-valid-cluster.xml... INFO | 2025-10-07 11:10:51.268 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/identify-cluster.xml... INFO | 2025-10-07 11:10:51.276 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/microwave-oven-control-cluster.xml... INFO | 2025-10-07 11:10:51.285 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/electrical-power-measurement-cluster.xml... INFO | 2025-10-07 11:10:51.299 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/global-attributes.xml... INFO | 2025-10-07 11:10:51.302 | matter_idl.zapxml.handlers.context:AddGlobalAttribute:120 | Adding global attribute 0xFFFD (65533): ClusterRevision INFO | 2025-10-07 11:10:51.304 | matter_idl.zapxml.handlers.context:AddGlobalAttribute:120 | Adding global attribute 0xFFFC (65532): FeatureMap INFO | 2025-10-07 11:10:51.305 | matter_idl.zapxml.handlers.context:AddGlobalAttribute:120 | Adding global attribute 0xFFFB (65531): AttributeList INFO | 2025-10-07 11:10:51.305 | matter_idl.zapxml.handlers.context:AddGlobalAttribute:120 | Adding global attribute 0xFFFA (65530): EventList INFO | 2025-10-07 11:10:51.306 | matter_idl.zapxml.handlers.context:AddGlobalAttribute:120 | Adding global attribute 0xFFF9 (65529): AcceptedCommandList INFO | 2025-10-07 11:10:51.307 | matter_idl.zapxml.handlers.context:AddGlobalAttribute:120 | Adding global attribute 0xFFF8 (65528): GeneratedCommandList INFO | 2025-10-07 11:10:51.309 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/access-control-cluster.xml... INFO | 2025-10-07 11:10:51.331 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/color-control-cluster.xml... INFO | 2025-10-07 11:10:51.385 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/ethernet-network-diagnostics-cluster.xml... INFO | 2025-10-07 11:10:51.394 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/software-diagnostics-cluster.xml... INFO | 2025-10-07 11:10:51.405 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/boolean-state-configuration-cluster.xml... INFO | 2025-10-07 11:10:51.419 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/service-area-cluster.xml... INFO | 2025-10-07 11:10:51.432 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/pressure-measurement-cluster.xml... INFO | 2025-10-07 11:10:51.439 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/energy-evse-mode-cluster.xml... INFO | 2025-10-07 11:10:51.447 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/operational-state-rvc-cluster.xml... INFO | 2025-10-07 11:10:51.458 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/general-diagnostics-cluster.xml... INFO | 2025-10-07 11:10:51.472 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/webrtc-provider-cluster.xml... INFO | 2025-10-07 11:10:51.485 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/time-synchronization-cluster.xml... INFO | 2025-10-07 11:10:51.502 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/rvc-clean-mode-cluster.xml... INFO | 2025-10-07 11:10:51.511 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/chime-cluster.xml... INFO | 2025-10-07 11:10:51.520 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/chip-ota.xml... INFO | 2025-10-07 11:10:51.539 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/fault-injection-cluster.xml... INFO | 2025-10-07 11:10:51.544 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/chip-types.xml... INFO | 2025-10-07 11:10:51.557 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/time-format-localization-cluster.xml... INFO | 2025-10-07 11:10:51.567 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/thermostat-user-interface-configuration-cluster.xml... INFO | 2025-10-07 11:10:51.579 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/smoke-co-alarm-cluster.xml... INFO | 2025-10-07 11:10:51.606 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/scene.xml... WARNING | 2025-10-07 11:10:51.610 | matter_idl.zapxml.handlers.context:MarkTagNotHandled:134 | TAG configurator::struct::item::optionalConform was not handled/recognized at /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/scene.xml:36:35 INFO | 2025-10-07 11:10:51.635 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/content-launch-cluster.xml... INFO | 2025-10-07 11:10:51.658 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/thread-network-diagnostics-cluster.xml... INFO | 2025-10-07 11:10:51.688 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/basic-information-cluster.xml... INFO | 2025-10-07 11:10:51.714 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/microwave-oven-mode-cluster.xml... INFO | 2025-10-07 11:10:51.725 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/illuminance-measurement-cluster.xml... INFO | 2025-10-07 11:10:51.730 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/unit-localization-cluster.xml... INFO | 2025-10-07 11:10:51.735 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/user-label-cluster.xml... INFO | 2025-10-07 11:10:51.738 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/energy-evse-cluster.xml... INFO | 2025-10-07 11:10:51.756 | matter_idl.zapxml:ParseXmls:115 | Parsing /app/test_collections/matter/sdk_tests/sdk_checkout/sdk_runner/specifications/chip/relative-humidity-measurement-cluster.xml... INFO | 2025-10-07 11:10:51.758 | matter_idl.zapxml:ParseXmls:115 | Parsing CommissionerCommands... INFO | 2025-10-07 11:10:51.761 | matter_idl.zapxml:ParseXmls:115 | Parsing DelayCommands... INFO | 2025-10-07 11:10:51.763 | matter_idl.zapxml:ParseXmls:115 | Parsing DiscoveryCommands... INFO | 2025-10-07 11:10:51.767 | matter_idl.zapxml:ParseXmls:115 | Parsing EqualityCommands... INFO | 2025-10-07 11:10:51.769 | matter_idl.zapxml:ParseXmls:115 | Parsing LogCommands... INFO | 2025-10-07 11:10:51.770 | matter_idl.zapxml:ParseXmls:115 | Parsing SystemCommands... ERROR | 2025-10-07 11:10:51.779 | matter_idl.zapxml.handlers.handlers:FinalizeProcessing:283 | Enum StreamTypeEnum could not find its clusters (codes: {1364}) ERROR | 2025-10-07 11:10:51.780 | matter_idl.zapxml.handlers.handlers:FinalizeProcessing:330 | Bitmap WebRTCMetadataOptions could not find its cluster (code 1364/0x554) ERROR | 2025-10-07 11:10:51.780 | matter_idl.zapxml.handlers.handlers:FinalizeProcessing:283 | Enum WebRTCEndReasonEnum could not find its clusters (codes: {1364}) ERROR | 2025-10-07 11:10:51.781 | matter_idl.zapxml.handlers.handlers:FinalizeProcessing:239 | Struct ICEServerStruct could not find cluster (code 1364/0x554) ERROR | 2025-10-07 11:10:51.781 | matter_idl.zapxml.handlers.handlers:FinalizeProcessing:239 | Struct WebRTCSessionStruct could not find cluster (code 1364/0x554) INFO | 2025-10-07 11:11:05.555 | alembic.runtime.migration:__init__:213 | Context impl PostgresqlImpl. INFO | 2025-10-07 11:11:05.555 | alembic.runtime.migration:__init__:216 | Will assume transactional DDL. INFO | 2025-10-07 11:11:05.564 | app.version:read_test_harness_backend_version:44 | Test Engine version is v2.12+spring2025 INFO | 2025-10-07 11:11:05.565 | app.version:read_test_harness_backend_version:45 | Test Engine SHA is 2154a06 INFO | 2025-10-07 11:11:05.565 | app.version:read_test_harness_backend_version:46 | Test Engine SDK SHA is 0c90d72 INFO | 2025-10-07 11:11:06.350 | uvicorn.server:serve:84 | Started server process [1] INFO | 2025-10-07 11:11:06.350 | uvicorn.lifespan.on:startup:45 | Waiting for application startup. INFO | 2025-10-07 11:11:06.351 | uvicorn.lifespan.on:startup:59 | Application startup complete. INFO | 2025-10-07 11:11:06.352 | uvicorn.server:_log_started_message:217 | Uvicorn running on http://0.0.0.0:80 (Press CTRL+C to quit) INFO | 2025-10-07 11:11:27.622 | uvicorn.protocols.http.httptools_impl:send:440 | 127.0.0.1:38624 - "GET /docs HTTP/1.1" 200 INFO | 2025-10-07 11:11:33.161 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44798 - "GET /api/v1/projects/default_config HTTP/1.1" 200 INFO | 2025-10-07 11:11:33.165 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44804 - "GET /api/v1/version HTTP/1.1" 200 INFO | 2025-10-07 11:11:33.175 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44820 - "GET /api/v1/test_collections HTTP/1.1" 307 INFO | 2025-10-07 11:11:33.235 | uvicorn.protocols.websockets.websockets_impl:asgi_send:231 | ('172.19.0.3', 44824) - "WebSocket /api/v1/ws" [accepted] INFO | 2025-10-07 11:11:33.241 | app.socket_connection_manager:connect:52 | Websocket connected: "". INFO | 2025-10-07 11:11:33.246 | websockets.legacy.server:handshake:643 | connection open INFO | 2025-10-07 11:11:33.258 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44804 - "GET /api/v1/operators/ HTTP/1.1" 200 INFO | 2025-10-07 11:11:33.259 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44822 - "GET /api/v1/projects/?archived=true&skip=0&limit=250 HTTP/1.1" 200 INFO | 2025-10-07 11:11:33.261 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44798 - "GET /api/v1/projects/?archived=false&skip=0&limit=250 HTTP/1.1" 200 INFO | 2025-10-07 11:11:33.262 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44820 - "GET /api/v1/test_run_executions/status HTTP/1.1" 200 INFO | 2025-10-07 11:11:33.316 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44838 - "GET /api/v1/test_collections/ HTTP/1.1" 200 INFO | 2025-10-07 11:11:40.873 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44486 - "GET /api/v1/test_run_executions/?archived=true&project_id=5&skip=0&limit=250 HTTP/1.1" 200 INFO | 2025-10-07 11:11:40.891 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44472 - "GET /api/v1/test_run_executions/?archived=false&project_id=5&skip=0&limit=250 HTTP/1.1" 200 INFO | 2025-10-07 11:11:46.078 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44472 - "POST /api/v1/test_run_executions/25/repeat HTTP/1.1" 200 INFO | 2025-10-07 11:11:46.101 | app.test_engine.test_runner:load_test_run:89 | Loading Test Run: TC-BINFO-2_1_2025_10_07_11_11_45 INFO | 2025-10-07 11:11:46.101 | app.test_engine.test_runner:load_test_run:92 | Loading Test Run: TC-BINFO-2_1_2025_10_07_11_11_45 INFO | 2025-10-07 11:11:46.124 | app.test_engine.test_runner:load_test_run:101 | Test Runner is Ready INFO | 2025-10-07 11:11:46.144 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:44472 - "POST /api/v1/test_run_executions/26/start HTTP/1.1" 200 INFO | 2025-10-07 11:11:46.151 | app.test_engine.test_runner:run:160 | Run Test Runner is Ready INFO | 2025-10-07 11:11:46.151 | app.test_engine.test_runner:run:161 | TH Version: v2.12+spring2025 INFO | 2025-10-07 11:11:46.151 | app.test_engine.test_runner:run:162 | TH SHA: 2154a06 INFO | 2025-10-07 11:11:46.152 | app.test_engine.test_runner:run:163 | TH SDK SHA: 0c90d72 INFO | 2025-10-07 11:11:46.158 | app.test_engine.test_runner:run:164 | Project config: {'test_parameters': {'paa-trust-store-path': '/paa-root-certs', 'endpoint': 0, 'thread-dataset-hex': '0e080000000000010000000300001235060004001fffe00208831b059bf104bb290708fdc236ce16c9a66c0510d5ef4a006abafeaeeceeb9bc37b5e0bb030f4f70656e5468726561642d3862613901028ba90410dfce17fd02fa4a24cdde9aad02fc21d40c0402a0f7f8', 'timeout': 1000000, 'payload': 'MT:8IXS142C00KA0648G00', 'bool-arg': 'use_pase_only:false'}, 'network': {'wifi': {'ssid': 'testharness', 'password': 'wifi-password'}, 'thread': {'rcp_serial_path': '/dev/ttyACM0', 'rcp_baudrate': 115200, 'on_mesh_prefix': 'fd11:22::/64', 'network_interface': 'eth0', 'dataset': {'channel': '15', 'panid': '0x1234', 'extpanid': '1111111122222222', 'networkkey': '00112233445566778899aabbccddeeff', 'networkname': 'DEMO'}, 'otbr_docker_image': None}}, 'dut_config': {'discriminator': '3840', 'setup_code': '20202021', 'pairing_mode': 'ble-thread', 'chip_timeout': None, 'chip_use_paa_certs': False, 'trace_log': True, 'enhanced_setup_flow': None}} INFO | 2025-10-07 11:11:46.158 | app.test_engine.test_runner:run:165 | Project PICS: clusters={'Basic Information Cluster Test Plan': PICSCluster(name='Basic Information Cluster Test Plan', items={'BINFO.S': PICSItem(number='BINFO.S', enabled=True), 'BINFO.S.A0000': PICSItem(number='BINFO.S.A0000', enabled=True), 'BINFO.S.A0001': PICSItem(number='BINFO.S.A0001', enabled=True), 'BINFO.S.A0002': PICSItem(number='BINFO.S.A0002', enabled=True), 'BINFO.S.A0003': PICSItem(number='BINFO.S.A0003', enabled=True), 'BINFO.S.A0004': PICSItem(number='BINFO.S.A0004', enabled=True), 'BINFO.S.A0005': PICSItem(number='BINFO.S.A0005', enabled=True), 'BINFO.S.A0006': PICSItem(number='BINFO.S.A0006', enabled=True), 'BINFO.S.A0007': PICSItem(number='BINFO.S.A0007', enabled=True), 'BINFO.S.A0008': PICSItem(number='BINFO.S.A0008', enabled=True), 'BINFO.S.A0009': PICSItem(number='BINFO.S.A0009', enabled=True), 'BINFO.S.A000a': PICSItem(number='BINFO.S.A000a', enabled=True), 'BINFO.S.A000b': PICSItem(number='BINFO.S.A000b', enabled=False), 'BINFO.S.A000c': PICSItem(number='BINFO.S.A000c', enabled=False), 'BINFO.S.A000d': PICSItem(number='BINFO.S.A000d', enabled=False), 'BINFO.S.A000e': PICSItem(number='BINFO.S.A000e', enabled=False), 'BINFO.S.A000f': PICSItem(number='BINFO.S.A000f', enabled=True), 'BINFO.S.A0010': PICSItem(number='BINFO.S.A0010', enabled=False), 'BINFO.S.A0011': PICSItem(number='BINFO.S.A0011', enabled=False), 'BINFO.S.A0012': PICSItem(number='BINFO.S.A0012', enabled=True), 'BINFO.S.A0013': PICSItem(number='BINFO.S.A0013', enabled=True), 'BINFO.S.A0014': PICSItem(number='BINFO.S.A0014', enabled=False), 'BINFO.S.A0015': PICSItem(number='BINFO.S.A0015', enabled=True), 'BINFO.S.A0016': PICSItem(number='BINFO.S.A0016', enabled=True), 'BINFO.S.A0017': PICSItem(number='BINFO.S.A0017', enabled=False), 'BINFO.S.E00': PICSItem(number='BINFO.S.E00', enabled=True), 'BINFO.S.E01': PICSItem(number='BINFO.S.E01', enabled=False), 'BINFO.S.E02': PICSItem(number='BINFO.S.E02', enabled=False), 'BINFO.S.E03': PICSItem(number='BINFO.S.E03', enabled=False), 'BINFO.C.A0005': PICSItem(number='BINFO.C.A0005', enabled=False)})} INFO | 2025-10-07 11:11:46.164 | app.test_engine.models.test_run:mark_as_executing:104 | Test Run Executing INFO | 2025-10-07 11:11:46.169 | app.test_engine.models.test_suite:__print_log_separator:142 | ################################################################################ INFO | 2025-10-07 11:11:46.170 | app.test_engine.models.test_suite:mark_as_executing:134 | Test Suite Executing: FirstChipToolSuite INFO | 2025-10-07 11:11:46.170 | test_collections.matter.sdk_tests.support.yaml_tests.models.test_suite:setup:53 | YAML Version: 0c90d7299f7ab70b325e8a7febd0f210ae629ce4 INFO | 2025-10-07 11:11:46.171 | test_collections.matter.sdk_tests.support.yaml_tests.models.chip_suite:setup:71 | Setting up SDK container INFO | 2025-10-07 11:11:46.174 | app.container_manager.container_manager:get_container:57 | Did not find container by id or name: th-sdk. INFO | 2025-10-07 11:11:46.734 | app.container_manager.container_manager:create_container:44 | Container running for connectedhomeip/chip-cert-bins:0c90d7299f7ab70b325e8a7febd0f210ae629ce4 INFO | 2025-10-07 11:11:46.735 | test_collections.matter.sdk_tests.support.sdk_container:start:179 | th-sdk container started with configuration: {'privileged': True, 'detach': True, 'network': 'host', 'name': 'th-sdk', 'command': 'tail -f /dev/null', 'volumes': {'/var/run/dbus/system_bus_socket': {'bind': '/var/run/dbus/system_bus_socket', 'mode': 'rw'}, PosixPath('/var/tmp'): {'bind': '/logs', 'mode': 'rw'}, PosixPath('/var/paa-root-certs'): {'bind': '/paa-root-certs', 'mode': 'ro'}, PosixPath('/var/credentials/development'): {'bind': '/credentials/development', 'mode': 'ro'}, PosixPath('/home/ubuntu/certification-tool/backend/test_collections/matter/sdk_tests/sdk_checkout/python_testing'): {'bind': '/root/python_testing', 'mode': 'rw'}, 'mapped_data_model_volume': {'bind': '/root/python_testing/data_model', 'mode': 'rw'}, PosixPath('/home/ubuntu/certification-tool/backend/test_collections/matter/sdk_tests/support/python_testing/models/rpc_client/test_harness_client.py'): {'bind': '/root/python_testing/scripts/sdk/test_harness_client.py', 'mode': 'rw'}}} INFO | 2025-10-07 11:11:46.735 | test_collections.matter.sdk_tests.support.yaml_tests.models.chip_suite:setup:74 | Setting up test runner INFO | 2025-10-07 11:11:46.739 | test_collections.matter.sdk_tests.support.chip.chip_server:start:113 | Starting chip server INFO | 2025-10-07 11:11:46.740 | test_collections.matter.sdk_tests.support.chip.chip_server:__reset_node_id:91 | New Node Id generated: 0xb1b7d0ce79237bcd INFO | 2025-10-07 11:11:46.740 | test_collections.matter.sdk_tests.support.sdk_container:send_command:206 | Sending command to SDK container: ./chip-tool interactive server --trace_file "/logs/trace_log_2025-10-07_11.11.46_0xb1b7d0ce79237bcd_CHIP_WEBSOCKET_SERVER.log" --trace_decode 1 CHIPTOOL | 2025-10-07 11:11:46.847 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.847] [7:7] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs CHIPTOOL | 2025-10-07 11:11:46.850 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.850] [7:7] [DL] Wrote settings to /tmp/chip_tool_kvs CHIPTOOL | 2025-10-07 11:11:46.851 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.850] [7:7] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs CHIPTOOL | 2025-10-07 11:11:46.853 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.853] [7:7] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini CHIPTOOL | 2025-10-07 11:11:46.856 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.856] [7:7] [DL] Wrote settings to /tmp/chip_factory.ini CHIPTOOL | 2025-10-07 11:11:46.857 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.856] [7:7] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini CHIPTOOL | 2025-10-07 11:11:46.859 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.859] [7:7] [DL] Wrote settings to /tmp/chip_config.ini CHIPTOOL | 2025-10-07 11:11:46.860 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.859] [7:7] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini CHIPTOOL | 2025-10-07 11:11:46.862 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.862] [7:7] [DL] Wrote settings to /tmp/chip_counters.ini CHIPTOOL | 2025-10-07 11:11:46.866 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.866] [7:7] [DL] Wrote settings to /tmp/chip_factory.ini CHIPTOOL | 2025-10-07 11:11:46.867 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.866] [7:7] [DL] NVS set: chip-factory/unique-id = "21CAD7D4E5569312" CHIPTOOL | 2025-10-07 11:11:46.869 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.869] [7:7] [DL] Wrote settings to /tmp/chip_factory.ini CHIPTOOL | 2025-10-07 11:11:46.870 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.869] [7:7] [DL] NVS set: chip-factory/vendor-id = 65521 (0xFFF1) CHIPTOOL | 2025-10-07 11:11:46.873 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.873] [7:7] [DL] Wrote settings to /tmp/chip_factory.ini CHIPTOOL | 2025-10-07 11:11:46.874 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.873] [7:7] [DL] NVS set: chip-factory/product-id = 32769 (0x8001) CHIPTOOL | 2025-10-07 11:11:46.877 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.876] [7:7] [DL] Wrote settings to /tmp/chip_counters.ini CHIPTOOL | 2025-10-07 11:11:46.877 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.876] [7:7] [DL] NVS set: chip-counters/reboot-count = 1 (0x1) CHIPTOOL | 2025-10-07 11:11:46.880 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.880] [7:7] [DL] Wrote settings to /tmp/chip_counters.ini CHIPTOOL | 2025-10-07 11:11:46.881 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.880] [7:7] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) CHIPTOOL | 2025-10-07 11:11:46.884 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.884] [7:7] [DL] Wrote settings to /tmp/chip_counters.ini CHIPTOOL | 2025-10-07 11:11:46.884 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.884] [7:7] [DL] NVS set: chip-counters/boot-reason = 0 (0x0) CHIPTOOL | 2025-10-07 11:11:46.887 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.887] [7:7] [DL] Wrote settings to /tmp/chip_config.ini CHIPTOOL | 2025-10-07 11:11:46.888 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.887] [7:7] [DL] NVS set: chip-config/regulatory-location = 0 (0x0) CHIPTOOL | 2025-10-07 11:11:46.891 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.891] [7:7] [DL] Wrote settings to /tmp/chip_config.ini CHIPTOOL | 2025-10-07 11:11:46.891 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.891] [7:7] [DL] NVS set: chip-config/location-capability = 2 (0x2) CHIPTOOL | 2025-10-07 11:11:46.892 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.891] [7:7] [DL] Got Ethernet interface: eth0 CHIPTOOL | 2025-10-07 11:11:46.893 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.892] [7:7] [DL] Found the primary Ethernet interface:eth0 CHIPTOOL | 2025-10-07 11:11:46.894 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.892] [7:7] [DL] Got WiFi interface: wlan0 CHIPTOOL | 2025-10-07 11:11:46.896 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.896] [7:7] [DL] Found the primary WiFi interface:wlan0 CHIPTOOL | 2025-10-07 11:11:46.896 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.896] [7:7] [IN] UDP::Init bind&listen port=0 CHIPTOOL | 2025-10-07 11:11:46.897 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.896] [7:7] [IN] UDP::Init bound to port=50507 CHIPTOOL | 2025-10-07 11:11:46.898 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.896] [7:7] [IN] BLEBase::Init - setting/overriding transport CHIPTOOL | 2025-10-07 11:11:46.899 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.896] [7:7] [IN] TransportMgr initialized CHIPTOOL | 2025-10-07 11:11:46.900 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.896] [7:7] [FP] Initializing FabricTable from persistent storage CHIPTOOL | 2025-10-07 11:11:46.900 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.896] [7:7] [TS] Last Known Good Time: [unknown] CHIPTOOL | 2025-10-07 11:11:46.901 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.896] [7:7] [TS] Setting Last Known Good Time to firmware build time 2023-10-14T01:16:48 CHIPTOOL | 2025-10-07 11:11:46.902 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.898] [7:7] [ZCL] Using ZAP configuration... CHIPTOOL | 2025-10-07 11:11:46.903 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [DL] Avahi client registered CHIPTOOL | 2025-10-07 11:11:46.904 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IN] CASE Server enabling CASE session setups CHIPTOOL | 2025-10-07 11:11:46.905 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IN] SecureSession[0xaaaaea180e10]: Allocated Type:2 LSID:64686 CHIPTOOL | 2025-10-07 11:11:46.906 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [SC] Allocated SecureSession (0xaaaaea180e10) - waiting for Sigma1 msg CHIPTOOL | 2025-10-07 11:11:46.907 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IM] WARNING ┌──────────────────────────────────────────────────── CHIPTOOL | 2025-10-07 11:11:46.908 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IM] WARNING │ Interaction Model Engine running in 'Checked' mode. CHIPTOOL | 2025-10-07 11:11:46.908 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IM] WARNING │ This executes BOTH ember and data-model code paths. CHIPTOOL | 2025-10-07 11:11:46.909 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IM] WARNING │ which is inefficient and consumes more flash space. CHIPTOOL | 2025-10-07 11:11:46.909 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IM] WARNING │ This should be done for testing only. CHIPTOOL | 2025-10-07 11:11:46.910 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IM] WARNING └──────────────────────────────────────────────────── CHIPTOOL | 2025-10-07 11:11:46.911 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [CTL] System State Initialized... CHIPTOOL | 2025-10-07 11:11:46.911 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [CTL] Setting attestation nonce to random value CHIPTOOL | 2025-10-07 11:11:46.912 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [CTL] Setting CSR nonce to random value CHIPTOOL | 2025-10-07 11:11:46.912 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IN] UDP::Init bind&listen port=5550 CHIPTOOL | 2025-10-07 11:11:46.913 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IN] UDP::Init bound to port=5550 CHIPTOOL | 2025-10-07 11:11:46.913 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.901] [7:7] [IN] TransportMgr initialized CHIPTOOL | 2025-10-07 11:11:46.917 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.902] [7:14] [DL] CHIP task running CHIPTOOL | 2025-10-07 11:11:46.918 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.902] [7:14] [DL] HandlePlatformSpecificBLEEvent 32786 CHIPTOOL | 2025-10-07 11:11:46.918 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1759835506.917] [7:7] [TOO] LWS_CALLBACK_GET_THREAD_ID CHIPTOOL | 2025-10-07 11:11:46.919 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:100 | [1759835506.917] [7:7] [TOO] LWS_CALLBACK_PROTOCOL_INIT INFO | 2025-10-07 11:11:46.919 | test_collections.matter.sdk_tests.support.yaml_tests.models.chip_suite:setup:80 | Create PICS file for DUT INFO | 2025-10-07 11:11:46.920 | test_collections.matter.sdk_tests.support.yaml_tests.matter_yaml_runner:set_pics:342 | Sending command: /bin/sh -c "echo 'BINFO.S=1 BINFO.S.A0000=1 BINFO.S.A0001=1 BINFO.S.A0002=1 BINFO.S.A0003=1 BINFO.S.A0004=1 BINFO.S.A0005=1 BINFO.S.A0006=1 BINFO.S.A0007=1 BINFO.S.A0008=1 BINFO.S.A0009=1 BINFO.S.A000a=1 BINFO.S.A000b=0 BINFO.S.A000c=0 BINFO.S.A000d=0 BINFO.S.A000e=0 BINFO.S.A000f=1 BINFO.S.A0010=0 BINFO.S.A0011=0 BINFO.S.A0012=1 BINFO.S.A0013=1 BINFO.S.A0014=0 BINFO.S.A0015=1 BINFO.S.A0016=1 BINFO.S.A0017=0 BINFO.S.E00=1 BINFO.S.E01=0 BINFO.S.E02=0 BINFO.S.E03=0 BINFO.C.A0005=0 PICS_SDK_CI_ONLY=0 PICS_SKIP_SAMPLE_APP=1 PICS_USER_PROMPT=1' > /var/tmp/pics" INFO | 2025-10-07 11:11:46.929 | test_collections.matter.sdk_tests.support.yaml_tests.models.chip_suite:setup:88 | Commission DUT INFO | 2025-10-07 11:11:46.930 | test_collections.matter.sdk_tests.support.utils:__prompt_pass_fail_options:53 | User prompt: "Make sure the DUT is in Commissioning Mode" INFO | 2025-10-07 11:11:54.638 | test_collections.matter.sdk_tests.support.utils:__prompt_pass_fail_options:58 | User chose prompt option DONE INFO | 2025-10-07 11:11:54.638 | test_collections.matter.sdk_tests.support.otbr_manager.otbr_manager:start_device:133 | Starting OTBR via docker image: nrfconnect/otbr:9185bda INFO | 2025-10-07 11:11:55.195 | app.container_manager.container_manager:create_container:44 | Container running for nrfconnect/otbr:9185bda WARNING | 2025-10-07 11:11:55.254 | retry.api:__retry_internal:40 | %s, retrying in %s seconds... INFO | 2025-10-07 11:11:58.344 | test_collections.matter.sdk_tests.support.otbr_manager.otbr_manager:start_device:150 | Border Router started: happy_pare with configuration: {'privileged': True, 'detach': True, 'network': 'host', 'environment': ['NAT64=1', 'DNS64=0', 'WEB_GUI=0'], 'volumes': {'/dev/ttyACM0': {'bind': '/dev/radio'}, PosixPath('/home/ubuntu/certification-tool/backend/otbr_manager/avahi'): {'bind': '/etc/avahi'}}, 'command': '--radio-url spinel+hdlc+uart:///dev/radio?uart-baudrate=115200 -B eth0'} ERROR | 2025-10-07 11:14:38.854 | app.test_engine.models.test_suite:record_error:138 | Test Suite Error: Error occurred during setup of test suite.FirstChipToolSuite. sent 1011 (internal error) keepalive ping timeout; no close frame received INFO | 2025-10-07 11:14:38.855 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.865 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.867 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.873 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.874 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.876 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.878 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.880 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.881 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.883 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.884 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.886 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.888 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.890 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.891 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.893 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.895 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.897 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.899 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.901 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.903 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.904 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.906 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.908 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.910 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.911 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.913 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.915 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.917 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.918 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.920 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.922 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.924 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.925 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.927 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.929 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.931 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.933 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.935 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.937 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.939 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.940 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.942 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.944 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.946 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.947 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.949 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.951 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.953 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.955 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.956 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.958 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.960 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.962 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.964 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.965 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.967 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.969 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.971 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.972 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.974 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.976 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.977 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.979 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.980 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.982 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.984 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.986 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.987 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.989 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.990 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.992 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.994 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.995 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.997 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:38.999 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:39.001 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:39.002 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:39.004 | app.test_engine.models.test_step:cancel:79 | Cancel test step INFO | 2025-10-07 11:14:39.010 | app.test_engine.models.test_case:cancel:173 | Cancel test case INFO | 2025-10-07 11:14:39.011 | app.test_engine.models.test_case:mark_as_completed:180 | Test Case Completed [CANCELLED]: TC-BINFO-2.1 (Semi-automated) INFO | 2025-10-07 11:14:39.012 | app.test_engine.models.test_case:__print_log_separator:298 | ================================================================================ INFO | 2025-10-07 11:14:39.012 | test_collections.matter.sdk_tests.support.yaml_tests.models.chip_suite:cleanup:208 | Stopping test runner INFO | 2025-10-07 11:14:39.012 | test_collections.matter.sdk_tests.support.sdk_container:send_command:206 | Sending command to SDK container: pkill -SIGTERM -f "./chip-tool interactive server --trace_file "/logs/trace_log_2025-10-07_11.11.46_0xb1b7d0ce79237bcd_CHIP_WEBSOCKET_SERVER.log" --trace_decode 1" INFO | 2025-10-07 11:15:39.115 | test_collections.matter.sdk_tests.support.chip.chip_server:stop:185 | Could not get exit code after pkill command ./chip-tool interactive server --trace_file "/logs/trace_log_2025-10-07_11.11.46_0xb1b7d0ce79237bcd_CHIP_WEBSOCKET_SERVER.log" --trace_decode 1. INFO | 2025-10-07 11:15:39.116 | test_collections.matter.sdk_tests.support.yaml_tests.models.chip_suite:cleanup:211 | Stopping SDK container INFO | 2025-10-07 11:15:59.391 | test_collections.matter.sdk_tests.support.yaml_tests.models.chip_suite:cleanup:215 | Stopping border router container INFO | 2025-10-07 11:15:59.805 | app.test_engine.models.test_suite:mark_as_completed:126 | Test Suite Completed [ERROR]: FirstChipToolSuite INFO | 2025-10-07 11:15:59.805 | app.test_engine.models.test_suite:__print_log_separator:142 | ################################################################################ INFO | 2025-10-07 11:15:59.820 | app.test_engine.models.test_run:mark_as_completed:100 | Test Run Completed [ERROR] INFO | 2025-10-07 11:21:04.355 | app.socket_connection_manager:disconnect:59 | Websocket disconnected: "". INFO | 2025-10-07 11:21:04.356 | websockets.legacy.server:handler:264 | connection closed INFO | 2025-10-07 11:46:39.681 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:45730 - "GET /api/v1/test_collections HTTP/1.1" 307 INFO | 2025-10-07 11:46:39.685 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:45700 - "GET /api/v1/projects/default_config HTTP/1.1" 200 INFO | 2025-10-07 11:46:39.686 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:45714 - "GET /api/v1/version HTTP/1.1" 200 INFO | 2025-10-07 11:46:39.691 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:45740 - "GET /api/v1/operators/ HTTP/1.1" 200 INFO | 2025-10-07 11:46:39.734 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:45714 - "GET /api/v1/projects/?archived=true&skip=0&limit=250 HTTP/1.1" 200 INFO | 2025-10-07 11:46:39.740 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:45740 - "GET /api/v1/projects/?archived=false&skip=0&limit=250 HTTP/1.1" 200 INFO | 2025-10-07 11:46:39.745 | uvicorn.protocols.websockets.websockets_impl:asgi_send:231 | ('172.19.0.3', 45754) - "WebSocket /api/v1/ws" [accepted] INFO | 2025-10-07 11:46:39.746 | app.socket_connection_manager:connect:52 | Websocket connected: "". INFO | 2025-10-07 11:46:39.747 | websockets.legacy.server:handshake:643 | connection open INFO | 2025-10-07 11:46:39.751 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:45740 - "GET /api/v1/test_run_executions/status HTTP/1.1" 200 INFO | 2025-10-07 11:46:39.860 | uvicorn.protocols.http.httptools_impl:send:440 | 172.19.0.3:45740 - "GET /api/v1/test_collections/ HTTP/1.1" 200