@eric.tx
torizoncore-builder --verbose --log-level debug build
2024-03-13 17:29:02,215 - torizon.tcbuilder.cli.build - INFO - Building image as per configuration file ‘tcbuild.yaml’…
2024-03-13 17:29:02,215 - torizon.tcbuilder.cli.build - DEBUG - Substitutions (enabled): {}
2024-03-13 17:29:02,277 - torizon.tcbuilder.cli.build - INFO -
=>> Handling input section
2024-03-13 17:29:03,966 - torizon.tcbuilder.backend.images - INFO - Unpacking Toradex Easy Installer image.
2024-03-13 17:29:03,967 - torizon.tcbuilder.backend.images - DEBUG - Running tar command: cat /workdir/torizon-core-docker-verdin-imx8mp-Tezi_6.6.0-devel-20240305+build.522.tar | cat | tar -xf - -C /storage/tezi.tmp
2024-03-13 17:29:04,528 - torizon.tcbuilder.backend.images - INFO - Copying Toradex Easy Installer image.
2024-03-13 17:29:04,528 - torizon.tcbuilder.backend.images - DEBUG - Copy directory /storage/tezi.tmp/torizon-core-docker-verdin-imx8mp-Tezi_6.6.0-devel-20240305+build.522 → /storage/tezi.
2024-03-13 17:29:04,703 - torizon.tcbuilder.backend.images - INFO - Unpacking TorizonCore Toradex Easy Installer image.
2024-03-13 17:29:04,704 - torizon.tcbuilder.backend.images - DEBUG - Running tar command: cat ‘/storage/tezi/torizon-core-docker-verdin-imx8mp.ota.tar.zst’ | zstd -dc | tar --xattrs --xattrs-include=‘*’ -xhf - -C /storage/sysroot
2024-03-13 17:29:08,488 - torizon.tcbuilder.backend.images - INFO - Importing OSTree revision b335e79d27949c07af0a4c5c31500fc66e153dd8b597debd7dcfe19389583374 from local repository…
2024-03-13 17:29:08,490 - torizon.tcbuilder.backend.ostree - DEBUG - Pulling from local repository /storage/sysroot/ostree/repo commit checksum b335e79d27949c07af0a4c5c31500fc66e153dd8b597debd7dcfe19389583374
921 metadata, 9128 content objects imported; 582.9 MB content written
2024-03-13 17:29:12,705 - torizon.tcbuilder.backend.ostree - DEBUG - Pulling from local repository /storage/sysroot/ostree/repo commit checksum b335e79d27949c07af0a4c5c31500fc66e153dd8b597debd7dcfe19389583374
0 metadata, 0 content objects imported; 0 bytes content written
2024-03-13 17:29:12,762 - torizon.tcbuilder.backend.images - INFO - Unpacked OSTree from Toradex Easy Installer image:
2024-03-13 17:29:12,762 - torizon.tcbuilder.backend.images - INFO - Commit checksum: b335e79d27949c07af0a4c5c31500fc66e153dd8b597debd7dcfe19389583374
2024-03-13 17:29:12,762 - torizon.tcbuilder.backend.images - INFO - TorizonCore Version: 6.6.0-devel-20240305+build.522
2024-03-13 17:29:12,762 - torizon.tcbuilder.cli.build - INFO -
=>> Handling customization section
2024-03-13 17:29:12,762 - torizon.tcbuilder.cli.build - INFO -
=> Handling device-tree subsection
2024-03-13 17:29:12,762 - torizon.tcbuilder.cli.dto - DEBUG - Removing all overlays
2024-03-13 17:29:12,811 - torizon.tcbuilder.cli.build - INFO - Not testing overlay because base image does not have a device-tree set!
2024-03-13 17:29:12,811 - torizon.tcbuilder.cli.build - INFO -
=> Adding device-tree overlay ‘device-trees/overlays/verdin-imx8mp-gimbal3b-overlay.dts’
2024-03-13 17:29:12,836 - torizon.tcbuilder.backend.dt - INFO - ‘verdin-imx8mp-gimbal3b-overlay.dts’ compiles successfully.
2024-03-13 17:29:12,860 - torizon.tcbuilder.cli.dto - INFO - Overlay verdin-imx8mp-gimbal3b-overlay.dtbo successfully applied.
2024-03-13 17:29:12,860 - torizon.tcbuilder.cli.build - INFO -
=>> Handling output section
2024-03-13 17:29:12,860 - torizon.tcbuilder.cli.union - DEBUG - union: subject=‘None’ body=‘None’
2024-03-13 17:29:12,862 - torizon.tcbuilder.cli.union - INFO - Applying changes from STORAGE/dt.
2024-03-13 17:29:12,864 - torizon.tcbuilder.backend.union - DEBUG - Processing whiteouts.
2024-03-13 17:29:12,970 - torizon.tcbuilder.backend.union - DEBUG - Transaction committed. 2946 bytes 2 objects written.
2024-03-13 17:29:12,974 - torizon.tcbuilder.cli.union - INFO - Commit 43f5511447c81e6fc9a8e555b3dbc60aa0d66438c3ab5aac6ebb49651631f2e7 has been generated for changes and is ready to be deployed.
Deploying commit ref: tcbuilder-20240313172912
2024-03-13 17:29:12,980 - torizon.tcbuilder.backend.deploy - INFO - Pulling OSTree with ref tcbuilder-20240313172912 from local archive repository…
2024-03-13 17:29:12,980 - torizon.tcbuilder.backend.deploy - INFO - Commit checksum: 43f5511447c81e6fc9a8e555b3dbc60aa0d66438c3ab5aac6ebb49651631f2e7
2024-03-13 17:29:12,980 - torizon.tcbuilder.backend.deploy - INFO - TorizonCore Version: 6.6.0-devel-20240305+build.522-tcbuilder.20240313172912
2024-03-13 17:29:12,980 - torizon.tcbuilder.backend.deploy - INFO - Default kernel arguments: quiet logo.nologo vt.global_cursor_default=0 plymouth.ignore-serial-consoles splash fbcon=map:3
2024-03-13 17:29:12,980 - torizon.tcbuilder.backend.ostree - DEBUG - Pulling from local repository /storage/ostree-archive commit checksum 43f5511447c81e6fc9a8e555b3dbc60aa0d66438c3ab5aac6ebb49651631f2e7
921 metadata, 9129 content objects imported; 582.9 MB content written
2024-03-13 17:29:14,485 - torizon.tcbuilder.backend.deploy - INFO - Pulling done.
2024-03-13 17:29:14,486 - torizon.tcbuilder.backend.deploy - INFO - Deploying OSTree with checksum 43f5511447c81e6fc9a8e555b3dbc60aa0d66438c3ab5aac6ebb49651631f2e7
2024-03-13 17:29:14,486 - torizon.tcbuilder.backend.deploy - DEBUG - Deploying revision 43f5511447c81e6fc9a8e555b3dbc60aa0d66438c3ab5aac6ebb49651631f2e7
2024-03-13 17:29:15,061 - torizon.tcbuilder.backend.deploy - DEBUG - Write deployment for revision 43f5511447c81e6fc9a8e555b3dbc60aa0d66438c3ab5aac6ebb49651631f2e7
2024-03-13 17:29:15,272 - torizon.tcbuilder.backend.deploy - INFO - Deploying done.
2024-03-13 17:29:15,272 - torizon.tcbuilder.backend.deploy - INFO - Copy files not under OSTree control from original deployment.
2024-03-13 17:29:15,279 - torizon.tcbuilder.backend.deploy - INFO - Packing rootfs…
2024-03-13 17:29:15,281 - torizon.tcbuilder.backend.deploy - DEBUG - Running tar command: tar --xattrs --xattrs-include=‘*’ -cf /workdir/torizon-core-docker-verdin-imx8mp-Tezi_6.6.0-devel-20240305+build.522.CUSTOM/torizon-core-docker-verdin-imx8mp.ota.tar -S -C /deploy -p .
2024-03-13 17:29:16,104 - torizon.tcbuilder.backend.deploy - DEBUG - Running compress command: zstd --rm /workdir/torizon-core-docker-verdin-imx8mp-Tezi_6.6.0-devel-20240305+build.522.CUSTOM/torizon-core-docker-verdin-imx8mp.ota.tar
2024-03-13 17:29:20,661 - torizon.tcbuilder.backend.deploy - INFO - Packing rootfs done.
2024-03-13 17:29:20,662 - torizon.tcbuilder.backend.combine - INFO - Updating TorizonCore image in place.
2024-03-13 17:29:20,662 - torizon.tezi.image - DEBUG - No ‘filelist’ present in image configuration.
2024-03-13 17:29:20,662 - torizon.tezi.image - DEBUG - Reading json_data[key], value ‘TorizonCore’
2024-03-13 17:29:20,662 - torizon.tezi.image - DEBUG - Writing json_data[key] = ‘TorizonCore’
2024-03-13 17:29:20,662 - torizon.tezi.image - DEBUG - Reading json_data[key], value ‘6.6.0-devel-20240305+build.522’
2024-03-13 17:29:20,662 - torizon.tezi.image - DEBUG - Writing json_data[key] = ‘6.6.0-devel-20240305+build.522.modified’
2024-03-13 17:29:20,662 - torizon.tezi.image - DEBUG - Writing json_data[key] = ‘2024-03-13’
2024-03-13 17:29:20,663 - torizon.tezi.image - DEBUG - Reading json_data[key], value ‘6.6.0-devel-20240305+build.522.modified’
2024-03-13 17:29:20,664 - torizon.tcbuilder.cli.build - INFO - Bundling images to directory bundle_20240313172920_664668.tmp
2024-03-13 17:29:20,665 - docker.utils.config - DEBUG - Trying paths: [‘/root/.docker/config.json’, ‘/root/.dockercfg’]
2024-03-13 17:29:20,665 - docker.utils.config - DEBUG - No config file found
2024-03-13 17:29:20,665 - docker.utils.config - DEBUG - Trying paths: [‘/root/.docker/config.json’, ‘/root/.dockercfg’]
2024-03-13 17:29:20,665 - docker.utils.config - DEBUG - No config file found
2024-03-13 17:29:20,677 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /version HTTP/1.1” 200 None
2024-03-13 17:29:20,684 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /v1.43/containers/json?limit=-1&all=0&size=0&trunc_cmd=0&filters=%7B%22name%22%3A+%5B%22tcb_1710350941%22%5D%7D HTTP/1.1” 200 None
2024-03-13 17:29:20,691 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /v1.43/containers/4f855ff0817d78cb58cf49629e45e7f6421dc7b2c23e7aab32c233c824c74321/json HTTP/1.1” 200 None
2024-03-13 17:29:20,695 - torizon.tcbuilder.backend.common - DEBUG - Current container ID (found by container name): 4f855ff0817d78cb58cf49629e45e7f6421dc7b2c23e7aab32c233c824c74321
2024-03-13 17:29:20,699 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /v1.43/containers/4f855ff0817d78cb58cf49629e45e7f6421dc7b2c23e7aab32c233c824c74321/json HTTP/1.1” 200 None
2024-03-13 17:29:20,703 - compose.config.config - DEBUG - Using configuration files: /workdir/docker-compose.yml
2024-03-13 17:29:20,718 - torizon.tcbuilder.backend.bundle - DEBUG - Using DindManager
2024-03-13 17:29:20,719 - docker.utils.config - DEBUG - Trying paths: [‘/root/.docker/config.json’, ‘/root/.dockercfg’]
2024-03-13 17:29:20,719 - docker.utils.config - DEBUG - No config file found
2024-03-13 17:29:20,719 - docker.utils.config - DEBUG - Trying paths: [‘/root/.docker/config.json’, ‘/root/.dockercfg’]
2024-03-13 17:29:20,719 - docker.utils.config - DEBUG - No config file found
2024-03-13 17:29:20,729 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /version HTTP/1.1” 200 None
2024-03-13 17:29:20,741 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /v1.43/info HTTP/1.1” 200 None
2024-03-13 17:29:20,743 - docker.utils.config - DEBUG - Trying paths: [‘/root/.docker/config.json’, ‘/root/.dockercfg’]
2024-03-13 17:29:20,743 - docker.utils.config - DEBUG - No config file found
2024-03-13 17:29:20,743 - docker.utils.config - DEBUG - Trying paths: [‘/root/.docker/config.json’, ‘/root/.dockercfg’]
2024-03-13 17:29:20,743 - docker.utils.config - DEBUG - No config file found
2024-03-13 17:29:20,753 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /version HTTP/1.1” 200 None
2024-03-13 17:29:20,760 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /v1.43/containers/json?limit=-1&all=0&size=0&trunc_cmd=0&filters=%7B%22name%22%3A+%5B%22tcb_1710350941%22%5D%7D HTTP/1.1” 200 None
2024-03-13 17:29:20,769 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /v1.43/containers/4f855ff0817d78cb58cf49629e45e7f6421dc7b2c23e7aab32c233c824c74321/json HTTP/1.1” 200 None
2024-03-13 17:29:20,772 - torizon.tcbuilder.backend.common - DEBUG - Current container ID (found by container name): 4f855ff0817d78cb58cf49629e45e7f6421dc7b2c23e7aab32c233c824c74321
2024-03-13 17:29:20,776 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /v1.43/containers/4f855ff0817d78cb58cf49629e45e7f6421dc7b2c23e7aab32c233c824c74321/json HTTP/1.1” 200 None
2024-03-13 17:29:20,780 - torizon.tcbuilder.backend.bundle - INFO -
Starting DIND container
2024-03-13 17:29:20,787 - urllib3.connectionpool - DEBUG - http://localhost:None “POST /v1.43/volumes/create HTTP/1.1” 201 None
2024-03-13 17:29:20,789 - torizon.tcbuilder.backend.bundle - DEBUG - Default platform: linux/arm64
2024-03-13 17:29:20,789 - torizon.tcbuilder.backend.bundle - DEBUG - Volume mapping for DinD: [{‘Target’: ‘/workdir/’, ‘Source’: ‘/home/steve/tcbdir’, ‘Type’: ‘bind’, ‘ReadOnly’: False}, {‘Target’: ‘/var/lib/docker/’, ‘Source’: ‘dind-volume’, ‘Type’: ‘volume’, ‘ReadOnly’: False}]
2024-03-13 17:29:20,789 - torizon.tcbuilder.backend.bundle - DEBUG - Running DinD container: ports={‘22376/tcp’: 22376}, network=bridge
2024-03-13 17:29:20,825 - urllib3.connectionpool - DEBUG - http://localhost:None “POST /v1.43/containers/create?name=tcb-fetch-dind HTTP/1.1” 201 None
2024-03-13 17:29:20,831 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /v1.43/containers/86698456eb526f5691b1aa00ac176603a5e757cf59fe4c8b6b18259b136b6387/json HTTP/1.1” 200 None
2024-03-13 17:29:21,348 - urllib3.connectionpool - DEBUG - http://localhost:None “POST /v1.43/containers/86698456eb526f5691b1aa00ac176603a5e757cf59fe4c8b6b18259b136b6387/start HTTP/1.1” 204 0
2024-03-13 17:29:21,354 - urllib3.connectionpool - DEBUG - http://localhost:None “GET /v1.43/containers/86698456eb526f5691b1aa00ac176603a5e757cf59fe4c8b6b18259b136b6387/json HTTP/1.1” 200 None
2024-03-13 17:29:24,361 - torizon.tcbuilder.backend.bundle - INFO - Connecting to Docker Daemon at “tcp://172.17.0.4:22376”
2024-03-13 17:29:24,362 - docker.utils.config - DEBUG - Trying paths: [‘/root/.docker/config.json’, ‘/root/.dockercfg’]
2024-03-13 17:29:24,362 - docker.utils.config - DEBUG - No config file found
2024-03-13 17:29:24,362 - docker.utils.config - DEBUG - Trying paths: [‘/root/.docker/config.json’, ‘/root/.dockercfg’]
2024-03-13 17:29:24,362 - docker.utils.config - DEBUG - No config file found
2024-03-13 17:29:24,363 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): 172.17.0.4:22376
2024-03-13 17:29:24,394 - urllib3.connectionpool - DEBUG - https://172.17.0.4:22376 “GET /version HTTP/1.1” 200 895
2024-03-13 17:29:24,395 - torizon.tcbuilder.backend.bundle - INFO - Fetching container image localhost:5002/gimbal3bcontainer-debug:arm64
2024-03-13 17:29:24,395 - docker.auth - DEBUG - Looking for auth config
2024-03-13 17:29:24,395 - docker.auth - DEBUG - No auth config in memory - loading from filesystem
2024-03-13 17:29:24,395 - docker.utils.config - DEBUG - Trying paths: [‘/root/.docker/config.json’, ‘/root/.dockercfg’]
2024-03-13 17:29:24,395 - docker.utils.config - DEBUG - No config file found
2024-03-13 17:29:24,395 - docker.auth - DEBUG - Looking for auth entry for ‘localhost:5002’
2024-03-13 17:29:24,395 - docker.auth - DEBUG - No entry found
2024-03-13 17:29:24,395 - docker.auth - DEBUG - No auth config found
2024-03-13 17:29:24,399 - urllib3.connectionpool - DEBUG - https://172.17.0.4:22376 “POST /v1.40/images/create?tag=arm64&fromImage=localhost%3A5002%2Fgimbal3bcontainer-debug&platform=linux%2Farm64 HTTP/1.1” 500 98
2024-03-13 17:29:24,399 - torizon.tcbuilder.backend.bundle - INFO - Stopping DIND container
2024-03-13 17:29:25,802 - urllib3.connectionpool - DEBUG - http://localhost:None “POST /v1.43/containers/86698456eb526f5691b1aa00ac176603a5e757cf59fe4c8b6b18259b136b6387/stop HTTP/1.1” 204 0
2024-03-13 17:29:26,822 - urllib3.connectionpool - DEBUG - http://localhost:None “DELETE /v1.43/volumes/dind-volume HTTP/1.1” 204 0
2024-03-13 17:29:26,822 - torizon.tcbuilder.cli.build - DEBUG - Removing temporary bundle directory bundle_20240313172920_664668.tmp
2024-03-13 17:29:26,823 - torizon.tcbuilder.cli.build - INFO - Removing output directory ‘torizon-core-docker-verdin-imx8mp-Tezi_6.6.0-devel-20240305+build.522.CUSTOM’ due to build errors
2024-03-13 17:29:26,853 - root - ERROR - Error: Error: container images download failed: 500 Server Error for https://172.17.0.4:22376/v1.40/images/create?tag=arm64&fromImage=localhost%3A5002%2Fgimbal3bcontainer-debug&platform=linux%2Farm64: Internal Server Error (“Get http://localhost:5002/v2/: dial tcp 127.0.0.1:5002: connect: connection refused”)
2024-03-13 17:29:26,856 - root - DEBUG - Traceback (most recent call last):
File “/usr/local/lib/python3.9/dist-packages/docker/api/client.py”, line 268, in _raise_for_status
response.raise_for_status()
File “/usr/local/lib/python3.9/dist-packages/requests/models.py”, line 1021, in raise_for_status
raise HTTPError(http_error_msg, response=self)
requests.exceptions.HTTPError: 500 Server Error: Internal Server Error for url: https://172.17.0.4:22376/v1.40/images/create?tag=arm64&fromImage=localhost%3A5002%2Fgimbal3bcontainer-debug&platform=linux%2Farm64
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
File “/builder/tcbuilder/backend/bundle.py”, line 573, in download_containers_by_compose_file
res_stream = dind_client.api.pull(
File “/usr/local/lib/python3.9/dist-packages/docker/api/image.py”, line 429, in pull
self._raise_for_status(response)
File “/usr/local/lib/python3.9/dist-packages/docker/api/client.py”, line 270, in _raise_for_status
raise create_api_error_from_http_exception(e) from e
File “/usr/local/lib/python3.9/dist-packages/docker/errors.py”, line 39, in create_api_error_from_http_exception
raise cls(e, response=response, explanation=explanation) from e
docker.errors.APIError: 500 Server Error for https://172.17.0.4:22376/v1.40/images/create?tag=arm64&fromImage=localhost%3A5002%2Fgimbal3bcontainer-debug&platform=linux%2Farm64: Internal Server Error (“Get http://localhost:5002/v2/: dial tcp 127.0.0.1:5002: connect: connection refused”)
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
File “/builder/torizoncore-builder”, line 221, in
mainargs.func(mainargs)
File “/builder/tcbuilder/cli/build.py”, line 506, in do_build
raise exc
File “/builder/tcbuilder/cli/build.py”, line 479, in do_build
build(args.config_fname, args.storage_directory,
File “/builder/tcbuilder/cli/build.py”, line 465, in build
raise exc
File “/builder/tcbuilder/cli/build.py”, line 454, in build
handle_output_section(
File “/builder/tcbuilder/cli/build.py”, line 303, in handle_output_section
handle_bundle_output(
File “/builder/tcbuilder/cli/build.py”, line 364, in handle_bundle_output
download_containers_by_compose_file(**download_params)
File “/builder/tcbuilder/backend/bundle.py”, line 594, in download_containers_by_compose_file
raise OperationFailureError(
tcbuilder.errors.OperationFailureError: Error: container images download failed: 500 Server Error for https://172.17.0.4:22376/v1.40/images/create?tag=arm64&fromImage=localhost%3A5002%2Fgimbal3bcontainer-debug&platform=linux%2Farm64: Internal Server Error (“Get http://localhost:5002/v2/: dial tcp 127.0.0.1:5002: connect: connection refused”)