10/08/2025 13:59:27 | MainThread | root | INFO | (logger.configure_logging|line:127) | Operating system: Linux-6.1.0-28-amd64-x86_64-with-glibc2.39 | 10/08/2025 13:59:27 | MainThread | root | INFO | (logger.configure_logging|line:128) | Python version: 3.12.3 | 10/08/2025 13:59:27 | MainThread | SetEnvs | INFO | (ci.convert_env|line:197) | Converting envs 'TZ=US/Pacific' to dictionary | 10/08/2025 13:59:27 | MainThread | SetEnvs | INFO | (ci.__init__|line:143) | ENVIRONMENT DATA: NODE_NAME: 'X86-BUILDER-3c --Hetzner' IMAGE: 'linuxserver/kicad' BASE: 'alpine' META_TAG: '9.0.2-r1-ls87' RELEASE_TAG: 'latest' TAGS: 'amd64-9.0.2-r1-ls87|arm64v8-9.0.2-r1-ls87' S6_VERBOSITY: '2' CI_S6_VERBOSITY 'None' CI_LOG_LEVEL 'None' DOCKER_ENV: 'TZ=US/Pacific' DOCKER_VOLUMES: 'None' (Not in use) DOCKER_PRIVILEGED: 'None' (Not in use) WEB_AUTH: 'user:password' WEB_PATH: '' WEB_SCREENSHOT: 'true' WEB_SCREENSHOT_TIMEOUT: 'None' WEB_SCREENSHOT_DELAY: 'None' DOCKER_LOGS_TIMEOUT: '120' SBOM_TIMEOUT: 'None' DELAY_START: 'None' (Not in use) PORT: '3001' SSL: 'true' S3_REGION: 'None' S3_BUCKET: 'None' SYFT_IMAGE_TAG: 'v1.26.1' Docker Engine Version: '28.2.2' | 10/08/2025 13:59:27 | AMD64Thread | LSIO CI | INFO | (ci.container_test|line:313) | Starting test of: amd64-9.0.2-r1-ls87 | 10/08/2025 13:59:27 | ARM64Thread | LSIO CI | INFO | (ci.container_test|line:313) | Starting test of: arm64v8-9.0.2-r1-ls87 | 10/08/2025 13:59:39 | ARM64Thread | LSIO CI | INFO | (ci.container_test|line:319) | Container config of tag arm64v8-9.0.2-r1-ls87: ['TZ=US/Pacific', 'S6_VERBOSITY=2', 'PATH=/lsiopy/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', 'PS1=$(whoami)@$(hostname):$(pwd)\\$ ', 'HOME=/config', 'TERM=xterm', 'S6_CMD_WAIT_FOR_SERVICES_MAXTIME=0', 'S6_STAGE2_HOOK=/docker-mods', 'VIRTUAL_ENV=/lsiopy', 'DISPLAY=:1', 'PERL5LIB=/usr/local/bin', 'START_DOCKER=true', 'PULSE_RUNTIME_PATH=/defaults', 'SELKIES_INTERPOSER=/usr/lib/selkies_joystick_interposer.so', 'DISABLE_ZINK=false', 'TITLE=KiCad', 'LSIO_FIRST_PARTY=true'] | 10/08/2025 13:59:39 | ARM64Thread_1 | LSIO CI | INFO | (ci.watch_container_logs|line:651) | Tailing the arm64v8-9.0.2-r1-ls87 logs for 120 seconds looking for the 'done' message | 10/08/2025 13:59:39 | AMD64Thread | LSIO CI | INFO | (ci.container_test|line:319) | Container config of tag amd64-9.0.2-r1-ls87: ['TZ=US/Pacific', 'S6_VERBOSITY=2', 'PATH=/lsiopy/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', 'PS1=$(whoami)@$(hostname):$(pwd)\\$ ', 'HOME=/config', 'TERM=xterm', 'S6_CMD_WAIT_FOR_SERVICES_MAXTIME=0', 'S6_STAGE2_HOOK=/docker-mods', 'VIRTUAL_ENV=/lsiopy', 'DISPLAY=:1', 'PERL5LIB=/usr/local/bin', 'START_DOCKER=true', 'PULSE_RUNTIME_PATH=/defaults', 'SELKIES_INTERPOSER=/usr/lib/selkies_joystick_interposer.so', 'DISABLE_ZINK=false', 'TITLE=KiCad', 'LSIO_FIRST_PARTY=true'] | 10/08/2025 13:59:39 | AMD64Thread_1 | LSIO CI | INFO | (ci.watch_container_logs|line:651) | Tailing the amd64-9.0.2-r1-ls87 logs for 120 seconds looking for the 'done' message | 10/08/2025 13:59:40 | ARM64Thread_0 | LSIO CI | INFO | (ci.generate_sbom|line:494) | Creating SBOM package list on arm64v8-9.0.2-r1-ls87 with syft version v1.26.1 | 10/08/2025 13:59:40 | ARM64Thread_0 | LSIO CI | INFO | (ci.generate_sbom|line:497) | Tailing the syft container logs for 900 seconds looking the 'VERSION' message on tag: arm64v8-9.0.2-r1-ls87 | 10/08/2025 13:59:40 | AMD64Thread_0 | LSIO CI | INFO | (ci.generate_sbom|line:494) | Creating SBOM package list on amd64-9.0.2-r1-ls87 with syft version v1.26.1 | 10/08/2025 13:59:40 | AMD64Thread_0 | LSIO CI | INFO | (ci.generate_sbom|line:497) | Tailing the syft container logs for 900 seconds looking the 'VERSION' message on tag: amd64-9.0.2-r1-ls87 | 10/08/2025 13:59:40 | AMD64Thread_1 | LSIO CI | INFO | (ci.watch_container_logs|line:656) | Container startup completed for amd64-9.0.2-r1-ls87 | 10/08/2025 13:59:40 | AMD64Thread_1 | LSIO CI | SUCCESS | (ci.watch_container_logs|line:658) | Container startup amd64-9.0.2-r1-ls87: PASSED after 1.01 seconds | 10/08/2025 13:59:48 | ARM64Thread_1 | LSIO CI | INFO | (ci.watch_container_logs|line:656) | Container startup completed for arm64v8-9.0.2-r1-ls87 | 10/08/2025 13:59:48 | ARM64Thread_1 | LSIO CI | SUCCESS | (ci.watch_container_logs|line:658) | Container startup arm64v8-9.0.2-r1-ls87: PASSED after 9.03 seconds | 10/08/2025 14:02:05 | ARM64Thread_0 | LSIO CI | INFO | (ci.generate_sbom|line:504) | Get package versions for arm64v8-9.0.2-r1-ls87 completed | 10/08/2025 14:02:05 | ARM64Thread_0 | LSIO CI | SUCCESS | (ci.generate_sbom|line:506) | Create SBOM package list arm64v8-9.0.2-r1-ls87: PASSED after 146.01 seconds | 10/08/2025 14:02:05 | ARM64Thread_0 | LSIO CI | INFO | (ci.create_html_ansi_file|line:751) | Creating arm64v8-9.0.2-r1-ls87.sbom.html | 10/08/2025 14:02:10 | AMD64Thread_0 | LSIO CI | INFO | (ci.generate_sbom|line:504) | Get package versions for amd64-9.0.2-r1-ls87 completed | 10/08/2025 14:02:10 | AMD64Thread_0 | LSIO CI | SUCCESS | (ci.generate_sbom|line:506) | Create SBOM package list amd64-9.0.2-r1-ls87: PASSED after 150.66 seconds | 10/08/2025 14:02:10 | AMD64Thread_0 | LSIO CI | INFO | (ci.create_html_ansi_file|line:751) | Creating amd64-9.0.2-r1-ls87.sbom.html | 10/08/2025 14:02:23 | AMD64Thread_0 | LSIO CI | ERROR | (ci.generate_sbom|line:511) | Failed to remove the syft container, amd64-9.0.2-r1-ls87 |'Traceback (most recent call last):\n File "/lsiopy/lib/python3.12/site-packages/docker/api/client.py", line 268, in _raise_for_status\n response.raise_for_status()\n File "/lsiopy/lib/python3.12/site-packages/requests/models.py", line 1021, in raise_for_status\n raise HTTPError(http_error_msg, response=self)\nrequests.exceptions.HTTPError: 500 Server Error: Internal Server Error for url: http+docker://localhost/v1.50/containers/9257f4b43389d933a2a20921c24d6bec0519ff838701ecd33e7e72d7769fc54b?v=False&link=False&force=True\n\nThe above exception was the direct cause of the following exception:\n\nTraceback (most recent call last):\n File "/ci/ci.py", line 509, in generate_sbom\n syft.remove(force=True)\n File "/lsiopy/lib/python3.12/site-packages/docker/models/containers.py", line 352, in remove\n return self.client.api.remove_container(self.id, **kwargs)\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File "/lsiopy/lib/python3.12/site-packages/docker/utils/decorators.py", line 19, in wrapped\n return f(self, resource_id, *args, **kwargs)\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File "/lsiopy/lib/python3.12/site-packages/docker/api/container.py", line 1027, in remove_container\n self._raise_for_status(res)\n File "/lsiopy/lib/python3.12/site-packages/docker/api/client.py", line 270, in _raise_for_status\n raise create_api_error_from_http_exception(e) from e\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File "/lsiopy/lib/python3.12/site-packages/docker/errors.py", line 39, in create_api_error_from_http_exception\n raise cls(e, response=response, explanation=explanation) from e\ndocker.errors.APIError: 500 Server Error for http+docker://localhost/v1.50/containers/9257f4b43389d933a2a20921c24d6bec0519ff838701ecd33e7e72d7769fc54b?v=False&link=False&force=True: Internal Server Error ("cannot remove container "9257f4b43389d933a2a20921c24d6bec0519ff838701ecd33e7e72d7769fc54b": could not kill container: tried to kill container, but did not receive an exit event")'| 10/08/2025 14:02:23 | AMD64Thread | LSIO CI | INFO | (ci.get_build_info|line:616) | Fetching build info on tag: amd64-9.0.2-r1-ls87 | 10/08/2025 14:02:23 | AMD64Thread | LSIO CI | SUCCESS | (ci.get_build_info|line:627) | Get build info on tag 'amd64-9.0.2-r1-ls87': PASS | 10/08/2025 14:02:23 | AMD64Thread | LSIO CI | INFO | (ci.setup_driver|line:949) | Init Chromedriver | 10/08/2025 14:02:24 | AMD64Thread | LSIO CI | INFO | (ci.take_screenshot|line:845) | Trying for 120 seconds to take a screenshot of amd64-9.0.2-r1-ls87 | 10/08/2025 14:02:38 | AMD64Thread | LSIO CI | SUCCESS | (ci.take_screenshot|line:857) | Screenshot amd64-9.0.2-r1-ls87: PASSED after 14.99 seconds | 10/08/2025 14:02:38 | AMD64Thread | LSIO CI | INFO | (ci._get_browser_logs|line:411) | Getting browser console logs for tag amd64-9.0.2-r1-ls87 | 10/08/2025 14:02:38 | AMD64Thread | LSIO CI | INFO | (ci.create_html_ansi_file|line:751) | Creating amd64-9.0.2-r1-ls87.browser.html | 10/08/2025 14:02:38 | AMD64Thread | LSIO CI | INFO | (ci.create_html_ansi_file|line:751) | Creating amd64-9.0.2-r1-ls87.log.html | 10/08/2025 14:02:51 | ARM64Thread | LSIO CI | INFO | (ci.get_build_info|line:616) | Fetching build info on tag: arm64v8-9.0.2-r1-ls87 | 10/08/2025 14:02:51 | ARM64Thread | LSIO CI | SUCCESS | (ci.get_build_info|line:627) | Get build info on tag 'arm64v8-9.0.2-r1-ls87': PASS | 10/08/2025 14:02:51 | ARM64Thread | LSIO CI | INFO | (ci.setup_driver|line:949) | Init Chromedriver | 10/08/2025 14:02:51 | ARM64Thread | LSIO CI | INFO | (ci.take_screenshot|line:845) | Trying for 120 seconds to take a screenshot of arm64v8-9.0.2-r1-ls87 | 10/08/2025 14:02:51 | AMD64Thread | LSIO CI | ERROR | (ci._endtest|line:377) | Failed to remove container amd64-9.0.2-r1-ls87 |'Traceback (most recent call last):\n File "/lsiopy/lib/python3.12/site-packages/docker/api/client.py", line 268, in _raise_for_status\n response.raise_for_status()\n File "/lsiopy/lib/python3.12/site-packages/requests/models.py", line 1021, in raise_for_status\n raise HTTPError(http_error_msg, response=self)\nrequests.exceptions.HTTPError: 500 Server Error: Internal Server Error for url: http+docker://localhost/v1.50/containers/6ea685eba4e3ede25b30f52c028bad5c98eb978b00d314874bf38703868112c7?v=False&link=False&force=true\n\nThe above exception was the direct cause of the following exception:\n\nTraceback (most recent call last):\n File "/ci/ci.py", line 375, in _endtest\n container.remove(force="true")\n File "/lsiopy/lib/python3.12/site-packages/docker/models/containers.py", line 352, in remove\n return self.client.api.remove_container(self.id, **kwargs)\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File "/lsiopy/lib/python3.12/site-packages/docker/utils/decorators.py", line 19, in wrapped\n return f(self, resource_id, *args, **kwargs)\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File "/lsiopy/lib/python3.12/site-packages/docker/api/container.py", line 1027, in remove_container\n self._raise_for_status(res)\n File "/lsiopy/lib/python3.12/site-packages/docker/api/client.py", line 270, in _raise_for_status\n raise create_api_error_from_http_exception(e) from e\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File "/lsiopy/lib/python3.12/site-packages/docker/errors.py", line 39, in create_api_error_from_http_exception\n raise cls(e, response=response, explanation=explanation) from e\ndocker.errors.APIError: 500 Server Error for http+docker://localhost/v1.50/containers/6ea685eba4e3ede25b30f52c028bad5c98eb978b00d314874bf38703868112c7?v=False&link=False&force=true: Internal Server Error ("cannot remove container "6ea685eba4e3ede25b30f52c028bad5c98eb978b00d314874bf38703868112c7": could not kill container: tried to kill container, but did not receive an exit event")'| 10/08/2025 14:02:51 | AMD64Thread | LSIO CI | SUCCESS | (ci.container_test|line:353) | Test of amd64-9.0.2-r1-ls87 PASSED after 204.51 seconds | 10/08/2025 14:03:02 | ARM64Thread | LSIO CI | SUCCESS | (ci.take_screenshot|line:857) | Screenshot arm64v8-9.0.2-r1-ls87: PASSED after 10.97 seconds | 10/08/2025 14:03:02 | ARM64Thread | LSIO CI | INFO | (ci._get_browser_logs|line:411) | Getting browser console logs for tag arm64v8-9.0.2-r1-ls87 | 10/08/2025 14:03:02 | ARM64Thread | LSIO CI | INFO | (ci.create_html_ansi_file|line:751) | Creating arm64v8-9.0.2-r1-ls87.browser.html | 10/08/2025 14:03:02 | ARM64Thread | LSIO CI | INFO | (ci.create_html_ansi_file|line:751) | Creating arm64v8-9.0.2-r1-ls87.log.html | 10/08/2025 14:03:04 | ARM64Thread | LSIO CI | SUCCESS | (ci.container_test|line:353) | Test of arm64v8-9.0.2-r1-ls87 PASSED after 217.50 seconds | 10/08/2025 14:03:04 | MainThread | __main__ | SUCCESS | (test_build.run_test|line:16) | All tests PASSED after 217.55 seconds | 10/08/2025 14:03:04 | MainThread | LSIO CI | INFO | (ci.report_render|line:674) | Rendering Report | 10/08/2025 14:03:04 | MainThread | LSIO CI | INFO | (ci.badge_render|line:693) | Creating badge | 10/08/2025 14:03:04 | MainThread | LSIO CI | INFO | (ci.json_render|line:705) | Creating report.json file | 10/08/2025 14:03:04 | MainThread | LSIO CI | INFO | (ci.report_upload|line:720) | Uploading report files | 10/08/2025 14:03:05 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/amd64-9.0.2-r1-ls87.png to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:06 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/report.json to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:08 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/amd64-9.0.2-r1-ls87.browser.html to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:09 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/arm64v8-9.0.2-r1-ls87.sbom.html to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:10 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/amd64-9.0.2-r1-ls87.log.html to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:11 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/404.jpg to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:12 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/ci.log to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:13 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/favicon.ico to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:14 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/badge.svg to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:15 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/index.html to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:17 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/arm64v8-9.0.2-r1-ls87.browser.html to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:18 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/arm64v8-9.0.2-r1-ls87.png to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:19 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/arm64v8-9.0.2-r1-ls87.log.html to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:20 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/logo.jpg to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:21 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/amd64-9.0.2-r1-ls87.sbom.html to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:22 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/ci-status.yml to ci-tests.linuxserver.io bucket | 10/08/2025 14:03:23 | MainThread | LSIO CI | INFO | (ci.report_upload|line:738) | Report available on https://ci-tests.linuxserver.io/linuxserver/kicad/9.0.2-r1-ls87/index.html | 10/08/2025 14:03:23 | MainThread | LSIO CI | INFO | (ci.log_upload|line:780) | Uploading logs | 10/08/2025 14:03:23 | MainThread | LSIO CI | INFO | (ci.upload_file|line:772) | Uploading /ci/output/linuxserver/kicad/9.0.2-r1-ls87/ci.log to ci-tests.linuxserver.io bucket |