Closed Bug 1920638 Opened 1 year ago Closed 1 year ago

Intermittent dom/promise/tests/test_promise_and_timeout_ordering_workers.html | single tracking bug

Categories

(Core :: DOM: Core & HTML, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=475559723&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Fz_s0f9MTaGSazq0lzMPVQ/runs/0/artifacts/public/logs/live_backing.log


[task 2024-09-24T05:17:31.660Z] 05:17:31     INFO - TEST-START | dom/promise/tests/test_promise_and_timeout_ordering_workers.html
[task 2024-09-24T05:22:58.461Z] 05:22:58     INFO - TEST-INFO | started process screentopng
[task 2024-09-24T05:22:58.582Z] 05:22:58     INFO - TEST-INFO | screentopng: exit 0
[task 2024-09-24T05:22:58.582Z] 05:22:58     INFO - TEST-UNEXPECTED-FAIL | dom/promise/tests/test_promise_and_timeout_ordering_workers.html | Test timed out. - 
[task 2024-09-24T05:23:28.463Z] 05:23:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:23:28.463Z] 05:23:28     INFO - TEST-UNEXPECTED-FAIL | dom/promise/tests/test_promise_and_timeout_ordering_workers.html | Test timed out. - 
[task 2024-09-24T05:23:58.463Z] 05:23:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:23:58.464Z] 05:23:58     INFO - TEST-UNEXPECTED-FAIL | dom/promise/tests/test_promise_and_timeout_ordering_workers.html | Test timed out. - 
[task 2024-09-24T05:24:28.465Z] 05:24:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:24:28.465Z] 05:24:28     INFO - TEST-UNEXPECTED-FAIL | dom/promise/tests/test_promise_and_timeout_ordering_workers.html | Test timed out. - 
[task 2024-09-24T05:24:28.466Z] 05:24:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:24:28.466Z] 05:24:28     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2024-09-24T05:24:28.489Z] 05:24:28     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:24:28.489Z] 05:24:28     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 7 remaining tests. - 
[task 2024-09-24T05:30:38.489Z] 05:30:38     INFO - TEST-UNEXPECTED-TIMEOUT | dom/promise/tests/test_promise_and_timeout_ordering_workers.html | application timed out after 370 seconds with no output
[task 2024-09-24T05:30:38.489Z] 05:30:38     INFO - TEST-INFO took 786342ms
[task 2024-09-24T05:30:38.490Z] 05:30:38     INFO - Buffered messages finished
[task 2024-09-24T05:30:38.490Z] 05:30:38  WARNING - Force-terminating active process(es).
[task 2024-09-24T05:30:38.490Z] 05:30:38     INFO - Attempting to start the profiler to help with diagnosing the hang.
[task 2024-09-24T05:30:38.490Z] 05:30:38     INFO - Sending SIGUSR1 to pid 4494 start the profiler.
[task 2024-09-24T05:30:38.490Z] 05:30:38     INFO - Waiting 10s to capture a profile.
[task 2024-09-24T05:30:48.501Z] 05:30:48     INFO - Sending SIGUSR2 to pid 4494 stop the profiler.
[task 2024-09-24T05:30:48.501Z] 05:30:48     INFO - Wait 10s for Firefox to write the profile to disk.
[task 2024-09-24T05:30:58.510Z] 05:30:58     INFO - Determining child pids from psutil...
[task 2024-09-24T05:30:58.510Z] 05:30:58  WARNING - Failed to lookup children of pid 4494
[task 2024-09-24T05:30:58.510Z] 05:30:58     INFO - ==> process 4494 launched child process 4584
[task 2024-09-24T05:30:58.511Z] 05:30:58     INFO - ==> process 4494 launched child process 4589
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4659
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4660
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4662
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4664
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4665
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4779
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4818
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4819
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4825
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - ==> process 4494 launched child process 4826
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - Found child pids: {4584, 4779, 4589, 4818, 4659, 4660, 4819, 4662, 4825, 4664, 4665, 4826}
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - Failed to get proc for pid 4494
[task 2024-09-24T05:30:58.519Z] 05:30:58     INFO - Failed to get child procs
[task 2024-09-24T05:30:58.521Z] 05:30:58     INFO - Killing process: 4584
[task 2024-09-24T05:30:58.521Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.521Z] 05:30:58     INFO - Killing process: 4779
[task 2024-09-24T05:30:58.521Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.521Z] 05:30:58     INFO - Killing process: 4589
[task 2024-09-24T05:30:58.522Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.523Z] 05:30:58     INFO - Can't trigger Breakpad, process no longer exists
[task 2024-09-24T05:30:58.523Z] 05:30:58     INFO - Killing process: 4818
[task 2024-09-24T05:30:58.523Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.524Z] 05:30:58     INFO - Killing process: 4659
[task 2024-09-24T05:30:58.524Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.531Z] 05:30:58     INFO - Killing process: 4660
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Killing process: 4819
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Killing process: 4662
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Can't trigger Breakpad, process no longer exists
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Killing process: 4825
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Killing process: 4664
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Killing process: 4665
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Killing process: 4826
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Killing process: 4494
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Can't trigger Breakpad, process no longer exists
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - TEST-INFO | Main app process: killed by SIGUSR2
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - Buffered messages finished
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - runtests.py | Application ran for: 0:13:36.650915
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - zombiecheck | Reading PID log: /tmp/tmprwmcvd6lpidlog
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - ==> process 4494 launched child process 4584
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - ==> process 4494 launched child process 4589
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - ==> process 4494 launched child process 4659
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - ==> process 4494 launched child process 4660
[task 2024-09-24T05:30:58.533Z] 05:30:58     INFO - ==> process 4494 launched child process 4662
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - ==> process 4494 launched child process 4664
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - ==> process 4494 launched child process 4665
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - ==> process 4494 launched child process 4779
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - ==> process 4494 launched child process 4818
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - ==> process 4494 launched child process 4819
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - ==> process 4494 launched child process 4825
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - ==> process 4494 launched child process 4826
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4584
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4779
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4589
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4818
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4659
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4660
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4819
[task 2024-09-24T05:30:58.534Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4662
[task 2024-09-24T05:30:58.535Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4825
[task 2024-09-24T05:30:58.535Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4664
[task 2024-09-24T05:30:58.536Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4665
[task 2024-09-24T05:30:58.536Z] 05:30:58     INFO - zombiecheck | Checking for orphan process with PID: 4826
[task 2024-09-24T05:30:58.536Z] 05:30:58     INFO - Stopping web server
[task 2024-09-24T05:30:58.536Z] 05:30:58     INFO - Server shut down.
[task 2024-09-24T05:30:58.536Z] 05:30:58     INFO - Web server killed.
[task 2024-09-24T05:30:58.536Z] 05:30:58     INFO - Stopping web socket server
[task 2024-09-24T05:30:58.536Z] 05:30:58     INFO - Stopping ssltunnel
[task 2024-09-24T05:30:58.536Z] 05:30:58     INFO - Stopping gst for v4l2loopback
[task 2024-09-24T05:30:58.537Z] 05:30:58     INFO - runtests.py | Running tests: end.
[task 2024-09-24T05:30:58.592Z] 05:30:58     INFO - Buffered messages finished
[task 2024-09-24T05:30:58.593Z] 05:30:58     INFO - Running manifest: dom/security/test/https-only/mochitest.toml
[task 2024-09-24T05:30:58.594Z] 05:30:58     INFO - The following extra prefs will be set:
[task 2024-09-24T05:30:58.594Z] 05:30:58     INFO -   dom.security.https_first=false
[task 2024-09-24T05:30:58.594Z] 05:30:58     INFO -   security.mixed_content.upgrade_display_content=false
[task 2024-09-24T05:30:58.644Z] 05:30:58     INFO -  Setting pipeline to PAUSED ...
[task 2024-09-24T05:30:58.644Z] 05:30:58     INFO -  Pipeline is PREROLLING ...
[task 2024-09-24T05:30:58.661Z] 05:30:58     INFO -  Pipeline is PREROLLED ...
[task 2024-09-24T05:30:58.661Z] 05:30:58     INFO -  Setting pipeline to PLAYING ...
[task 2024-09-24T05:30:58.661Z] 05:30:58     INFO -  New clock: GstSystemClock
[task 2024-09-24T05:30:58.694Z] 05:30:58     INFO -  Got EOS from element "pipeline0".
[task 2024-09-24T05:30:58.694Z] 05:30:58     INFO -  Execution ended after 0:00:00.033440549
[task 2024-09-24T05:30:58.695Z] 05:30:58     INFO -  Setting pipeline to PAUSED ...
[task 2024-09-24T05:30:58.695Z] 05:30:58     INFO -  Setting pipeline to READY ...
[task 2024-09-24T05:30:58.695Z] 05:30:58     INFO -  (gst-launch-1.0:4909): GStreamer-CRITICAL **: 05:30:58.694: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2024-09-24T05:30:58.696Z] 05:30:58     INFO -  Setting pipeline to NULL ...
[task 2024-09-24T05:30:58.697Z] 05:30:58     INFO -  Freeing pipeline ...
[task 2024-09-24T05:30:59.099Z] 05:30:59     INFO - PID 4918 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2024-09-24T05:30:59.099Z] 05:30:59     INFO - 
[task 2024-09-24T05:30:59.110Z] 05:30:59     INFO - MochitestServer : launching ['/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-e', "const _PROFILE_PATH = '/tmp/tmpv_foyvjn.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false; const _HTTPD_PATH = '/builds/worker/workspace/build/tests/bin/components';", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2024-09-24T05:30:59.110Z] 05:30:59     INFO - runtests.py | Server pid: 4919
[task 2024-09-24T05:30:59.111Z] 05:30:59     INFO - runtests.py | Websocket server pid: 4920
[task 2024-09-24T05:30:59.113Z] 05:30:59     INFO - runtests.py | SSL tunnel pid: 4921
[task 2024-09-24T05:30:59.190Z] 05:30:59     INFO -  [CodeCoverage] Setting handlers for process 4919.
[task 2024-09-24T05:30:59.264Z] 05:30:59     INFO - use http3 server: 0
[task 2024-09-24T05:30:59.265Z] 05:30:59     INFO - runtests.py | Running with scheme: http
[task 2024-09-24T05:30:59.265Z] 05:30:59     INFO - runtests.py | Running with e10s: True
[task 2024-09-24T05:30:59.266Z] 05:30:59     INFO - runtests.py | Running with fission: True
[task 2024-09-24T05:30:59.267Z] 05:30:59     INFO - runtests.py | Running with cross-origin iframes: True
[task 2024-09-24T05:30:59.267Z] 05:30:59     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2024-09-24T05:30:59.267Z] 05:30:59     INFO - runtests.py | Running tests: start.
[task 2024-09-24T05:30:59.267Z] 05:30:59     INFO - 
[task 2024-09-24T05:30:59.274Z] 05:30:59     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpv_foyvjn.mozrunner
[task 2024-09-24T05:30:59.278Z] 05:30:59     INFO - runtests.py | Application pid: 4952
[task 2024-09-24T05:30:59.279Z] 05:30:59     INFO - TEST-INFO | started process GECKO(4952)
[task 2024-09-24T05:30:59.326Z] 05:30:59     INFO - GECKO(4952) | [CodeCoverage] Setting handlers for process 4952.
[task 2024-09-24T05:30:59.901Z] 05:30:59     INFO - GECKO(4952) | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-09-24T05:30:59.940Z] 05:30:59     INFO - GECKO(4952) | 1727155859939	Marionette	INFO	Marionette enabled
[task 2024-09-24T05:30:59.942Z] 05:30:59     INFO - GECKO(4952) | 1727155859941	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-09-24T05:31:00.020Z] 05:31:00     INFO - GECKO(4952) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2024-09-24T05:31:00.047Z] 05:31:00     INFO - GECKO(4952) | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-09-24T05:31:00.074Z] 05:31:00     INFO - GECKO(4952) | 1727155860073	Marionette	INFO	Listening on port 2828
[task 2024-09-24T05:31:00.077Z] 05:31:00     INFO - GECKO(4952) | 1727155860076	Marionette	DEBUG	Marionette is listening
[task 2024-09-24T05:31:00.120Z] 05:31:00     INFO - GECKO(4952) | 1727155860119	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:51656
[task 2024-09-24T05:31:00.152Z] 05:31:00     INFO - GECKO(4952) | 1727155860151	Marionette	DEBUG	Closed connection 0
[task 2024-09-24T05:31:00.155Z] 05:31:00     INFO - GECKO(4952) | 1727155860152	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:51658
[task 2024-09-24T05:31:00.379Z] 05:31:00     INFO - GECKO(4952) | 1727155860378	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-09-24T05:31:00.385Z] 05:31:00     INFO - GECKO(4952) | 1727155860384	Marionette	DEBUG	Waiting for initial application window
[task 2024-09-24T05:31:01.028Z] 05:31:01     INFO - GECKO(4952) | [CodeCoverage] Setting handlers for process 5040.
[task 2024-09-24T05:31:01.193Z] 05:31:01     INFO - GECKO(4952) | [CodeCoverage] Setting handlers for process 5047.
[task 2024-09-24T05:31:02.607Z] 05:31:02     INFO - GECKO(4952) | console.error: ({})
[task 2024-09-24T05:31:04.314Z] 05:31:04     INFO - GECKO(4952) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T05:31:04.337Z] 05:31:04     INFO - GECKO(4952) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Amo: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T05:31:04.342Z] 05:31:04     INFO - GECKO(4952) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T05:31:04.344Z] 05:31:04     INFO - GECKO(4952) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Amp: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T05:31:04.405Z] 05:31:04     INFO - GECKO(4952) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T05:31:04.407Z] 05:31:04     INFO - GECKO(4952) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Wikipedia: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T05:31:04.482Z] 05:31:04     INFO - GECKO(4952) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T05:31:04.483Z] 05:31:04     INFO - GECKO(4952) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Mdn: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T05:31:04.486Z] 05:31:04     INFO - GECKO(4952) | 1727155864483	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2024-09-24T05:31:04.502Z] 05:31:04     INFO - GECKO(4952) | 1727155864500	RemoteAgent	TRACE	[9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=80000 waitForExplicitStart=false
[task 2024-09-24T05:31:04.504Z] 05:31:04     INFO - GECKO(4952) | 1727155864500	RemoteAgent	TRACE	[9] ProgressListener Setting unload timer (80000ms)
[task 2024-09-24T05:31:04.508Z] 05:31:04     INFO - GECKO(4952) | 1727155864501	RemoteAgent	TRACE	[9] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-09-24T05:31:04.511Z] 05:31:04     INFO - GECKO(4952) | 1727155864501	RemoteAgent	TRACE	[9] Document already finished loading: about:blank
[task 2024-09-24T05:31:04.514Z] 05:31:04     INFO - GECKO(4952) | 1727155864502	RemoteAgent	TRACE	[9] ProgressListener Stop: has error=false url=about:blank
[task 2024-09-24T05:31:04.542Z] 05:31:04     INFO - GECKO(4952) | 1727155864539	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"6164f8a6-dd80-4a66-9bac-a8341f385e38","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"132.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0","moz:buildID":"20240924041126","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":4952,"moz:profile":"/tmp/tmpv_foyvjn.mozrunner","moz:shutdownTimeout":180000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"setWindowRect":true,"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2024-09-24T05:31:04.553Z] 05:31:04     INFO - GECKO(4952) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T05:31:04.563Z] 05:31:04     INFO - GECKO(4952) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Yelp: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T05:31:04.565Z] 05:31:04     INFO - GECKO(4952) | 1727155864564	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpql475yr2.zip","temporary":false}]
[task 2024-09-24T05:31:04.771Z] 05:31:04     INFO - GECKO(4952) | 1727155864770	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2024-09-24T05:31:04.814Z] 05:31:04     INFO - GECKO(4952) | 1727155864813	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpe_m2rgix.zip","temporary":false}]
[task 2024-09-24T05:31:04.898Z] 05:31:04     INFO - GECKO(4952) | 1727155864897	Marionette	DEBUG	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2024-09-24T05:31:04.905Z] 05:31:04     INFO - GECKO(4952) | 1727155864904	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
[task 2024-09-24T05:31:04.907Z] 05:31:04     INFO - GECKO(4952) | 1727155864906	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
[task 2024-09-24T05:31:04.909Z] 05:31:04     INFO - GECKO(4952) | 1727155864908	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-09-24T05:31:04.910Z] 05:31:04     INFO - GECKO(4952) | 1727155864910	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2024-09-24T05:31:04.914Z] 05:31:04     INFO - GECKO(4952) | 1727155864912	Marionette	DEBUG	1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, v. 2.0. If a copy of the MPL was not distr ... s which flavor and url to load.\nlet ev = new CustomEvent(\"mochitest-load\", { detail: [flavor, url] });\nwin.dispatchEvent(ev);","args":[{"flavor":"mochitest","testUrl":"http://mochi.xorigin-test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true&xOriginTests=true&ignorePrefsFile=ignorePrefs.json"}],"newSandbox":true,"sandbox":"default","line":2166,"filename":"tests/mochitest/runtests.py"}]
[task 2024-09-24T05:31:04.926Z] 05:31:04     INFO - GECKO(4952) | 1727155864925	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 4952
[task 2024-09-24T05:31:04.929Z] 05:31:04     INFO - GECKO(4952) | 1727155864928	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2024-09-24T05:31:04.950Z] 05:31:04     INFO - GECKO(4952) | 1727155864950	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2024-09-24T05:31:04.972Z] 05:31:04     INFO - GECKO(4952) | 1727155864971	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2024-09-24T05:31:04.975Z] 05:31:04     INFO - GECKO(4952) | 1727155864973	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2024-09-24T05:31:04.991Z] 05:31:04     INFO - GECKO(4952) | 1727155864989	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2024-09-24T05:31:04.995Z] 05:31:04     INFO - GECKO(4952) | 1727155864994	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2024-09-24T05:31:04.999Z] 05:31:04     INFO - GECKO(4952) | 1727155864998	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2024-09-24T05:31:05.011Z] 05:31:05     INFO - runtests.py | Waiting for browser...
[task 2024-09-24T05:31:05.045Z] 05:31:05     INFO - GECKO(4952) | 1727155865044	Marionette	DEBUG	Closed connection 1
[task 2024-09-24T05:31:05.404Z] 05:31:05     INFO - GECKO(4952) | [CodeCoverage] Setting handlers for process 5121.
[task 2024-09-24T05:31:05.410Z] 05:31:05     INFO - GECKO(4952) | [CodeCoverage] Setting handlers for process 5124.
[task 2024-09-24T05:31:05.480Z] 05:31:05     INFO - GECKO(4952) | [CodeCoverage] Setting handlers for process 5125.
[task 2024-09-24T05:31:05.483Z] 05:31:05     INFO - GECKO(4952) | [CodeCoverage] Setting handlers for process 5126.
[task 2024-09-24T05:31:05.884Z] 05:31:05     INFO - SimpleTest START
[task 2024-09-24T05:31:05.887Z] 05:31:05     INFO - Dumping test context:
[task 2024-09-24T05:31:05.888Z] 05:31:05     INFO -   fission.autostart=true
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.