Filed by: sstanca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=463647753&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/XbqPytm5RTGSCnic3hN3fg/runs/0/artifacts/public/logs/live_backing.log
[taskcluster 2024-06-23T01:11:35.641Z] Worker Type (gecko-t/t-linux-2204-wayland-snap) settings:
[taskcluster 2024-06-23T01:11:35.641Z] {
[taskcluster 2024-06-23T01:11:35.641Z] "config": {
[taskcluster 2024-06-23T01:11:35.641Z] "deploymentId": ""
[taskcluster 2024-06-23T01:11:35.641Z] },
[taskcluster 2024-06-23T01:11:35.641Z] "generic-worker": {
[taskcluster 2024-06-23T01:11:35.641Z] "engine": "multiuser",
[taskcluster 2024-06-23T01:11:35.641Z] "go-arch": "amd64",
[taskcluster 2024-06-23T01:11:35.641Z] "go-os": "linux",
[taskcluster 2024-06-23T01:11:35.641Z] "go-version": "go1.22.2",
[taskcluster 2024-06-23T01:11:35.641Z] "release": "https://github.com/taskcluster/taskcluster/releases/tag/v64.3.0",
[taskcluster 2024-06-23T01:11:35.641Z] "revision": "b66b6614b9b79077f1bfc78f97afb8c25c0c4b30",
[taskcluster 2024-06-23T01:11:35.641Z] "source": "https://github.com/taskcluster/taskcluster/commits/b66b6614b9b79077f1bfc78f97afb8c25c0c4b30",
[taskcluster 2024-06-23T01:11:35.641Z] "version": "64.3.0"
[taskcluster 2024-06-23T01:11:35.641Z] },
[taskcluster 2024-06-23T01:11:35.641Z] "image": "projects/taskcluster-imaging/global/images/gw-fxci-gcp-l1-gui-googlecompute-2024-05-31t15-18-14z",
[taskcluster 2024-06-23T01:11:35.641Z] "instance-id": "8050282283755293553",
[taskcluster 2024-06-23T01:11:35.641Z] "instance-type": "projects/887720501152/machineTypes/n2-standard-2",
[taskcluster 2024-06-23T01:11:35.641Z] "local-ipv4": "10.128.0.26",
[taskcluster 2024-06-23T01:11:35.641Z] "project-id": "fxci-production-level1-workers",
[taskcluster 2024-06-23T01:11:35.641Z] "public-hostname": "gecko-t-t-linux-2204-wayland-snap-ffkh0zgetjiv94-im53s6g.c.fxci-production-level1-workers.internal",
[taskcluster 2024-06-23T01:11:35.641Z] "public-ipv4": "34.66.203.60",
[taskcluster 2024-06-23T01:11:35.641Z] "region": "us-central1",
[taskcluster 2024-06-23T01:11:35.641Z] "zone": "us-central1-f"
[taskcluster 2024-06-23T01:11:35.641Z] }
[taskcluster 2024-06-23T01:11:35.641Z] Task ID: XbqPytm5RTGSCnic3hN3fg
[taskcluster 2024-06-23T01:11:35.641Z] === Task Starting ===
[taskcluster 2024-06-23T01:11:36.882Z] Uploading redirect artifact public/logs/live.log to URL https://firefoxci-websocktunnel.services.mozilla.com/us-central1-f.8050282283755293553.60099/log/Pc-cJ6JpTLG_Abn2DzC_Uw with mime type "text/plain; charset=utf-8" and expiry 2024-06-23T01:41:36.811Z
[taskcluster 2024-06-23T01:11:37.110Z] [mounts] Downloading url http://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/R47IRomJRnCtevh8Z9WHow/artifacts/public/run-task to downloads/Nu-UR-PBTvSb2R8srsQJCw
[taskcluster 2024-06-23T01:11:37.580Z] [mounts] Downloaded 38478 bytes with SHA256 91dffdb6efcae13c131e42a069128eae54153ef7c994760a3a623e62190d1a4a from url http://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/R47IRomJRnCtevh8Z9WHow/artifacts/public/run-task to downloads/Nu-UR-PBTvSb2R8srsQJCw
[taskcluster:warn 2024-06-23T01:11:37.580Z] [mounts] Download downloads/Nu-UR-PBTvSb2R8srsQJCw of url http://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/R47IRomJRnCtevh8Z9WHow/artifacts/public/run-task has SHA256 91dffdb6efcae13c131e42a069128eae54153ef7c994760a3a623e62190d1a4a but task payload does not declare a required value, so content authenticity cannot be verified
[taskcluster 2024-06-23T01:11:37.580Z] [mounts] Creating directory /task_171910498552872
[taskcluster 2024-06-23T01:11:37.612Z] [mounts] Copying downloads/Nu-UR-PBTvSb2R8srsQJCw to /task_171910498552872/run-task
[taskcluster 2024-06-23T01:11:37.648Z] [mounts] Granting task_171910498552872 full control of file '/task_171910498552872/run-task'
[taskcluster 2024-06-23T01:11:37.660Z] [mounts] Downloading url http://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/R47IRomJRnCtevh8Z9WHow/artifacts/public/fetch-content to downloads/KtPYTQEASlKBJ3eN2ZYcsg
[taskcluster 2024-06-23T01:11:37.784Z] [mounts] Downloaded 30851 bytes with SHA256 bf34e42dbbe66291b7ecabf85e6be9a967730184c2015ef90e460a7e6b218315 from url http://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/R47IRomJRnCtevh8Z9WHow/artifacts/public/fetch-content to downloads/KtPYTQEASlKBJ3eN2ZYcsg
[taskcluster:warn 2024-06-23T01:11:37.784Z] [mounts] Download downloads/KtPYTQEASlKBJ3eN2ZYcsg of url http://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/R47IRomJRnCtevh8Z9WHow/artifacts/public/fetch-content has SHA256 bf34e42dbbe66291b7ecabf85e6be9a967730184c2015ef90e460a7e6b218315 but task payload does not declare a required value, so content authenticity cannot be verified
[taskcluster 2024-06-23T01:11:37.784Z] [mounts] Creating directory /task_171910498552872
[taskcluster 2024-06-23T01:11:37.805Z] [mounts] Copying downloads/KtPYTQEASlKBJ3eN2ZYcsg to /task_171910498552872/fetch-content
[taskcluster 2024-06-23T01:11:37.812Z] [mounts] Granting task_171910498552872 full control of file '/task_171910498552872/fetch-content'
[taskcluster 2024-06-23T01:11:37.816Z] Executing command 0: chmod '+x' run-task
[taskcluster 2024-06-23T01:11:37.816Z] Exit Code: 0
[taskcluster 2024-06-23T01:11:37.816Z] User Time: 706µs
[taskcluster 2024-06-23T01:11:37.816Z] Kernel Time: 0s
[taskcluster 2024-06-23T01:11:37.816Z] Wall Time: 843.39µs
[taskcluster 2024-06-23T01:11:37.816Z] Result: SUCCEEDED
[taskcluster 2024-06-23T01:11:37.817Z] Executing command 1: './run-task' -- bash -cx 'export TASKCLUSTER_ROOT_DIR=$PWD && cd $MOZ_FETCHES_DIR/ && ./tests.sh --basic'
[setup 2024-06-23T01:11:37.897Z] run-task started in /task_171910498552872
[setup 2024-06-23T01:11:37.902Z] MOZ_FETCHES_DIR is /task_171910498552872/fetches
[fetches 2024-06-23T01:11:37.903Z] fetching artifacts
[fetches 2024-06-23T01:11:37.903Z] executing ['/usr/bin/python3', '-u', '/task_171910498552872/./fetch-content', 'task-artifacts']
[fetches 2024-06-23T01:11:38.139Z] attempt 1/5
[fetches 2024-06-23T01:11:38.139Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HcODMjJ5SrCO4A94XwGSIg/artifacts/public/build/firefox.snap to /task_171910498552872/fetches/firefox.snap
[fetches 2024-06-23T01:11:38.140Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HcODMjJ5SrCO4A94XwGSIg/artifacts/public/build/firefox.snap
[fetches 2024-06-23T01:11:38.140Z] /task_171910498552872/./fetch-content:186: DeprecationWarning: cafile, capath and cadefault are deprecated, use a custom context instead.
[fetches 2024-06-23T01:11:38.140Z] with urllib.request.urlopen(
[fetches 2024-06-23T01:11:38.141Z] attempt 1/5
[fetches 2024-06-23T01:11:38.141Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HcODMjJ5SrCO4A94XwGSIg/artifacts/public/build/snap-tests.zip to /task_171910498552872/fetches/snap-tests.zip
[fetches 2024-06-23T01:11:38.141Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HcODMjJ5SrCO4A94XwGSIg/artifacts/public/build/snap-tests.zip
[fetches 2024-06-23T01:11:38.545Z] https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HcODMjJ5SrCO4A94XwGSIg/artifacts/public/build/snap-tests.zip resolved to 9779236 bytes with sha256 2a2922fc95cc3466f5a8d21c9c55f16883e5863eba9809c78c280b83cda6665c in 0.404s
[fetches 2024-06-23T01:11:38.545Z] Verified size of https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HcODMjJ5SrCO4A94XwGSIg/artifacts/public/build/snap-tests.zip
[fetches 2024-06-23T01:11:38.547Z] Extracting /task_171910498552872/fetches/snap-tests.zip to /task_171910498552872/fetches
[fetches 2024-06-23T01:11:38.706Z] Archive: /task_171910498552872/fetches/snap-tests.zip
[fetches 2024-06-23T01:11:38.706Z] inflating: README.md
[fetches 2024-06-23T01:11:38.706Z] creating: basic_tests/
[fetches 2024-06-23T01:11:38.706Z] inflating: basic_tests/expectations.json.in
[fetches 2024-06-23T01:11:38.706Z] inflating: basic_tests.py
[fetches 2024-06-23T01:11:38.706Z] creating: qa_tests/
[fetches 2024-06-23T01:11:38.708Z] inflating: qa_tests/test_pdf_navigation_pageRotateCcw.png
[fetches 2024-06-23T01:11:38.709Z] inflating: qa_tests/test_pdf_download_base_nightly.png
[fetches 2024-06-23T01:11:38.712Z] inflating: qa_tests/test_pdf_navigation_up_nightly.png
[fetches 2024-06-23T01:11:38.713Z] inflating: qa_tests/test_pdf_navigation_pageup.png
[fetches 2024-06-23T01:11:38.714Z] inflating: qa_tests/test_pdf_download_base.png
[fetches 2024-06-23T01:11:38.716Z] inflating: qa_tests/test_pdf_navigation_pageup_nightly.png
[fetches 2024-06-23T01:11:38.718Z] inflating: qa_tests/test_pdf_navigation_next_nightly.png
[fetches 2024-06-23T01:11:38.720Z] inflating: qa_tests/test_pdf_navigation_end_nightly.png
[fetches 2024-06-23T01:11:38.721Z] inflating: qa_tests/test_pdf_zoom_p1_width.png
[fetches 2024-06-23T01:11:38.722Z] inflating: qa_tests/test_pdf_zoom_p1_400p.png
[fetches 2024-06-23T01:11:38.723Z] inflating: qa_tests/test_pdf_navigation_documentProperties.png
[fetches 2024-06-23T01:11:38.726Z] inflating: qa_tests/test_pdf_navigation_down_nightly.png
[fetches 2024-06-23T01:11:38.727Z] inflating: qa_tests/test_pdf_navigation_firstPage.png
[fetches 2024-06-23T01:11:38.729Z] inflating: qa_tests/test_pdf_navigation_left_nightly.png
[fetches 2024-06-23T01:11:38.731Z] inflating: qa_tests/test_pdf_navigation_pageRotateCw.png
[fetches 2024-06-23T01:11:38.733Z] inflating: qa_tests/test_pdf_zoom_p1_50p_nightly.png
[fetches 2024-06-23T01:11:38.734Z] inflating: qa_tests/test_pdf_navigation_lastPage.png
[fetches 2024-06-23T01:11:38.845Z] inflating: qa_tests/test_pdf_navigation_down.png
[fetches 2024-06-23T01:11:38.848Z] inflating: qa_tests/test_pdf_navigation_select_text_with_highlight.png
[fetches 2024-06-23T01:11:38.850Z] inflating: qa_tests/test_pdf_navigation_home.png
[fetches 2024-06-23T01:11:38.853Z] inflating: qa_tests/test_pdf_navigation_right_nightly.png
[fetches 2024-06-23T01:11:38.855Z] inflating: qa_tests/test_pdf_navigation_base.png
[fetches 2024-06-23T01:11:38.858Z] inflating: qa_tests/test_pdf_zoom_p1_100p_nightly.png
[fetches 2024-06-23T01:11:38.879Z] inflating: qa_tests/test_pdf_navigation_pagedown.png
[fetches 2024-06-23T01:11:38.880Z] inflating: qa_tests/test_custom_fonts_ref.png
[fetches 2024-06-23T01:11:38.883Z] inflating: qa_tests/test_pdf_navigation_right.png
[fetches 2024-06-23T01:11:38.886Z] inflating: qa_tests/test_pdf_navigation_previous_nightly.png
[fetches 2024-06-23T01:11:38.888Z] inflating: qa_tests/test_pdf_zoom_p1_actual.png
[fetches 2024-06-23T01:11:38.890Z] inflating: qa_tests/test_pdf_navigation_lastPage_nightly.png
[fetches 2024-06-23T01:11:38.892Z] inflating: qa_tests/test_pdf_navigation_firstPage_nightly.png
[fetches 2024-06-23T01:11:38.895Z] inflating: qa_tests/test_pdf_navigation_home_nightly.png
[fetches 2024-06-23T01:11:38.898Z] inflating: qa_tests/test_pdf_navigation_next.png
[fetches 2024-06-23T01:11:38.900Z] inflating: qa_tests/test_pdf_navigation_select_text_with_highlight_nightly.png
[fetches 2024-06-23T01:11:38.913Z] inflating: qa_tests/test_pdf_navigation_pageRotateCcw_nightly.png
[fetches 2024-06-23T01:11:38.913Z] inflating: qa_tests/test_pdf_zoom_p1_width_nightly.png
[fetches 2024-06-23T01:11:38.913Z] inflating: qa_tests/test_pdf_zoom_p1_150p.png
[fetches 2024-06-23T01:11:38.913Z] inflating: qa_tests/test_pdf_navigation_hand_tool.png
[fetches 2024-06-23T01:11:38.914Z] inflating: qa_tests/test_pdf_zoom_p1_fit_nightly.png
[fetches 2024-06-23T01:11:38.918Z] inflating: qa_tests/test_pdf_zoom_p1_actual_nightly.png
[fetches 2024-06-23T01:11:38.918Z] inflating: qa_tests/test_pdf_zoom_p1_400p_nightly.png
[fetches 2024-06-23T01:11:38.920Z] inflating: qa_tests/test_pdf_zoom_p1_75p_nightly.png
[fetches 2024-06-23T01:11:38.927Z] inflating: qa_tests/test_pdf_navigation_hand_tool_nightly.png
[fetches 2024-06-23T01:11:38.927Z] inflating: qa_tests/test_custom_fonts_ref_nightly.png
[fetches 2024-06-23T01:11:38.927Z] inflating: qa_tests/test_pdf_navigation_documentProperties_nightly.png
[fetches 2024-06-23T01:11:38.927Z] inflating: qa_tests/test_pdf_navigation_end.png
[fetches 2024-06-23T01:11:38.936Z] inflating: qa_tests/test_pdf_navigation_base_nightly.png
[fetches 2024-06-23T01:11:38.936Z] inflating: qa_tests/test_pdf_navigation_left.png
[fetches 2024-06-23T01:11:38.937Z] inflating: qa_tests/test_pdf_zoom_p1_fit.png
[fetches 2024-06-23T01:11:38.944Z] inflating: qa_tests/test_pdf_zoom_p1_150p_nightly.png
[fetches 2024-06-23T01:11:38.945Z] inflating: qa_tests/test_pdf_navigation_pageRotateCw_nightly.png
[fetches 2024-06-23T01:11:38.948Z] inflating: qa_tests/test_pdf_navigation_pagedown_nightly.png
[fetches 2024-06-23T01:11:38.949Z] inflating: qa_tests/test_pdf_zoom_p1_50p.png
[fetches 2024-06-23T01:11:38.950Z] inflating: qa_tests/qa_expectations.json
[fetches 2024-06-23T01:11:38.952Z] inflating: qa_tests/test_pdf_navigation_previous.png
[fetches 2024-06-23T01:11:38.955Z] inflating: qa_tests/test_pdf_navigation_select_text_without_highlight.png
[fetches 2024-06-23T01:11:38.959Z] inflating: qa_tests/test_pdf_navigation_up.png
[fetches 2024-06-23T01:11:38.962Z] inflating: qa_tests/test_pdf_zoom_p1_100p.png
[fetches 2024-06-23T01:11:38.967Z] inflating: qa_tests/test_pdf_zoom_p1_75p.png
[fetches 2024-06-23T01:11:38.967Z] inflating: qa_tests.py
[fetches 2024-06-23T01:11:38.967Z] inflating: record.py
[fetches 2024-06-23T01:11:38.967Z] inflating: requirements.txt
[fetches 2024-06-23T01:11:38.967Z] inflating: tests.sh
[fetches 2024-06-23T01:11:38.967Z] inflating: update-references.sh
[fetches 2024-06-23T01:11:38.968Z] /task_171910498552872/fetches/snap-tests.zip extracted in 0.421s
[fetches 2024-06-23T01:11:38.969Z] Removing /task_171910498552872/fetches/snap-tests.zip
[fetches 2024-06-23T01:11:50.801Z] https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HcODMjJ5SrCO4A94XwGSIg/artifacts/public/build/firefox.snap resolved to 998195200 bytes with sha256 9c9a758eca7a9a1975041fbabeaf3c6ed0627f8fa13c1f350964e0494a3499b1 in 12.661s
[fetches 2024-06-23T01:11:50.801Z] Extracting /task_171910498552872/fetches/firefox.snap to /task_171910498552872/fetches
[fetches 2024-06-23T01:11:50.801Z] PERFHERDER_DATA: {"framework": {"name": "build_metrics"}, "suites": [{"name": "fetch_content", "value": 12.664482958999997, "lowerIsBetter": true, "shouldAlert": false, "subtests": []}]}
[fetches 2024-06-23T01:11:50.814Z] finished fetching artifacts
[task 2024-06-23T01:11:50.814Z] executing ['bash', '-cx', 'export TASKCLUSTER_ROOT_DIR=$PWD && cd $MOZ_FETCHES_DIR/ && ./tests.sh --basic']
[task 2024-06-23T01:11:50.815Z] + export TASKCLUSTER_ROOT_DIR=/task_171910498552872
[task 2024-06-23T01:11:50.815Z] + TASKCLUSTER_ROOT_DIR=/task_171910498552872
[task 2024-06-23T01:11:50.815Z] + cd /task_171910498552872/fetches/
[task 2024-06-23T01:11:50.815Z] + ./tests.sh --basic
[task 2024-06-23T01:11:50.816Z] + pwd
[task 2024-06-23T01:11:50.816Z] /task_171910498552872/fetches
[task 2024-06-23T01:11:50.816Z] + SUITE=--basic
[task 2024-06-23T01:11:50.816Z] + SUITE=basic
[task 2024-06-23T01:11:50.816Z] + export ARTIFACT_DIR=/task_171910498552872/builds/worker/artifacts/
[task 2024-06-23T01:11:50.816Z] + ARTIFACT_DIR=/task_171910498552872/builds/worker/artifacts/
[task 2024-06-23T01:11:50.816Z] + mkdir -p /task_171910498552872/builds/worker/artifacts/
[task 2024-06-23T01:11:50.818Z] ++ snap info snapd --color=never --unicode=never
[task 2024-06-23T01:11:50.818Z] ++ awk '{ print $2 }'
[task 2024-06-23T01:11:50.820Z] ++ grep installed:
[task 2024-06-23T01:11:51.750Z] + SNAP_VERSION=2.63
[task 2024-06-23T01:11:51.751Z] ++ echo 2.63
[task 2024-06-23T01:11:51.751Z] ++ cut -d. -f1
[task 2024-06-23T01:11:51.752Z] + SNAP_MAJOR=2
[task 2024-06-23T01:11:51.752Z] ++ echo 2.63
[task 2024-06-23T01:11:51.753Z] ++ cut -d. -f2
[task 2024-06-23T01:11:51.754Z] + SNAP_MINOR=63
[task 2024-06-23T01:11:51.754Z] ++ echo 2.63
[task 2024-06-23T01:11:51.754Z] ++ cut -d. -f3
[task 2024-06-23T01:11:51.756Z] + SNAP_RELEASE=
[task 2024-06-23T01:11:51.756Z] + REFRESH_CAN_FAIL=true
[task 2024-06-23T01:11:51.756Z] + '[' 2 -ge 2 ']'
[task 2024-06-23T01:11:51.756Z] + '[' 2 -gt 2 ']'
[task 2024-06-23T01:11:51.756Z] + '[' 63 -ge 61 ']'
[task 2024-06-23T01:11:51.756Z] + '[' 63 -gt 61 ']'
[task 2024-06-23T01:11:51.756Z] + REFRESH_CAN_FAIL=false
[task 2024-06-23T01:11:51.756Z] + '[' false = true ']'
[task 2024-06-23T01:11:51.756Z] + sudo snap refresh
[task 2024-06-23T01:12:45.712Z] firefox 127.0.1-1 from Mozilla** refreshed
[task 2024-06-23T01:12:45.713Z] google-cloud-cli 481.0.0 from Cloud SDK (google-cloud-sdk**) refreshed
[task 2024-06-23T01:12:45.715Z] + sudo snap refresh --hold=24h firefox
[task 2024-06-23T01:12:45.811Z] General refreshes of "firefox" held until 2024-06-24T01:12:45Z
[task 2024-06-23T01:12:45.811Z] + true
[task 2024-06-23T01:12:45.811Z] + grep -E '(Doing|Undoing|Do\s|restarting)'
[task 2024-06-23T01:12:45.811Z] + snap changes
[task 2024-06-23T01:12:45.838Z] + break
[task 2024-06-23T01:12:45.838Z] + sudo snap install --name firefox --dangerous ./firefox.snap
[task 2024-06-23T01:13:03.477Z] firefox 128.0b6-1 installed
[task 2024-06-23T01:13:03.481Z] ++ awk '{ print $3 }'
[task 2024-06-23T01:13:03.483Z] ++ snap run firefox --version
[task 2024-06-23T01:13:05.392Z] [4568, Main Thread] WARNING: XPCOM object ProfilerParentTracker destroyed from static ctor/dtor: file /builds/worker/firefox-snap/parts/firefox/build/xpcom/base/nsTraceRefcnt.cpp:216
[task 2024-06-23T01:13:05.393Z] [4568, Main Thread] WARNING: XPCOM object nsLocalFile released from static ctor/dtor: file /builds/worker/firefox-snap/parts/firefox/build/xpcom/base/nsTraceRefcnt.cpp:216
[task 2024-06-23T01:13:05.393Z] [4568, Main Thread] WARNING: XPCOM object StringBuffer released from static ctor/dtor: file /builds/worker/firefox-snap/parts/firefox/build/xpcom/base/nsTraceRefcnt.cpp:216
[task 2024-06-23T01:13:05.393Z] [4568, Main Thread] WARNING: XPCOM object StringBuffer released from static ctor/dtor: file /builds/worker/firefox-snap/parts/firefox/build/xpcom/base/nsTraceRefcnt.cpp:216
[task 2024-06-23T01:13:05.403Z] + RUNTIME_VERSION=128.0b6
[task 2024-06-23T01:13:05.403Z] + python3 -m pip install --user -r requirements.txt
[task 2024-06-23T01:13:09.064Z] Collecting attrs==23.1.0
[task 2024-06-23T01:13:09.244Z] Downloading attrs-23.1.0-py3-none-any.whl (61 kB)
[task 2024-06-23T01:13:09.293Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 61.2/61.2 KB 1.1 MB/s eta 0:00:00
[task 2024-06-23T01:13:09.338Z] Collecting blessed==1.20.0
[task 2024-06-23T01:13:09.374Z] Downloading blessed-1.20.0-py2.py3-none-any.whl (58 kB)
[task 2024-06-23T01:13:09.390Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 58.4/58.4 KB 3.8 MB/s eta 0:00:00
[task 2024-06-23T01:13:09.436Z] Collecting certifi==2023.7.22
[task 2024-06-23T01:13:09.472Z] Downloading certifi-2023.7.22-py3-none-any.whl (158 kB)
[task 2024-06-23T01:13:09.514Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 158.3/158.3 KB 3.8 MB/s eta 0:00:00
[task 2024-06-23T01:13:09.550Z] Collecting exceptiongroup==1.1.3
[task 2024-06-23T01:13:09.586Z] Downloading exceptiongroup-1.1.3-py3-none-any.whl (14 kB)
[task 2024-06-23T01:13:09.620Z] Collecting h11==0.14.0
[task 2024-06-23T01:13:09.657Z] Downloading h11-0.14.0-py3-none-any.whl (58 kB)
[task 2024-06-23T01:13:09.667Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 58.3/58.3 KB 9.9 MB/s eta 0:00:00
[task 2024-06-23T01:13:09.698Z] Collecting idna==3.4
[task 2024-06-23T01:13:09.734Z] Downloading idna-3.4-py3-none-any.whl (61 kB)
[task 2024-06-23T01:13:09.742Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 61.5/61.5 KB 11.5 MB/s eta 0:00:00
[task 2024-06-23T01:13:09.771Z] Collecting mozfile==3.0.0
[task 2024-06-23T01:13:09.809Z] Downloading mozfile-3.0.0-py2.py3-none-any.whl (8.2 kB)
[task 2024-06-23T01:13:09.848Z] Collecting mozlog==8.0.0
[task 2024-06-23T01:13:09.884Z] Downloading mozlog-8.0.0.tar.gz (53 kB)
[task 2024-06-23T01:13:09.891Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 53.1/53.1 KB 11.0 MB/s eta 0:00:00
[task 2024-06-23T01:13:09.905Z] Preparing metadata (setup.py): started
[task 2024-06-23T01:13:10.306Z] Preparing metadata (setup.py): finished with status 'done'
[task 2024-06-23T01:13:10.333Z] Collecting mozterm==1.0.0
[task 2024-06-23T01:13:10.370Z] Downloading mozterm-1.0.0-py2.py3-none-any.whl (3.4 kB)
[task 2024-06-23T01:13:10.403Z] Collecting outcome==1.2.0
[task 2024-06-23T01:13:10.439Z] Downloading outcome-1.2.0-py2.py3-none-any.whl (9.7 kB)
[task 2024-06-23T01:13:10.883Z] Collecting Pillow==10.1.0
[task 2024-06-23T01:13:10.919Z] Downloading Pillow-10.1.0-cp310-cp310-manylinux_2_28_x86_64.whl (3.6 MB)
[task 2024-06-23T01:13:11.081Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 3.6/3.6 MB 22.8 MB/s eta 0:00:00
[task 2024-06-23T01:13:11.132Z] Collecting PySocks==1.7.1
[task 2024-06-23T01:13:11.168Z] Downloading PySocks-1.7.1-py3-none-any.whl (16 kB)
[task 2024-06-23T01:13:11.265Z] Collecting PyYAML==6.0.1
[task 2024-06-23T01:13:11.301Z] Downloading PyYAML-6.0.1-cp310-cp310-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (705 kB)
[task 2024-06-23T01:13:11.316Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 705.5/705.5 KB 64.9 MB/s eta 0:00:00
[task 2024-06-23T01:13:11.392Z] Collecting selenium==4.12.0
[task 2024-06-23T01:13:11.428Z] Downloading selenium-4.12.0-py3-none-any.whl (9.4 MB)
[task 2024-06-23T01:13:11.577Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 9.4/9.4 MB 63.9 MB/s eta 0:00:00
[task 2024-06-23T01:13:11.615Z] Requirement already satisfied: six==1.16.0 in /usr/lib/python3/dist-packages (from -r requirements.txt (line 15)) (1.16.0)
[task 2024-06-23T01:13:11.643Z] Collecting sniffio==1.3.0
[task 2024-06-23T01:13:11.680Z] Downloading sniffio-1.3.0-py3-none-any.whl (10 kB)
[task 2024-06-23T01:13:11.719Z] Collecting sortedcontainers==2.4.0
[task 2024-06-23T01:13:11.755Z] Downloading sortedcontainers-2.4.0-py2.py3-none-any.whl (29 kB)
[task 2024-06-23T01:13:11.800Z] Collecting trio==0.22.2
[task 2024-06-23T01:13:11.837Z] Downloading trio-0.22.2-py3-none-any.whl (400 kB)
[task 2024-06-23T01:13:11.847Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 400.2/400.2 KB 49.8 MB/s eta 0:00:00
[task 2024-06-23T01:13:11.884Z] Collecting trio-websocket==0.10.4
[task 2024-06-23T01:13:11.920Z] Downloading trio_websocket-0.10.4-py3-none-any.whl (17 kB)
[task 2024-06-23T01:13:11.992Z] Collecting urllib3==2.0.5
[task 2024-06-23T01:13:12.028Z] Downloading urllib3-2.0.5-py3-none-any.whl (123 kB)
[task 2024-06-23T01:13:12.043Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 123.8/123.8 KB 13.7 MB/s eta 0:00:00
[task 2024-06-23T01:13:12.071Z] Collecting wcwidth==0.2.13
[task 2024-06-23T01:13:12.108Z] Downloading wcwidth-0.2.13-py2.py3-none-any.whl (34 kB)
[task 2024-06-23T01:13:12.142Z] Collecting wsproto==1.2.0
[task 2024-06-23T01:13:12.179Z] Downloading wsproto-1.2.0-py3-none-any.whl (24 kB)
[task 2024-06-23T01:13:12.282Z] Requirement already satisfied: urllib3[socks]<3,>=1.26 in /usr/lib/python3/dist-packages (from selenium==4.12.0->-r requirements.txt (line 14)) (1.26.5)
[task 2024-06-23T01:13:12.368Z] Collecting urllib3[socks]<3,>=1.26
[task 2024-06-23T01:13:12.503Z] Downloading urllib3-2.2.2-py3-none-any.whl (121 kB)
[task 2024-06-23T01:13:12.510Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 121.4/121.4 KB 22.8 MB/s eta 0:00:00
[task 2024-06-23T01:13:12.561Z] Downloading urllib3-2.2.1-py3-none-any.whl (121 kB)
[task 2024-06-23T01:13:12.569Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 121.1/121.1 KB 19.6 MB/s eta 0:00:00
[task 2024-06-23T01:13:12.619Z] Downloading urllib3-2.2.0-py3-none-any.whl (120 kB)
[task 2024-06-23T01:13:12.626Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 120.9/120.9 KB 22.5 MB/s eta 0:00:00
[task 2024-06-23T01:13:12.676Z] Downloading urllib3-2.1.0-py3-none-any.whl (104 kB)
[task 2024-06-23T01:13:12.684Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 104.6/104.6 KB 20.2 MB/s eta 0:00:00
[task 2024-06-23T01:13:12.732Z] Downloading urllib3-2.0.7-py3-none-any.whl (124 kB)
[task 2024-06-23T01:13:12.740Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 124.2/124.2 KB 22.3 MB/s eta 0:00:00
[task 2024-06-23T01:13:12.791Z] Downloading urllib3-2.0.6-py3-none-any.whl (123 kB)
[task 2024-06-23T01:13:12.798Z] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 123.8/123.8 KB 24.1 MB/s eta 0:00:00
[task 2024-06-23T01:13:12.824Z] Building wheels for collected packages: mozlog
[task 2024-06-23T01:13:12.825Z] Building wheel for mozlog (setup.py): started
[task 2024-06-23T01:13:13.131Z] Building wheel for mozlog (setup.py): finished with status 'done'
[task 2024-06-23T01:13:13.131Z] Created wheel for mozlog: filename=mozlog-8.0.0-py2.py3-none-any.whl size=69608 sha256=917663d6c6efc2d3bc16842e071ff5521fe328cf6133601ff3368f776be36b1e
[task 2024-06-23T01:13:13.131Z] Stored in directory: /home/task_171910498552872/.cache/pip/wheels/f3/04/57/02f3d25fa74ca7a408a80e1b4892033a55c233229f7cc0b858
[task 2024-06-23T01:13:13.132Z] Successfully built mozlog
[task 2024-06-23T01:13:13.413Z] Installing collected packages: wcwidth, sortedcontainers, urllib3, sniffio, PyYAML, PySocks, Pillow, mozterm, mozfile, idna, h11, exceptiongroup, certifi, blessed, attrs, wsproto, outcome, mozlog, trio, trio-websocket, selenium
[task 2024-06-23T01:13:14.194Z] WARNING: The script structlog is installed in '/home/task_171910498552872/.local/bin' which is not on PATH.
[task 2024-06-23T01:13:14.194Z] Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
[task 2024-06-23T01:13:15.248Z] Successfully installed Pillow-10.1.0 PySocks-1.7.1 PyYAML-6.0.1 attrs-23.1.0 blessed-1.20.0 certifi-2023.7.22 exceptiongroup-1.1.3 h11-0.14.0 idna-3.4 mozfile-3.0.0 mozlog-8.0.0 mozterm-1.0.0 outcome-1.2.0 selenium-4.12.0 sniffio-1.3.0 sortedcontainers-2.4.0 trio-0.22.2 trio-websocket-0.10.4 urllib3-2.0.5 wcwidth-0.2.13 wsproto-1.2.0
[task 2024-06-23T01:13:15.369Z] + export TEST_NO_HEADLESS=1
[task 2024-06-23T01:13:15.369Z] + TEST_NO_HEADLESS=1
[task 2024-06-23T01:13:15.369Z] + '[' -n '' ']'
[task 2024-06-23T01:13:15.369Z] + RECORD_SCREEN_PID=0
[task 2024-06-23T01:13:15.369Z] + '[' '' = true ']'
[task 2024-06-23T01:13:15.369Z] + '[' basic = basic ']'
[task 2024-06-23T01:13:15.369Z] + sed -e s/#RUNTIME_VERSION#/128.0b6/#
[task 2024-06-23T01:13:15.371Z] + python3 basic_tests.py basic_tests/expectations.json
[task 2024-06-23T01:15:26.400Z] Traceback (most recent call last):
[task 2024-06-23T01:15:26.400Z] File "/task_171910498552872/fetches/basic_tests.py", line 442, in <module>
[task 2024-06-23T01:15:26.401Z] SnapTests(exp=sys.argv[1])
[task 2024-06-23T01:15:26.401Z] File "/task_171910498552872/fetches/basic_tests.py", line 269, in __init__
[task 2024-06-23T01:15:26.401Z] super(SnapTests, self).__init__(exp)
[task 2024-06-23T01:15:26.401Z] File "/task_171910498552872/fetches/basic_tests.py", line 46, in __init__
[task 2024-06-23T01:15:26.401Z] self._driver = webdriver.Firefox(service=driver_service, options=options)
[task 2024-06-23T01:15:26.401Z] File "/home/task_171910498552872/.local/lib/python3.10/site-packages/selenium/webdriver/firefox/webdriver.py", line 67, in __init__
[task 2024-06-23T01:15:26.401Z] super().__init__(command_executor=executor, options=options)
[task 2024-06-23T01:15:26.401Z] File "/home/task_171910498552872/.local/lib/python3.10/site-packages/selenium/webdriver/remote/webdriver.py", line 205, in __init__
[task 2024-06-23T01:15:26.401Z] self.start_session(capabilities)
[task 2024-06-23T01:15:26.401Z] File "/home/task_171910498552872/.local/lib/python3.10/site-packages/selenium/webdriver/remote/webdriver.py", line 289, in start_session
[task 2024-06-23T01:15:26.402Z] response = self.execute(Command.NEW_SESSION, caps)["value"]
[task 2024-06-23T01:15:26.402Z] File "/home/task_171910498552872/.local/lib/python3.10/site-packages/selenium/webdriver/remote/webdriver.py", line 344, in execute
[task 2024-06-23T01:15:26.402Z] self.error_handler.check_response(response)
[task 2024-06-23T01:15:26.402Z] File "/home/task_171910498552872/.local/lib/python3.10/site-packages/selenium/webdriver/remote/errorhandler.py", line 229, in check_response
[task 2024-06-23T01:15:26.402Z] raise exception_class(message, screen, stacktrace)
[task 2024-06-23T01:15:26.402Z] selenium.common.exceptions.TimeoutException: Message: Failed to read marionette port
[task 2024-06-23T01:15:26.402Z]
[taskcluster 2024-06-23T01:15:26.442Z] Exit Code: 1
[taskcluster 2024-06-23T01:15:26.442Z] User Time: 10.284916s
[taskcluster 2024-06-23T01:15:26.442Z] Kernel Time: 3.549322s
[taskcluster 2024-06-23T01:15:26.442Z] Wall Time: 3m48.625291847s
[taskcluster 2024-06-23T01:15:26.442Z] Result: FAILED
[taskcluster 2024-06-23T01:15:26.442Z] === Task Finished ===
[taskcluster 2024-06-23T01:15:26.442Z] Task Duration: 3m48.626515412s
[taskcluster 2024-06-23T01:15:26.529Z] Uploading artifact public/build/gecko.log.moz_log from file /task_171910498552872/builds/worker/artifacts/gecko.log.moz_log with content encoding "gzip", mime type "application/octet-stream" and expiry 2024-09-21T00:06:53.665Z
[taskcluster 2024-06-23T01:15:26.532Z] Uploading artifact public/build/geckodriver.log from file /task_171910498552872/builds/worker/artifacts/geckodriver.log with content encoding "gzip", mime type "text/plain" and expiry 2024-09-21T00:06:53.665Z
[taskcluster 2024-06-23T01:15:26.791Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2024-09-21T00:06:53.665Z
[taskcluster:error] exit status 1
Description
•