Closed
Bug 1916046
Opened 1 year ago
Closed 1 year ago
Intermittent [tier 2] docshell/test/mochitest/test_bug1121701.html | single tracking bug
Categories
(Core :: DOM: Navigation, defect, P5)
Core
DOM: Navigation
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: tszentpeteri [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=472443273&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MZTCB0cER6atMorC2tbCOA/runs/0/artifacts/public/logs/live_backing.log
[task 2024-08-31T10:32:00.890Z] 10:32:00 INFO - TEST-START | docshell/test/mochitest/test_bug1121701.html
[task 2024-08-31T10:32:16.789Z] 10:32:16 INFO - GECKO(1102) | [Parent 1102, Main Thread] WARNING: Failed to create DBus proxy for org.a11y.Bus: Error calling StartServiceByName for org.a11y.Bus: Timeout was reached
[task 2024-08-31T10:32:16.790Z] 10:32:16 INFO - GECKO(1102) | : 'glib warning', file /builds/worker/checkouts/gecko/toolkit/xre/nsSigHandlers.cpp:187
[task 2024-08-31T10:32:16.790Z] 10:32:16 INFO - GECKO(1102) | ** (firefox-default:1102): WARNING **: 10:32:16.788: Failed to create DBus proxy for org.a11y.Bus: Error calling StartServiceByName for org.a11y.Bus: Timeout was reached
[task 2024-08-31T10:37:27.616Z] 10:37:27 INFO - TEST-INFO | started process screentopng
[task 2024-08-31T10:37:27.741Z] 10:37:27 INFO - TEST-INFO | screentopng: exit 0
[task 2024-08-31T10:37:27.741Z] 10:37:27 INFO - TEST-UNEXPECTED-FAIL | docshell/test/mochitest/test_bug1121701.html | Test timed out. -
[task 2024-08-31T10:37:57.619Z] 10:37:57 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:37:57.619Z] 10:37:57 INFO - TEST-UNEXPECTED-FAIL | docshell/test/mochitest/test_bug1121701.html | Test timed out. -
[task 2024-08-31T10:38:27.619Z] 10:38:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:38:27.620Z] 10:38:27 INFO - TEST-UNEXPECTED-FAIL | docshell/test/mochitest/test_bug1121701.html | Test timed out. -
[task 2024-08-31T10:38:57.621Z] 10:38:57 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:38:57.622Z] 10:38:57 INFO - TEST-UNEXPECTED-FAIL | docshell/test/mochitest/test_bug1121701.html | Test timed out. -
[task 2024-08-31T10:38:57.623Z] 10:38:57 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:38:57.623Z] 10:38:57 INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. -
[task 2024-08-31T10:38:57.648Z] 10:38:57 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:38:57.649Z] 10:38:57 INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 63 remaining tests. -
[task 2024-08-31T10:45:07.649Z] 10:45:07 INFO - TEST-UNEXPECTED-TIMEOUT | docshell/test/mochitest/test_bug1121701.html | application timed out after 370 seconds with no output
[task 2024-08-31T10:45:07.649Z] 10:45:07 INFO - TEST-INFO took 786111ms
[task 2024-08-31T10:45:07.649Z] 10:45:07 INFO - Buffered messages finished
[task 2024-08-31T10:45:07.649Z] 10:45:07 WARNING - Force-terminating active process(es).
[task 2024-08-31T10:45:07.649Z] 10:45:07 INFO - Attempting to start the profiler to help with diagnosing the hang.
[task 2024-08-31T10:45:07.650Z] 10:45:07 INFO - Sending SIGUSR1 to pid 1102 start the profiler.
[task 2024-08-31T10:45:07.650Z] 10:45:07 INFO - Waiting 10s to capture a profile.
[task 2024-08-31T10:45:17.659Z] 10:45:17 INFO - Sending SIGUSR2 to pid 1102 stop the profiler.
[task 2024-08-31T10:45:17.659Z] 10:45:17 INFO - Wait 10s for Firefox to write the profile to disk.
[task 2024-08-31T10:45:27.669Z] 10:45:27 INFO - Determining child pids from psutil...
[task 2024-08-31T10:45:27.670Z] 10:45:27 WARNING - Failed to lookup children of pid 1102
[task 2024-08-31T10:45:27.681Z] 10:45:27 INFO - ==> process 1102 launched child process 1216
[task 2024-08-31T10:45:27.683Z] 10:45:27 INFO - ==> process 1102 launched child process 1231
[task 2024-08-31T10:45:27.684Z] 10:45:27 INFO - ==> process 1102 launched child process 1327
[task 2024-08-31T10:45:27.684Z] 10:45:27 INFO - ==> process 1102 launched child process 1329
[task 2024-08-31T10:45:27.685Z] 10:45:27 INFO - ==> process 1102 launched child process 1331
[task 2024-08-31T10:45:27.686Z] 10:45:27 INFO - ==> process 1102 launched child process 1332
[task 2024-08-31T10:45:27.687Z] 10:45:27 INFO - ==> process 1102 launched child process 1333
[task 2024-08-31T10:45:27.687Z] 10:45:27 INFO - ==> process 1102 launched child process 1451
[task 2024-08-31T10:45:27.688Z] 10:45:27 INFO - ==> process 1102 launched child process 1486
[task 2024-08-31T10:45:27.689Z] 10:45:27 INFO - ==> process 1102 launched child process 1487
[task 2024-08-31T10:45:27.690Z] 10:45:27 INFO - ==> process 1102 launched child process 1495
[task 2024-08-31T10:45:27.690Z] 10:45:27 INFO - ==> process 1102 launched child process 1496
[task 2024-08-31T10:45:27.691Z] 10:45:27 INFO - Found child pids: {1216, 1451, 1486, 1327, 1231, 1329, 1487, 1331, 1332, 1333, 1495, 1496}
[task 2024-08-31T10:45:27.692Z] 10:45:27 INFO - Failed to get proc for pid 1102
[task 2024-08-31T10:45:27.692Z] 10:45:27 INFO - Failed to get child procs
[task 2024-08-31T10:45:27.693Z] 10:45:27 INFO - Killing process: 1216
[task 2024-08-31T10:45:27.698Z] 10:45:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:45:27.699Z] 10:45:27 INFO - Killing process: 1451
[task 2024-08-31T10:45:27.699Z] 10:45:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Killing process: 1486
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Killing process: 1327
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Killing process: 1231
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Can't trigger Breakpad, process no longer exists
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Killing process: 1329
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Killing process: 1487
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Killing process: 1331
[task 2024-08-31T10:45:27.700Z] 10:45:27 INFO - Not taking screenshot here: see the one that was previously logged
<...>
[task 2024-08-31T10:45:29.089Z] 10:45:29 INFO - TEST-INFO | started process GECKO(1628)
[task 2024-08-31T10:45:29.137Z] 10:45:29 INFO - GECKO(1628) | [CodeCoverage] Setting handlers for process 1628.
[task 2024-08-31T10:45:29.727Z] 10:45:29 INFO - GECKO(1628) | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-08-31T10:45:29.765Z] 10:45:29 INFO - GECKO(1628) | 1725101129764 Marionette INFO Marionette enabled
[task 2024-08-31T10:45:29.767Z] 10:45:29 INFO - GECKO(1628) | 1725101129766 Marionette TRACE Received observer notification final-ui-startup
[task 2024-08-31T10:45:29.852Z] 10:45:29 INFO - GECKO(1628) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2024-08-31T10:45:29.882Z] 10:45:29 INFO - GECKO(1628) | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-08-31T10:45:29.910Z] 10:45:29 INFO - GECKO(1628) | 1725101129909 Marionette INFO Listening on port 2828
[task 2024-08-31T10:45:29.927Z] 10:45:29 INFO - GECKO(1628) | 1725101129926 Marionette DEBUG Marionette is listening
[task 2024-08-31T10:45:29.948Z] 10:45:29 INFO - GECKO(1628) | 1725101129947 Marionette DEBUG Accepted connection 0 from 127.0.0.1:44366
[task 2024-08-31T10:45:29.978Z] 10:45:29 INFO - GECKO(1628) | 1725101129977 Marionette DEBUG Closed connection 0
[task 2024-08-31T10:45:29.979Z] 10:45:29 INFO - GECKO(1628) | 1725101129978 Marionette DEBUG Accepted connection 1 from 127.0.0.1:44368
[task 2024-08-31T10:45:30.215Z] 10:45:30 INFO - GECKO(1628) | 1725101130214 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-08-31T10:45:30.222Z] 10:45:30 INFO - GECKO(1628) | 1725101130221 Marionette DEBUG Waiting for initial application window
[task 2024-08-31T10:45:30.890Z] 10:45:30 INFO - GECKO(1628) | [CodeCoverage] Setting handlers for process 1719.
[task 2024-08-31T10:45:31.221Z] 10:45:31 INFO - GECKO(1628) | [CodeCoverage] Setting handlers for process 1724.
[task 2024-08-31T10:45:32.635Z] 10:45:32 INFO - GECKO(1628) | console.error: ({})
[task 2024-08-31T10:45:34.370Z] 10:45:34 INFO - GECKO(1628) | 1725101134369 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2024-08-31T10:45:34.423Z] 10:45:34 INFO - GECKO(1628) | 1725101134421 RemoteAgent TRACE [9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=80000 waitForExplicitStart=false
[task 2024-08-31T10:45:34.423Z] 10:45:34 INFO - GECKO(1628) | 1725101134422 RemoteAgent TRACE [9] ProgressListener Setting unload timer (80000ms)
[task 2024-08-31T10:45:34.425Z] 10:45:34 INFO - GECKO(1628) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-31T10:45:34.426Z] 10:45:34 INFO - GECKO(1628) | 1725101134425 RemoteAgent TRACE [9] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-08-31T10:45:34.440Z] 10:45:34 INFO - GECKO(1628) | 1725101134440 RemoteAgent TRACE [9] Document already finished loading: about:blank
[task 2024-08-31T10:45:34.441Z] 10:45:34 INFO - GECKO(1628) | 1725101134441 RemoteAgent TRACE [9] ProgressListener Stop: has error=false url=about:blank
[task 2024-08-31T10:45:34.503Z] 10:45:34 INFO - GECKO(1628) | 1725101134500 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"f7c1283b-385d-4d6d-bf55-34c4cf728b04","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"131.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:131.0) Gecko/20100101 Firefox/131.0","moz:buildID":"20240831093030","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":1628,"moz:profile":"/tmp/tmp1i8ffhc8.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-08-31T10:45:34.528Z] 10:45:34 INFO - GECKO(1628) | 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-08-31T10:45:34.552Z] 10:45:34 INFO - GECKO(1628) | 1725101134551 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpllti40jv.zip","temporary":false}]
[task 2024-08-31T10:45:34.646Z] 10:45:34 INFO - GECKO(1628) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-31T10:45:34.647Z] 10:45:34 INFO - GECKO(1628) | 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-08-31T10:45:34.647Z] 10:45:34 INFO - GECKO(1628) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-31T10:45:34.648Z] 10:45:34 INFO - GECKO(1628) | 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-08-31T10:45:34.649Z] 10:45:34 INFO - GECKO(1628) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-31T10:45:34.650Z] 10:45:34 INFO - GECKO(1628) | 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-08-31T10:45:34.651Z] 10:45:34 INFO - GECKO(1628) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-31T10:45:34.651Z] 10:45:34 INFO - GECKO(1628) | 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-08-31T10:45:34.791Z] 10:45:34 INFO - GECKO(1628) | 1725101134790 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2024-08-31T10:45:34.833Z] 10:45:34 INFO - GECKO(1628) | 1725101134829 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpeue7plc_.zip","temporary":false}]
[task 2024-08-31T10:45:34.927Z] 10:45:34 INFO - GECKO(1628) | 1725101134926 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2024-08-31T10:45:34.946Z] 10:45:34 INFO - GECKO(1628) | 1725101134945 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2024-08-31T10:45:34.947Z] 10:45:34 INFO - GECKO(1628) | 1725101134945 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2024-08-31T10:45:34.951Z] 10:45:34 INFO - GECKO(1628) | 1725101134949 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-08-31T10:45:34.951Z] 10:45:34 INFO - GECKO(1628) | 1725101134950 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2024-08-31T10:45:34.954Z] 10:45:34 INFO - GECKO(1628) | 1725101134953 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":2165,"filename":"tests/mochitest/runtests.py"}]
[task 2024-08-31T10:45:34.982Z] 10:45:34 INFO - GECKO(1628) | 1725101134982 RemoteAgent TRACE WebDriverProcessData actor created for PID 1628
[task 2024-08-31T10:45:35.002Z] 10:45:35 INFO - GECKO(1628) | 1725101135001 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2024-08-31T10:45:35.036Z] 10:45:35 INFO - GECKO(1628) | 1725101135035 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2024-08-31T10:45:35.090Z] 10:45:35 INFO - GECKO(1628) | 1725101135089 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2024-08-31T10:45:35.091Z] 10:45:35 INFO - GECKO(1628) | 1725101135090 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2024-08-31T10:45:35.130Z] 10:45:35 INFO - GECKO(1628) | 1725101135129 Marionette TRACE Remoteness change detected. Set new top-level browsing context to 13
[task 2024-08-31T10:45:35.169Z] 10:45:35 INFO - GECKO(1628) | 1725101135168 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2024-08-31T10:45:35.170Z] 10:45:35 INFO - GECKO(1628) | 1725101135169 Marionette TRACE [1] MarionetteCommands actor destroyed for window id 2
[task 2024-08-31T10:45:35.175Z] 10:45:35 INFO - GECKO(1628) | 1725101135174 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2024-08-31T10:45:35.182Z] 10:45:35 INFO - runtests.py | Waiting for browser...
[task 2024-08-31T10:45:35.189Z] 10:45:35 INFO - GECKO(1628) | 1725101135188 Marionette DEBUG Closed connection 1
[task 2024-08-31T10:45:35.498Z] 10:45:35 INFO - GECKO(1628) | [CodeCoverage] Setting handlers for process 1801.
[task 2024-08-31T10:45:35.569Z] 10:45:35 INFO - GECKO(1628) | [CodeCoverage] Setting handlers for process 1803.
[task 2024-08-31T10:45:35.625Z] 10:45:35 INFO - GECKO(1628) | [CodeCoverage] Setting handlers for process 1806.
[task 2024-08-31T10:45:35.732Z] 10:45:35 INFO - GECKO(1628) | [CodeCoverage] Setting handlers for process 1805.
[task 2024-08-31T10:45:36.060Z] 10:45:36 INFO - SimpleTest START
[task 2024-08-31T10:45:36.063Z] 10:45:36 INFO - Dumping test context:
[task 2024-08-31T10:45:36.064Z] 10:45:36 INFO - fission.autostart=true
[task 2024-08-31T10:45:36.102Z] 10:45:36 INFO - TEST-START | dom/bindings/test/test_ByteString.html
|
||
Updated•1 year ago
|
Summary: Intermittent docshell/test/mochitest/test_bug1121701.html | single tracking bug → Intermittent [tier 2] docshell/test/mochitest/test_bug1121701.html | single tracking bug
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 4•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
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.
Description
•