Closed
Bug 1767405
Opened 3 years ago
Closed 2 years ago
Intermittent editor/spellchecker/tests/test_bug1205983.html | Test timed out. -
Categories
(Core :: DOM: Editor, defect, P5)
Core
DOM: Editor
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=376601972&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FkoGFyqMQFmqXrIEYLhSHA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FkoGFyqMQFmqXrIEYLhSHA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2022-05-03T07:29:58.060Z] 07:29:58 INFO - TEST-PASS | editor/spellchecker/tests/test_bug1205983.html | true expected (de_DE directory should exist)
[task 2022-05-03T07:29:58.060Z] 07:29:58 INFO - Buffered messages finished
[task 2022-05-03T07:29:58.061Z] 07:29:58 INFO - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1205983.html | Test timed out. -
[task 2022-05-03T07:29:58.947Z] 07:29:58 INFO - GECKO(3281) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2022-05-03T07:29:58.948Z] 07:29:58 INFO - GECKO(3281) | MEMORY STAT | vsize 11977MB | residentFast 5393MB | heapAllocated 5252MB
[task 2022-05-03T07:30:24.337Z] 07:30:24 INFO - GECKO(3281) | [2022-05-03T07:30:24Z WARN rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2022-05-03T07:32:24.408Z] 07:32:24 INFO - GECKO(3281) | 1651563144407 addons.xpi ERROR System addon update list error Error: got node name: html, expected: updates
[task 2022-05-03T07:38:34.548Z] 07:38:34 INFO - Buffered messages finished
[task 2022-05-03T07:38:34.548Z] 07:38:34 ERROR - TEST-UNEXPECTED-TIMEOUT | editor/spellchecker/tests/test_bug1205983.html | application timed out after 370 seconds with no output
[task 2022-05-03T07:38:34.549Z] 07:38:34 ERROR - Force-terminating active process(es).
[task 2022-05-03T07:38:34.549Z] 07:38:34 INFO - Determining child pids from psutil...
[task 2022-05-03T07:38:34.551Z] 07:38:34 INFO - [3286, 3298, 3299, 3300, 3305, 3306]
[task 2022-05-03T07:38:34.552Z] 07:38:34 INFO - ==> process 3281 launched child process 3286
[task 2022-05-03T07:38:34.552Z] 07:38:34 INFO - ==> process 3281 launched child process 3293
[task 2022-05-03T07:38:34.553Z] 07:38:34 INFO - ==> process 3281 launched child process 3298
[task 2022-05-03T07:38:34.553Z] 07:38:34 INFO - ==> process 3281 launched child process 3299
[task 2022-05-03T07:38:34.553Z] 07:38:34 INFO - ==> process 3281 launched child process 3300
[task 2022-05-03T07:38:34.554Z] 07:38:34 INFO - ==> process 3281 launched child process 3305
[task 2022-05-03T07:38:34.554Z] 07:38:34 INFO - ==> process 3281 launched child process 3306
[task 2022-05-03T07:38:34.554Z] 07:38:34 INFO - Found child pids: {3298, 3299, 3300, 3305, 3306, 3286, 3293}
[task 2022-05-03T07:38:34.555Z] 07:38:34 INFO - Failed to get child procs
[task 2022-05-03T07:38:34.555Z] 07:38:34 INFO - Killing process: 3298
[task 2022-05-03T07:38:34.555Z] 07:38:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-03T07:38:34.556Z] 07:38:34 INFO - Killing process: 3299
[task 2022-05-03T07:38:34.556Z] 07:38:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-03T07:38:34.556Z] 07:38:34 INFO - Killing process: 3300
[task 2022-05-03T07:38:34.557Z] 07:38:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-03T07:38:34.557Z] 07:38:34 INFO - Killing process: 3305
[task 2022-05-03T07:38:34.558Z] 07:38:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-03T07:38:34.558Z] 07:38:34 INFO - Killing process: 3306
[task 2022-05-03T07:38:34.558Z] 07:38:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-03T07:38:34.559Z] 07:38:34 INFO - Killing process: 3286
[task 2022-05-03T07:38:34.559Z] 07:38:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-03T07:38:34.559Z] 07:38:34 INFO - Killing process: 3293
[task 2022-05-03T07:38:34.560Z] 07:38:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-03T07:38:34.560Z] 07:38:34 INFO - Can't trigger Breakpad, process no longer exists
[task 2022-05-03T07:38:34.560Z] 07:38:34 INFO - Killing process: 3281
[task 2022-05-03T07:38:34.561Z] 07:38:34 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-03T07:38:34.880Z] 07:38:34 INFO - psutil found pid 3281 dead
[task 2022-05-03T07:55:15.136Z] 07:55:15 INFO - Automation Error: mozprocess timed out after 1000 seconds running ['/opt/worker/tasks/task_165155968231989/build/venv/bin/python', '-u', '/opt/worker/tasks/task_165155968231989/build/tests/mochitest/runtests.py', '--setpref=fission.autostart=true', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--appname=/opt/worker/tasks/task_165155968231989/build/application/Firefox NightlyDebug.app/Contents/MacOS/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=/opt/worker/tasks/task_165155968231989/build/symbols', '--certificate-path=tests/certs', '--quiet', '--log-raw=/opt/worker/tasks/task_165155968231989/build/blobber_upload_dir/mochitest-plain-test2_raw.log', '--log-errorsummary=/opt/worker/tasks/task_165155968231989/build/blobber_upload_dir/mochitest-plain-test2_errorsummary.log', '--screenshot-on-fail', '--cleanup-crashes', '--marionette-startup-timeout=180', '--sandbox-read-whitelist=/opt/worker/tasks/task_165155968231989/build', '--log-raw=-', '--chunk-by-dir=4', '--verify-max-time=900', '--verify', 'editor/spellchecker/tests/test_bug1205983.html']
[task 2022-05-03T07:55:15.136Z] 07:55:15 ERROR - timed out after 1000 seconds of no output
[task 2022-05-03T07:55:15.136Z] 07:55:15 ERROR - Return code: -15
[task 2022-05-03T07:55:15.137Z] 07:55:15 ERROR - Got 1 unexpected statuses
[task 2022-05-03T07:55:15.137Z] 07:55:15 INFO - TinderboxPrint: mochitest-mochitest-plain<br/>33/<em class="testfail">1</em>/0
[task 2022-05-03T07:55:15.137Z] 07:55:15 ERROR - # TBPL FAILURE #
[task 2022-05-03T07:55:15.137Z] 07:55:15 WARNING - setting return code to 2
[task 2022-05-03T07:55:15.137Z] 07:55:15 ERROR - TinderboxPrint: Per-test run of .../tests/test_bug1205983.html<br/>: FAILURE
[task 2022-05-03T07:55:15.138Z] 07:55:15 INFO - Running command: ['/opt/worker/tasks/task_165155968231989/build/venv/bin/python', '-u', '/opt/worker/tasks/task_165155968231989/build/tests/mochitest/runtests.py', '--setpref=fission.autostart=true', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--appname=/opt/worker/tasks/task_165155968231989/build/application/Firefox NightlyDebug.app/Contents/MacOS/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=/opt/worker/tasks/task_165155968231989/build/symbols', '--certificate-path=tests/certs', '--quiet', '--log-raw=/opt/worker/tasks/task_165155968231989/build/blobber_upload_dir/mochitest-plain-test3_raw.log', '--log-errorsummary=/opt/worker/tasks/task_165155968231989/build/blobber_upload_dir/mochitest-plain-test3_errorsummary.log', '--screenshot-on-fail', '--cleanup-crashes', '--marionette-startup-timeout=180', '--sandbox-read-whitelist=/opt/worker/tasks/task_165155968231989/build', '--log-raw=-', '--chunk-by-dir=4', '--verify-max-time=900', '--verify', 'dom/base/test/test_window_define_nonconfigurable.html'] in /opt/worker/tasks/task_165155968231989/build
[task 2022-05-03T07:55:15.138Z] 07:55:15 INFO - Copy/paste: /opt/worker/tasks/task_165155968231989/build/venv/bin/python -u /opt/worker/tasks/task_165155968231989/build/tests/mochitest/runtests.py --setpref=fission.autostart=true --setpref=media.peerconnection.mtransport_process=false --setpref=network.process.enabled=false --setpref=layers.d3d11.enable-blacklist=false "--appname=/opt/worker/tasks/task_165155968231989/build/application/Firefox NightlyDebug.app/Contents/MacOS/firefox" --utility-path=tests/bin --extra-profile-file=tests/bin/plugins --symbols-path=/opt/worker/tasks/task_165155968231989/build/symbols --certificate-path=tests/certs --quiet --log-raw=/opt/worker/tasks/task_165155968231989/build/blobber_upload_dir/mochitest-plain-test3_raw.log --log-errorsummary=/opt/worker/tasks/task_165155968231989/build/blobber_upload_dir/mochitest-plain-test3_errorsummary.log --screenshot-on-fail --cleanup-crashes --marionette-startup-timeout=180 --sandbox-read-whitelist=/opt/worker/tasks/task_165155968231989/build --log-raw=- --chunk-by-dir=4 --verify-max-time=900 --verify dom/base/test/test_window_define_nonconfigurable.html
[task 2022-05-03T07:55:15.138Z] 07:55:15 INFO - Using env: (same as previous command)
[task 2022-05-03T07:55:15.138Z] 07:55:15 INFO - Calling ['/opt/worker/tasks/task_165155968231989/build/venv/bin/python', '-u', '/opt/worker/tasks/task_165155968231989/build/tests/mochitest/runtests.py', '--setpref=fission.autostart=true', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--appname=/opt/worker/tasks/task_165155968231989/build/application/Firefox NightlyDebug.app/Contents/MacOS/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=/opt/worker/tasks/task_165155968231989/build/symbols', '--certificate-path=tests/certs', '--quiet', '--log-raw=/opt/worker/tasks/task_165155968231989/build/blobber_upload_dir/mochitest-plain-test3_raw.log', '--log-errorsummary=/opt/worker/tasks/task_165155968231989/build/blobber_upload_dir/mochitest-plain-test3_errorsummary.log', '--screenshot-on-fail', '--cleanup-crashes', '--marionette-startup-timeout=180', '--sandbox-read-whitelist=/opt/worker/tasks/task_165155968231989/build', '--log-raw=-', '--chunk-by-dir=4', '--verify-max-time=900', '--verify', 'dom/base/test/test_window_define_nonconfigurable.html'] with output_timeout 1000
[task 2022-05-03T07:55:15.792Z] 07:55:15 INFO - :::
[task 2022-05-03T07:55:15.793Z] 07:55:15 INFO - ::: Running test verification step "1. Run each test 10 times in one browser."...
[task 2022-05-03T07:55:15.793Z] 07:55:15 INFO - :::
[task 2022-05-03T07:55:15.794Z] 07:55:15 INFO - These variables are available in the mozinfo environment and can be used to skip tests conditionally:
[task 2022-05-03T07:55:15.794Z] 07:55:15 INFO - a11y_checks: False
[task 2022-05-03T07:55:15.794Z] 07:55:15 INFO - apple_catalina: True
[task 2022-05-03T07:55:15.795Z] 07:55:15 INFO - apple_silicon: False
[task 2022-05-03T07:55:15.795Z] 07:55:15 INFO - appname: firefox
[task 2022-05-03T07:55:15.795Z] 07:55:15 INFO - artifact: False
[task 2022-05-03T07:55:15.796Z] 07:55:15 INFO - asan: False
[task 2022-05-03T07:55:15.796Z] 07:55:15 INFO - automation: True
[task 2022-05-03T07:55:15.796Z] 07:55:15 INFO - bin_suffix:
[task 2022-05-03T07:55:15.797Z] 07:55:15 INFO - bits: 64
[task 2022-05-03T07:55:15.797Z] 07:55:15 INFO - buildapp: browser
[task 2022-05-03T07:55:15.797Z] 07:55:15 INFO - buildtype_guess: debug
[task 2022-05-03T07:55:15.797Z] 07:55:15 INFO - cc_type: clang
[task 2022-05-03T07:55:15.798Z] 07:55:15 INFO - ccov: False
[task 2022-05-03T07:55:15.798Z] 07:55:15 INFO - crashreporter: True
[task 2022-05-03T07:55:15.798Z] 07:55:15 INFO - datareporting: True
[task 2022-05-03T07:55:15.799Z] 07:55:15 INFO - debug: True
[task 2022-05-03T07:55:15.799Z] 07:55:15 INFO - devedition: False
[task 2022-05-03T07:55:15.799Z] 07:55:15 INFO - domstreams: True
[task 2022-05-03T07:55:15.800Z] 07:55:15 INFO - e10s: True
[task 2022-05-03T07:55:15.800Z] 07:55:15 INFO - early_beta_or_earlier: True
[task 2022-05-03T07:55:15.800Z] 07:55:15 INFO - fission: True
[task 2022-05-03T07:55:15.800Z] 07:55:15 INFO - has_sandbox: True
[task 2022-05-03T07:55:15.801Z] 07:55:15 INFO - headless: False
[task 2022-05-03T07:55:15.801Z] 07:55:15 INFO - healthreport: True
[task 2022-05-03T07:55:15.801Z] 07:55:15 INFO - isolated_process: False
[task 2022-05-03T07:55:15.802Z] 07:55:15 INFO - mozconfig: /builds/worker/checkouts/gecko/.mozconfig
[task 2022-05-03T07:55:15.802Z] 07:55:15 INFO - nightly_build: True
[task 2022-05-03T07:55:15.802Z] 07:55:15 INFO - non_native_theme: True
[task 2022-05-03T07:55:15.803Z] 07:55:15 INFO - normandy: True
[task 2022-05-03T07:55:15.803Z] 07:55:15 INFO - official: True
[task 2022-05-03T07:55:15.803Z] 07:55:15 INFO - os: mac
[task 2022-05-03T07:55:15.804Z] 07:55:15 INFO - os_version: 10.15
[task 2022-05-03T07:55:15.804Z] 07:55:15 INFO - pgo: False
[task 2022-05-03T07:55:15.804Z] 07:55:15 INFO - platform_guess: macosx64
[task 2022-05-03T07:55:15.804Z] 07:55:15 INFO - processor: x86_64
[task 2022-05-03T07:55:15.805Z] 07:55:15 INFO - release_or_beta: False
[task 2022-05-03T07:55:15.805Z] 07:55:15 INFO - require_signing: False
[task 2022-05-03T07:55:15.805Z] 07:55:15 INFO - serviceworker_e10s: True
[task 2022-05-03T07:55:15.806Z] 07:55:15 INFO - sessionHistoryInParent: True
[task 2022-05-03T07:55:15.806Z] 07:55:15 INFO - socketprocess_e10s: False
[task 2022-05-03T07:55:15.806Z] 07:55:15 INFO - socketprocess_networking: False
[task 2022-05-03T07:55:15.807Z] 07:55:15 INFO - stylo: True
[task 2022-05-03T07:55:15.807Z] 07:55:15 INFO - swgl: False
[task 2022-05-03T07:55:15.807Z] 07:55:15 INFO - sync: True
[task 2022-05-03T07:55:15.808Z] 07:55:15 INFO - telemetry: False
[task 2022-05-03T07:55:15.808Z] 07:55:15 INFO - tests_enabled: True
[task 2022-05-03T07:55:15.808Z] 07:55:15 INFO - toolkit: cocoa
[task 2022-05-03T07:55:15.809Z] 07:55:15 INFO - topsrcdir: /builds/worker/checkouts/gecko
[task 2022-05-03T07:55:15.809Z] 07:55:15 INFO - tsan: False
[task 2022-05-03T07:55:15.809Z] 07:55:15 INFO - ubsan: False
[task 2022-05-03T07:55:15.810Z] 07:55:15 INFO - updater: True
[task 2022-05-03T07:55:15.810Z] 07:55:15 INFO - verify: True
[task 2022-05-03T07:55:15.810Z] 07:55:15 INFO - verify_fission: False
[task 2022-05-03T07:55:15.811Z] 07:55:15 INFO - version: OS X 10.15.7
[task 2022-05-03T07:55:15.811Z] 07:55:15 INFO - webgl_ipc: False
[task 2022-05-03T07:55:15.811Z] 07:55:15 INFO - win10_2004: False
[task 2022-05-03T07:55:15.811Z] 07:55:15 INFO - xorigin: False
[task 2022-05-03T07:55:15.812Z] 07:55:15 INFO - Checking for ssltunnel processes...
[task 2022-05-03T07:55:15.812Z] 07:55:15 INFO - killing {'pid': 3280, 'username': 'cltbld', 'ppid': 1, 'name': 'ssltunnel'}
[task 2022-05-03T07:55:15.812Z] 07:55:15 INFO - psutil found pid 3280 dead
[task 2022-05-03T07:55:15.812Z] 07:55:15 INFO - Checking for xpcshell processes...
[task 2022-05-03T07:55:15.817Z] 07:55:15 INFO - killing {'pid': 3278, 'username': 'cltbld', 'ppid': 1, 'name': 'xpcshell'}
[task 2022-05-03T07:55:15.824Z] 07:55:15 INFO - psutil found pid 3278 dead
[task 2022-05-03T07:55:15.826Z] 07:55:15 INFO - mozcrash Removed pending crash reports at '/Users/cltbld/Library/Application Support/firefox/Crash Reports'
[task 2022-05-03T07:55:16.426Z] 07:55:16 INFO - SUITE-START | Running 1 tests
[task 2022-05-03T07:55:16.426Z] 07:55:16 INFO - Running manifest: dom/base/test/mochitest.ini
[task 2022-05-03T07:55:16.426Z] 07:55:16 INFO - The following extra prefs will be set:
[task 2022-05-03T07:55:16.426Z] 07:55:16 INFO - formhelper.autozoom.force-disable.test-only=true
[task 2022-05-03T07:55:17.024Z] 07:55:17 INFO - PID 4359 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2022-05-03T07:55:17.192Z] 07:55:17 INFO - MochitestServer : launching ['/opt/worker/tasks/task_165155968231989/build/tests/bin/xpcshell', '-g', '/opt/worker/tasks/task_165155968231989/build/application/Firefox NightlyDebug.app/Contents/Resources', '-f', '/opt/worker/tasks/task_165155968231989/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/opt/worker/tasks/task_165155968231989/build/tests/mochitest/server.js']
[task 2022-05-03T07:55:17.192Z] 07:55:17 INFO - runtests.py | Server pid: 4360
[task 2022-05-03T07:55:17.195Z] 07:55:17 INFO - runtests.py | Websocket server pid: 4361
[task 2022-05-03T07:55:17.199Z] 07:55:17 INFO - runtests.py | SSL tunnel pid: 4362
[task 2022-05-03T07:55:17.422Z] 07:55:17 INFO - Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2022-05-03T07:55:17.422Z] 07:55:17 INFO - [Parent 4360, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:413
[task 2022-05-03T07:55:17.482Z] 07:55:17 INFO - runtests.py | Running with scheme: http
[task 2022-05-03T07:55:17.483Z] 07:55:17 INFO - runtests.py | Running with e10s: True
[task 2022-05-03T07:55:17.483Z] 07:55:17 INFO - runtests.py | Running with fission: True
[task 2022-05-03T07:55:17.484Z] 07:55:17 INFO - runtests.py | Running with cross-origin iframes: False
[task 2022-05-03T07:55:17.484Z] 07:55:17 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2022-05-03T07:55:17.484Z] 07:55:17 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2022-05-03T07:55:17.485Z] 07:55:17 INFO - runtests.py | Running tests: start.
[task 2022-05-03T07:55:17.485Z] 07:55:17 INFO -
[task 2022-05-03T07:55:17.493Z] 07:55:17 INFO - Application command: /opt/worker/tasks/task_165155968231989/build/application/Firefox NightlyDebug.app/Contents/MacOS/firefox -marionette -foreground -profile /var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner
[task 2022-05-03T07:55:17.497Z] 07:55:17 INFO - runtests.py | Application pid: 4363
[task 2022-05-03T07:55:17.498Z] 07:55:17 INFO - TEST-INFO | started process GECKO(4363)
[task 2022-05-03T07:55:17.553Z] 07:55:17 INFO - GECKO(4363) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner/runtests_leaks.log
[task 2022-05-03T07:55:17.554Z] 07:55:17 INFO - GECKO(4363) | [4363, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-03T07:55:17.555Z] 07:55:17 INFO - GECKO(4363) | [4363, RegisterFonts] WARNING: Called GetMainThread but there isn't a main thread and we're not the main thread.: file /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:575
[task 2022-05-03T07:55:17.556Z] 07:55:17 INFO - GECKO(4363) | [4363, RegisterFonts] WARNING: Called GetMainThread but there isn't a main thread and we're not the main thread.: file /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:575
[task 2022-05-03T07:55:17.618Z] 07:55:17 INFO - GECKO(4363) | [Parent 4363, Main Thread] WARNING: Rejected attempt to change type of pref extensions.formautofill.creditCards.available's user value from bool to string: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:1721
[task 2022-05-03T07:55:18.057Z] 07:55:18 INFO - GECKO(4363) | [2022-05-03T07:55:18Z WARN rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2022-05-03T07:55:18.340Z] 07:55:18 INFO - GECKO(4363) | 1651564518340 Marionette INFO Marionette enabled
[task 2022-05-03T07:55:18.346Z] 07:55:18 INFO - GECKO(4363) | GL_VENDOR: Intel Inc.
[task 2022-05-03T07:55:18.346Z] 07:55:18 INFO - GECKO(4363) | mVendor: Intel
[task 2022-05-03T07:55:18.346Z] 07:55:18 INFO - GECKO(4363) | GL_RENDERER: Intel(R) UHD Graphics 630
[task 2022-05-03T07:55:18.347Z] 07:55:18 INFO - GECKO(4363) | mRenderer: Unknown
[task 2022-05-03T07:55:18.347Z] 07:55:18 INFO - GECKO(4363) | mIsMesa: 0
[task 2022-05-03T07:55:18.473Z] 07:55:18 INFO - GECKO(4363) | 1651564518472 Marionette TRACE Received observer notification toplevel-window-ready
[task 2022-05-03T07:55:19.095Z] 07:55:19 INFO - GECKO(4363) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner/runtests_leaks_tab_pid4365.log
[task 2022-05-03T07:55:19.095Z] 07:55:19 INFO - GECKO(4363) | [4365, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-03T07:55:19.099Z] 07:55:19 INFO - GECKO(4363) | [Parent 4363, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1727
[task 2022-05-03T07:55:19.208Z] 07:55:19 INFO - GECKO(4363) | [Child 4365, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-03T07:55:19.219Z] 07:55:19 INFO - GECKO(4363) | [Child 4365, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-03T07:55:19.224Z] 07:55:19 INFO - GECKO(4363) | [Child 4365, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-03T07:55:19.229Z] 07:55:19 INFO - GECKO(4363) | [Child 4365, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-03T07:55:19.235Z] 07:55:19 INFO - GECKO(4363) | [Child 4365, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-03T07:55:19.255Z] 07:55:19 INFO - GECKO(4363) | [2022-05-03T07:55:19Z WARN webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2022-05-03T07:55:19.296Z] 07:55:19 INFO - GECKO(4363) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner/runtests_leaks_tab_pid4366.log
[task 2022-05-03T07:55:19.297Z] 07:55:19 INFO - GECKO(4363) | [4366, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-03T07:55:19.795Z] 07:55:19 INFO - GECKO(4363) | [Parent 4363, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1601
[task 2022-05-03T07:55:19.945Z] 07:55:19 INFO - GECKO(4363) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner/search.json.mozlz4", (void 0)))
[task 2022-05-03T07:55:19.948Z] 07:55:19 INFO - GECKO(4363) | [2022-05-03T07:55:19Z WARN webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-03T07:55:19.948Z] 07:55:19 INFO - GECKO(4363) | [2022-05-03T07:55:19Z WARN webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-03T07:55:19.949Z] 07:55:19 INFO - GECKO(4363) | [2022-05-03T07:55:19Z WARN webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-03T07:55:20.141Z] 07:55:20 INFO - GECKO(4363) | [Child 4365, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-03T07:55:20.460Z] 07:55:20 INFO - GECKO(4363) | [Child 4365, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-03T07:55:20.460Z] 07:55:20 INFO - GECKO(4363) | [Child 4365, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-03T07:55:20.791Z] 07:55:20 INFO - GECKO(4363) | [Parent 4363, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/ThirdPartyUtil.cpp:421
[task 2022-05-03T07:55:20.792Z] 07:55:20 INFO - GECKO(4363) | [Parent 4363, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1234
[task 2022-05-03T07:55:21.354Z] 07:55:21 INFO - GECKO(4363) | 1651564521353 Marionette TRACE Received observer notification marionette-startup-requested
[task 2022-05-03T07:55:21.354Z] 07:55:21 INFO - GECKO(4363) | 1651564521353 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2022-05-03T07:55:21.369Z] 07:55:21 INFO - GECKO(4363) | 1651564521368 Marionette TRACE All scripts recorded.
[task 2022-05-03T07:55:21.371Z] 07:55:21 INFO - GECKO(4363) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner/runtests_leaks_tab_pid4367.log
[task 2022-05-03T07:55:21.371Z] 07:55:21 INFO - GECKO(4363) | [4367, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-03T07:55:21.372Z] 07:55:21 INFO - GECKO(4363) | 1651564521371 Marionette INFO Listening on port 2828
[task 2022-05-03T07:55:21.372Z] 07:55:21 INFO - GECKO(4363) | 1651564521371 Marionette DEBUG Marionette is listening
[task 2022-05-03T07:55:21.388Z] 07:55:21 INFO - GECKO(4363) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner/runtests_leaks_tab_pid4368.log
[task 2022-05-03T07:55:21.388Z] 07:55:21 INFO - GECKO(4363) | [4368, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-03T07:55:21.430Z] 07:55:21 INFO - GECKO(4363) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner/runtests_leaks_tab_pid4369.log
[task 2022-05-03T07:55:21.430Z] 07:55:21 INFO - GECKO(4363) | [4369, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-03T07:55:21.520Z] 07:55:21 INFO - GECKO(4363) | 1651564521518 Marionette DEBUG Accepted connection 0 from 127.0.0.1:51466
[task 2022-05-03T07:55:21.527Z] 07:55:21 INFO - GECKO(4363) | 1651564521525 Marionette DEBUG Closed connection 0
[task 2022-05-03T07:55:21.528Z] 07:55:21 INFO - GECKO(4363) | 1651564521526 Marionette DEBUG Accepted connection 1 from 127.0.0.1:51467
[task 2022-05-03T07:55:21.536Z] 07:55:21 INFO - GECKO(4363) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpk904jbt_.mozrunner/runtests_leaks_tab_pid4370.log
[task 2022-05-03T07:55:21.537Z] 07:55:21 INFO - GECKO(4363) | [4370, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-05-03T07:55:21.541Z] 07:55:21 INFO - GECKO(4363) | 1651564521540 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-05-03T07:55:21.565Z] 07:55:21 INFO - GECKO(4363) | 1651564521564 RemoteAgent TRACE [25] Document already finished loading: about:blank
[task 2022-05-03T07:55:21.576Z] 07:55:21 INFO - GECKO(4363) | 1651564521574 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"a49d1499-939f-4679-92e5-08c23738499e","capabilities":{"browserName":"firefox","browserVersion":"102.0 ... _.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2022-05-03T07:55:21.606Z] 07:55:21 INFO - GECKO(4363) | 1651564521605 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmpt2rmw0he.zip","temporary":false}]
[task 2022-05-03T07:55:21.704Z] 07:55:21 INFO - GECKO(4363) | 1651564521703 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2022-05-03T07:55:21.737Z] 07:55:21 INFO - GECKO(4363) | 1651564521736 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/var/folders/8d/27zjr6tj7t76grr015qgfvwr000014/T/tmp8gpicetl.zip","temporary":false}]
[task 2022-05-03T07:55:21.781Z] 07:55:21 INFO - GECKO(4363) | 1651564521779 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2022-05-03T07:55:21.785Z] 07:55:21 INFO - GECKO(4363) | 1651564521784 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2022-05-03T07:55:21.786Z] 07:55:21 INFO - GECKO(4363) | 1651564521785 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2022-05-03T07:55:21.788Z] 07:55:21 INFO - GECKO(4363) | 1651564521787 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-05-03T07:55:21.789Z] 07:55:21 INFO - GECKO(4363) | 1651564521788 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2022-05-03T07:55:21.793Z] 07:55:21 INFO - GECKO(4363) | 1651564521792 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... 00014%2FT&cleanupCrashes=true"}],"newSandbox":true,"sandbox":"default","line":2007,"filename":"tests/mochitest/runtests.py"}]
[task 2022-05-03T07:55:21.800Z] 07:55:21 INFO - GECKO(4363) | 1651564521799 Marionette TRACE [9] MarionetteCommands actor created for window id 4
[task 2022-05-03T07:55:21.809Z] 07:55:21 INFO - GECKO(4363) | 1651564521808 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2022-05-03T07:55:21.829Z] 07:55:21 INFO - GECKO(4363) | 1651564521827 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-03T07:55:21.829Z] 07:55:21 INFO - GECKO(4363) | 1651564521828 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2022-05-03T07:55:21.841Z] 07:55:21 INFO - GECKO(4363) | 1651564521840 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2022-05-03T07:55:21.845Z] 07:55:21 INFO - GECKO(4363) | 1651564521844 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2022-05-03T07:55:21.858Z] 07:55:21 INFO - runtests.py | Waiting for browser...
[task 2022-05-03T07:55:21.878Z] 07:55:21 INFO - GECKO(4363) | 1651564521877 Marionette DEBUG Closed connection 1
[task 2022-05-03T07:55:22.184Z] 07:55:22 INFO - GECKO(4363) | [Parent 4363, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1098
[task 2022-05-03T07:55:22.227Z] 07:55:22 INFO - SimpleTest START
[task 2022-05-03T07:55:22.228Z] 07:55:22 INFO - Dumping test context:
[task 2022-05-03T07:55:22.228Z] 07:55:22 INFO - fission.autostart=true
[task 2022-05-03T07:55:22.235Z] 07:55:22 INFO - TEST-START | dom/base/test/test_window_define_nonconfigurable.html
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
|
||
Updated•2 years ago
|
Summary: Intermittent TV editor/spellchecker/tests/test_bug1205983.html | Test timed out. - → Intermittent editor/spellchecker/tests/test_bug1205983.html | Test timed out. -
|
Reporter | |
Comment 3•2 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=407904361&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 5•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 3 years ago → 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•