[taskcluster 2024-01-11 21:41:49.686Z] Task ID: GCXhOZd2RAqdD2HH2Dujbg
[taskcluster 2024-01-11 21:41:49.686Z] Worker ID: 7841310753084531583
[taskcluster 2024-01-11 21:41:49.686Z] Worker Group: us-west1
[taskcluster 2024-01-11 21:41:49.686Z] Worker Node Type: projects/887720501152/machineTypes/n2-highmem-32
[taskcluster 2024-01-11 21:41:49.686Z] Worker Pool: translations-1/b-linux-large-gcp-300gb
[taskcluster 2024-01-11 21:41:49.686Z] Worker Version: 38.0.5
[taskcluster 2024-01-11 21:41:49.686Z] Public IP: 34.105.97.143
[taskcluster 2024-01-11 21:41:49.686Z] Hostname: translations-1-b-linux-large-gcp-300gb-bgoqs0vvr1ucdsezzgyxlw
[taskcluster 2024-01-11 21:41:49.686Z] using cache "translations-level-1-checkouts-v3-58974d7dcf0417b3fe53-JEojYIdHR228XjxewijpYw" -> /builds/worker/checkouts

[taskcluster 2024-01-11 21:41:53.325Z] Downloading artifact "public/image.tar.zst" from task ID: JEojYIdHR228XjxewijpYw.
[taskcluster 2024-01-11 21:41:58.326Z] Download Progress: 98.23%
[taskcluster 2024-01-11 21:41:58.353Z] Downloaded artifact successfully.
[taskcluster 2024-01-11 21:41:58.353Z] Downloaded 251.881 mb
[taskcluster 2024-01-11 21:41:58.353Z] Decompressing downloaded image
[taskcluster 2024-01-11 21:41:59.815Z] Loading docker image from downloaded archive.
[taskcluster 2024-01-11 21:42:08.819Z] Image 'public/image.tar.zst' from task 'JEojYIdHR228XjxewijpYw' loaded.  Using image ID sha256:c5ffa7c5a30f7c56161b6472aafb1fd56f2211a6dff55bb47b33e0214bd65ce9.
[taskcluster 2024-01-11 21:42:08.915Z] === Task Starting ===
[setup 2024-01-11T21:42:16.167Z] run-task started in /builds/worker
[setup 2024-01-11T21:42:16.167Z] Invoked by command: --firefox_translations_training-checkout=/builds/worker/checkouts/vcs/ -- bash -c zstd -d --rm $MOZ_FETCHES_DIR/*.zst && python3 $VCS_PATH/pipeline/translate/bestbleu.py -i "$MOZ_FETCHES_DIR/file.2.nbest" -r "$MOZ_FETCHES_DIR/file.2.ref" -o /builds/worker/artifacts/file.2.nbest.out -m bleu
[setup 2024-01-11T21:42:16.167Z] Python version: 3.10.12
[cache 2024-01-11T21:42:16.169Z] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2024-01-11T21:42:16.169Z] volume /builds/worker/checkouts is a cache
[setup 2024-01-11T21:42:16.169Z] running as worker:worker
[vcs 2024-01-11T21:42:16.170Z] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2024-01-11T21:42:16.171Z] executing ['git', 'clone', 'https://github.com/mozilla/firefox-translations-training', '/builds/worker/checkouts/vcs']
[vcs 2024-01-11T21:42:16.173Z] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2024-01-11T21:42:16.801Z] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla/firefox-translations-training', 'dependabot/pip/taskcluster/jinja2-3.1.3']
[vcs 2024-01-11T21:42:17.110Z] From https://github.com/mozilla/firefox-translations-training
[vcs 2024-01-11T21:42:17.110Z]  * branch            dependabot/pip/taskcluster/jinja2-3.1.3 -> FETCH_HEAD
[vcs 2024-01-11T21:42:17.116Z] executing ['git', 'fetch', '--no-tags', 'https://github.com/mozilla/firefox-translations-training', 'dependabot/pip/taskcluster/jinja2-3.1.3']
[vcs 2024-01-11T21:42:17.442Z] From https://github.com/mozilla/firefox-translations-training
[vcs 2024-01-11T21:42:17.442Z]  * branch            dependabot/pip/taskcluster/jinja2-3.1.3 -> FETCH_HEAD
[vcs 2024-01-11T21:42:17.447Z] executing ['git', 'checkout', '-f', '-B', 'dependabot/pip/taskcluster/jinja2-3.1.3', '3177fe8708ed9226cc0b7d5886eb1c13f3ad40b0']
[vcs 2024-01-11T21:42:17.482Z] Switched to a new branch 'dependabot/pip/taskcluster/jinja2-3.1.3'
[vcs 2024-01-11T21:42:17.483Z] executing ['git', 'submodule', 'init']
[vcs 2024-01-11T21:42:17.502Z] Submodule '3rd_party/browsermt-marian-dev' (https://github.com/browsermt/marian-dev) registered for path '3rd_party/browsermt-marian-dev'
[vcs 2024-01-11T21:42:17.503Z] Submodule 'extract-lex' (https://github.com/marian-nmt/extract-lex) registered for path '3rd_party/extract-lex'
[vcs 2024-01-11T21:42:17.503Z] Submodule 'fast_align' (https://github.com/clab/fast_align) registered for path '3rd_party/fast_align'
[vcs 2024-01-11T21:42:17.504Z] Submodule '3rd_party/kenlm' (https://github.com/kpu/kenlm) registered for path '3rd_party/kenlm'
[vcs 2024-01-11T21:42:17.504Z] Submodule '3rd_party/marian-dev' (https://github.com/marian-nmt/marian-dev) registered for path '3rd_party/marian-dev'
[vcs 2024-01-11T21:42:17.505Z] Submodule '3rd_party/preprocess' (https://github.com/kpu/preprocess.git) registered for path '3rd_party/preprocess'
[vcs 2024-01-11T21:42:17.505Z] executing ['git', 'submodule', 'update', '--force']
[vcs 2024-01-11T21:42:17.525Z] Cloning into '/builds/worker/checkouts/vcs/3rd_party/browsermt-marian-dev'...
[vcs 2024-01-11T21:42:18.865Z] Cloning into '/builds/worker/checkouts/vcs/3rd_party/extract-lex'...
[vcs 2024-01-11T21:42:19.265Z] Cloning into '/builds/worker/checkouts/vcs/3rd_party/fast_align'...
[vcs 2024-01-11T21:42:19.714Z] Cloning into '/builds/worker/checkouts/vcs/3rd_party/kenlm'...
[vcs 2024-01-11T21:42:20.384Z] Cloning into '/builds/worker/checkouts/vcs/3rd_party/marian-dev'...
[vcs 2024-01-11T21:42:22.086Z] Cloning into '/builds/worker/checkouts/vcs/3rd_party/preprocess'...
[vcs 2024-01-11T21:42:22.638Z] Submodule path '3rd_party/browsermt-marian-dev': checked out '11c6ae7c46be21ef96ed10c60f28022fa968939f'
[vcs 2024-01-11T21:42:22.648Z] Submodule path '3rd_party/extract-lex': checked out '42fa605b53f32eaf6c6e0b5677255c21c91b3d49'
[vcs 2024-01-11T21:42:22.656Z] Submodule path '3rd_party/fast_align': checked out 'cab1e9aac8d3bb02ff5ae58218d8d225a039fa11'
[vcs 2024-01-11T21:42:22.679Z] Submodule path '3rd_party/kenlm': checked out 'bbf4fc511266c5d4515047055d7bdec659a6e158'
[vcs 2024-01-11T21:42:22.780Z] Submodule path '3rd_party/marian-dev': checked out 'e8a1a2530fb84cbff7383302ebca393e5875c441'
[vcs 2024-01-11T21:42:22.797Z] Submodule path '3rd_party/preprocess': checked out '64307314b4d5a9a0bd529b5c1036b0710d995eec'
[vcs 2024-01-11T21:42:22.797Z] cleaning git checkout...
[vcs 2024-01-11T21:42:22.797Z] executing ['git', 'clean', '-nxdff']
[vcs 2024-01-11T21:42:22.800Z] removing []
[vcs 2024-01-11T21:42:22.800Z] successfully cleaned git checkout!
[vcs 2024-01-11T21:42:22.801Z] TinderboxPrint:<a href='https://github.com/mozilla/firefox-translations-training/commit/3177fe8708ed9226cc0b7d5886eb1c13f3ad40b0' title='Built from firefox-translations-training commit 3177fe8708ed9226cc0b7d5886eb1c13f3ad40b0'>3177fe8708ed9226cc0b7d5886eb1c13f3ad40b0</a>
[setup 2024-01-11T21:42:22.801Z] MOZ_FETCHES_DIR is /builds/worker/fetches
[fetches 2024-01-11T21:42:22.801Z] fetching artifacts
[fetches 2024-01-11T21:42:22.801Z] executing ['/usr/bin/python3', '-u', '/usr/local/bin/fetch-content', 'task-artifacts']
attempt 1/5attempt 1/5
Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PBTsF1iFRbOPBzQgq0F07w/artifacts/public/build/file.2.ref.zst to /builds/worker/fetches/file.2.ref.zst

Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/J6TXDcc_QBSyqK28bBBzFw/artifacts/public/build/file.2.nbest.zst to /builds/worker/fetches/file.2.nbest.zst
Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PBTsF1iFRbOPBzQgq0F07w/artifacts/public/build/file.2.ref.zst
Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/J6TXDcc_QBSyqK28bBBzFw/artifacts/public/build/file.2.nbest.zst
https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PBTsF1iFRbOPBzQgq0F07w/artifacts/public/build/file.2.ref.zst resolved to 152306 bytes with sha256 0d35077170016b78819e14d74cd2500b5ca9fb1e224f7840908be1ebd3b09116 in 0.169s
https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/J6TXDcc_QBSyqK28bBBzFw/artifacts/public/build/file.2.nbest.zst resolved to 429261 bytes with sha256 51b58f5cd3db477545be4cb6abbfac3ac854649ee13f78ebd5fc5ba23e866b1f in 0.219s
PERFHERDER_DATA: {"framework": {"name": "build_metrics"}, "suites": [{"name": "fetch_content", "value": 0.2222245909999856, "lowerIsBetter": true, "shouldAlert": false, "subtests": []}]}
[fetches 2024-01-11T21:42:23.103Z] finished fetching artifacts
[task 2024-01-11T21:42:23.103Z] executing ['bash', '-c', 'zstd -d --rm $MOZ_FETCHES_DIR/*.zst && python3 $VCS_PATH/pipeline/translate/bestbleu.py -i "$MOZ_FETCHES_DIR/file.2.nbest" -r "$MOZ_FETCHES_DIR/file.2.ref" -o /builds/worker/artifacts/file.2.nbest.out -m bleu']
[task 2024-01-11T21:42:23.105Z] 
[task 2024-01-11T21:42:23.122Z] Decompress:  1/ 2 files. Current: ...ile.2.nbest.zst : 0 MB...    
[task 2024-01-11T21:42:23.123Z]                                                                                
[task 2024-01-11T21:42:23.123Z] 
[task 2024-01-11T21:42:23.123Z]                                                                                
[task 2024-01-11T21:42:23.123Z] 2 files decompressed : 20931695 bytes total 
[fetches 2024-01-11T21:42:27.201Z] removing /builds/worker/fetches
[fetches 2024-01-11T21:42:27.204Z] finished
[taskcluster 2024-01-11 21:42:27.704Z] === Task Finished ===
[taskcluster 2024-01-11 21:42:27.968Z] Successful task run with exit code: 0 completed in 38.284 seconds