Closed Bug 1901625 Opened 1 year ago Closed 1 year ago

Intermittent toolkit/components/extensions/test/mochitest/test_ext_contentscript_blob.html | single tracking bug

Categories

(WebExtensions :: General, defect, P5)

defect

Tracking

(Not tracked)

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=461715547&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fn0ZBx0XT4y58NljR1Kjgg/runs/1/artifacts/public/logs/live_backing.log


[task 2024-06-10T18:19:33.084Z] 18:19:33     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_contentscript_blob.html
[task 2024-06-10T18:19:34.577Z] 18:19:34     INFO - GECKO(7506) | -----------------------------------------------------
[task 2024-06-10T18:19:34.577Z] 18:19:34     INFO - GECKO(7506) | -----------------------------------------------------
[task 2024-06-10T18:19:34.578Z] 18:19:34     INFO - GECKO(7506) | Suppressions used:
[task 2024-06-10T18:19:34.579Z] 18:19:34     INFO - GECKO(7506) |   count      bytes template
[task 2024-06-10T18:19:34.579Z] 18:19:34     INFO - GECKO(7506) | Suppressions used:
[task 2024-06-10T18:19:34.580Z] 18:19:34     INFO - GECKO(7506) |      31      16288 nsComponentManagerImpl
[task 2024-06-10T18:19:34.580Z] 18:19:34     INFO - GECKO(7506) |   count      bytes template
[task 2024-06-10T18:19:34.581Z] 18:19:34     INFO - GECKO(7506) |       2        288 libfontconfig.so
[task 2024-06-10T18:19:34.582Z] 18:19:34     INFO - GECKO(7506) |       1       9240 style::sharing::SHARING_CACHE_KEY
[task 2024-06-10T18:19:34.583Z] 18:19:34     INFO - GECKO(7506) |      31      16288 nsComponentManagerImpl
[task 2024-06-10T18:19:34.584Z] 18:19:34     INFO - GECKO(7506) |       1       4104 style::bloom::BLOOM_KEY
[task 2024-06-10T18:19:34.584Z] 18:19:34     INFO - GECKO(7506) |       2        288 libfontconfig.so
[task 2024-06-10T18:19:34.585Z] 18:19:34     INFO - GECKO(7506) | -----------------------------------------------------
[task 2024-06-10T18:19:34.585Z] 18:19:34     INFO - GECKO(7506) |       1       9240 style::sharing::SHARING_CACHE_KEY
[task 2024-06-10T18:19:34.585Z] 18:19:34     INFO - GECKO(7506) |       1       4104 style::bloom::BLOOM_KEY
[task 2024-06-10T18:19:34.586Z] 18:19:34     INFO - GECKO(7506) | -----------------------------------------------------
[task 2024-06-10T18:19:34.586Z] 18:19:34     INFO - GECKO(7506) | -----------------------------------------------------
[task 2024-06-10T18:19:34.587Z] 18:19:34     INFO - GECKO(7506) | Suppressions used:
[task 2024-06-10T18:19:34.587Z] 18:19:34     INFO - GECKO(7506) |   count      bytes template
[task 2024-06-10T18:19:34.588Z] 18:19:34     INFO - GECKO(7506) |      31      16288 nsComponentManagerImpl
[task 2024-06-10T18:19:34.588Z] 18:19:34     INFO - GECKO(7506) |       2        288 libfontconfig.so
[task 2024-06-10T18:19:34.589Z] 18:19:34     INFO - GECKO(7506) |       1       9240 style::sharing::SHARING_CACHE_KEY
[task 2024-06-10T18:19:34.589Z] 18:19:34     INFO - GECKO(7506) |       1       4104 style::bloom::BLOOM_KEY
[task 2024-06-10T18:19:34.590Z] 18:19:34     INFO - GECKO(7506) | -----------------------------------------------------
[task 2024-06-10T18:28:53.668Z] 18:28:53     INFO - TEST-UNEXPECTED-TIMEOUT | toolkit/components/extensions/test/mochitest/test_ext_contentscript_blob.html | application timed out after 370 seconds with no output
[task 2024-06-10T18:28:53.668Z] 18:28:53     INFO - TEST-INFO took 455918ms
[task 2024-06-10T18:28:53.669Z] 18:28:53     INFO - Buffered messages finished
[task 2024-06-10T18:28:53.669Z] 18:28:53  WARNING - Force-terminating active process(es).
[task 2024-06-10T18:28:53.669Z] 18:28:53     INFO - Determining child pids from psutil...
[task 2024-06-10T18:28:53.669Z] 18:28:53     INFO - [7576, 7659, 7666, 7702, 9428, 9430, 9470, 9502, 9504, 9543, 9575, 9579, 9628, 9636, 9650, 9699]
[task 2024-06-10T18:28:54.918Z] 18:28:54     INFO - ==> process 7506 launched child process 7576
[task 2024-06-10T18:28:55.199Z] 18:28:55     INFO - ==> process 7506 launched child process 7607
[task 2024-06-10T18:28:55.199Z] 18:28:55     INFO - ==> process 7506 launched child process 7659
[task 2024-06-10T18:28:55.200Z] 18:28:55     INFO - ==> process 7506 launched child process 7666
[task 2024-06-10T18:28:55.200Z] 18:28:55     INFO - ==> process 7506 launched child process 7669
[task 2024-06-10T18:28:55.200Z] 18:28:55     INFO - ==> process 7506 launched child process 7692
[task 2024-06-10T18:28:55.200Z] 18:28:55     INFO - ==> process 7506 launched child process 7702
[task 2024-06-10T18:28:55.200Z] 18:28:55     INFO - ==> process 7506 launched child process 7764
<...>
[task 2024-06-10T18:29:51.482Z] 18:29:51     INFO - zombiecheck | Checking for orphan process with PID: 7669
[task 2024-06-10T18:29:51.482Z] 18:29:51     INFO - zombiecheck | Checking for orphan process with PID: 8695
[task 2024-06-10T18:29:51.503Z] 18:29:51     INFO - runtests.py | Running with scheme: https
[task 2024-06-10T18:29:51.504Z] 18:29:51     INFO - runtests.py | Running with e10s: True
[task 2024-06-10T18:29:51.504Z] 18:29:51     INFO - runtests.py | Running with fission: True
[task 2024-06-10T18:29:51.504Z] 18:29:51     INFO - runtests.py | Running with cross-origin iframes: False
[task 2024-06-10T18:29:51.504Z] 18:29:51     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2024-06-10T18:29:51.505Z] 18:29:51     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2024-06-10T18:29:51.505Z] 18:29:51     INFO - runtests.py | Running tests: start.
[task 2024-06-10T18:29:51.505Z] 18:29:51     INFO - 
[task 2024-06-10T18:29:51.531Z] 18:29:51     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpe8gqec7e.mozrunner
[task 2024-06-10T18:29:51.549Z] 18:29:51     INFO - runtests.py | Application pid: 9768
[task 2024-06-10T18:29:51.549Z] 18:29:51     INFO - TEST-INFO | started process GECKO(9768)
[task 2024-06-10T18:29:55.217Z] 18:29:55     INFO - GECKO(9768) | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-06-10T18:29:55.300Z] 18:29:55     INFO - GECKO(9768) | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-06-10T18:29:57.789Z] 18:29:57     INFO - GECKO(9768) | 1718044197788	Marionette	INFO	Marionette enabled
[task 2024-06-10T18:29:57.792Z] 18:29:57     INFO - GECKO(9768) | 1718044197791	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-06-10T18:29:57.859Z] 18:29:57     INFO - GECKO(9768) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2024-06-10T18:29:57.870Z] 18:29:57     INFO - GECKO(9768) | 1718044197869	Marionette	INFO	Listening on port 2828
[task 2024-06-10T18:29:57.874Z] 18:29:57     INFO - GECKO(9768) | 1718044197870	Marionette	DEBUG	Marionette is listening
[task 2024-06-10T18:29:57.901Z] 18:29:57     INFO - GECKO(9768) | 1718044197900	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:38864
[task 2024-06-10T18:29:58.227Z] 18:29:58     INFO - GECKO(9768) | 1718044198226	Marionette	DEBUG	Closed connection 0
[task 2024-06-10T18:29:58.228Z] 18:29:58     INFO - GECKO(9768) | 1718044198227	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:38866
[task 2024-06-10T18:29:58.884Z] 18:29:58     INFO - GECKO(9768) | 1718044198883	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-06-10T18:29:58.889Z] 18:29:58     INFO - GECKO(9768) | 1718044198888	Marionette	DEBUG	Waiting for initial application window
[task 2024-06-10T18:30:02.193Z] 18:30:02     INFO - GECKO(9768) | console.error: ({})
[task 2024-06-10T18:30:05.452Z] 18:30:05     INFO - GECKO(9768) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-06-10T18:30:05.470Z] 18:30:05     INFO - GECKO(9768) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-06-10T18:30:05.606Z] 18:30:05     INFO - GECKO(9768) | 1718044205605	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2024-06-10T18:30:05.639Z] 18:30:05     INFO - GECKO(9768) | 1718044205638	RemoteAgent	TRACE	[3] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2024-06-10T18:30:05.642Z] 18:30:05     INFO - GECKO(9768) | 1718044205640	RemoteAgent	TRACE	[3] ProgressListener Setting unload timer (40000ms)
[task 2024-06-10T18:30:05.644Z] 18:30:05     INFO - GECKO(9768) | 1718044205643	RemoteAgent	TRACE	[3] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-06-10T18:30:05.645Z] 18:30:05     INFO - GECKO(9768) | 1718044205644	RemoteAgent	TRACE	[3] Document already finished loading: about:blank
[task 2024-06-10T18:30:05.646Z] 18:30:05     INFO - GECKO(9768) | 1718044205646	RemoteAgent	TRACE	[3] ProgressListener Stop: has error=false url=about:blank
[task 2024-06-10T18:30:05.669Z] 18:30:05     INFO - GECKO(9768) | 1718044205667	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"57984261-147f-47b0-bc0f-0c532896dd21","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"129.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:129.0) Gecko/20100101 Firefox/129.0","moz:buildID":"20240610162603","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":9768,"moz:profile":"/tmp/tmpe8gqec7e.mozrunner","moz:shutdownTimeout":300000,"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-06-10T18:30:05.849Z] 18:30:05     INFO - GECKO(9768) | 1718044205848	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpwv519egv.zip","temporary":false}]
[task 2024-06-10T18:30:06.156Z] 18:30:06     INFO - GECKO(9768) | 1718044206155	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2024-06-10T18:30:06.436Z] 18:30:06     INFO - GECKO(9768) | 1718044206435	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpkj7frkjn.zip","temporary":false}]
[task 2024-06-10T18:30:06.621Z] 18:30:06     INFO - GECKO(9768) | 1718044206620	Marionette	DEBUG	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2024-06-10T18:30:06.661Z] 18:30:06     INFO - GECKO(9768) | 1718044206660	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
[task 2024-06-10T18:30:06.664Z] 18:30:06     INFO - GECKO(9768) | 1718044206660	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
[task 2024-06-10T18:30:06.668Z] 18:30:06     INFO - GECKO(9768) | 1718044206665	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-06-10T18:30:06.669Z] 18:30:06     INFO - GECKO(9768) | 1718044206666	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2024-06-10T18:30:06.697Z] 18:30:06     INFO - GECKO(9768) | 1718044206696	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":"https://example.com:443/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true&ignorePrefsFile=ignorePrefs.json"}],"newSandbox":true,"sandbox":"default","line":2167,"filename":"tests/mochitest/runtests.py"}]
[task 2024-06-10T18:30:06.705Z] 18:30:06     INFO - GECKO(9768) | 1718044206704	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 9768
[task 2024-06-10T18:30:06.706Z] 18:30:06     INFO - GECKO(9768) | 1718044206705	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2024-06-10T18:30:06.809Z] 18:30:06     INFO - GECKO(9768) | 1718044206808	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2024-06-10T18:30:06.823Z] 18:30:06     INFO - GECKO(9768) | 1718044206822	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2024-06-10T18:30:06.825Z] 18:30:06     INFO - GECKO(9768) | 1718044206823	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2024-06-10T18:30:06.829Z] 18:30:06     INFO - GECKO(9768) | 1718044206828	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2024-06-10T18:30:06.831Z] 18:30:06     INFO - GECKO(9768) | 1718044206830	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2024-06-10T18:30:06.836Z] 18:30:06     INFO - GECKO(9768) | 1718044206835	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2024-06-10T18:30:06.837Z] 18:30:06     INFO - runtests.py | Waiting for browser...
[task 2024-06-10T18:30:06.844Z] 18:30:06     INFO - GECKO(9768) | 1718044206844	Marionette	DEBUG	Closed connection 1
[task 2024-06-10T18:30:09.109Z] 18:30:09     INFO - SimpleTest START
[task 2024-06-10T18:30:09.110Z] 18:30:09     INFO - Dumping test context:
[task 2024-06-10T18:30:09.111Z] 18:30:09     INFO -   fission.autostart=true
[task 2024-06-10T18:30:09.128Z] 18:30:09     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_storage_manager_capabilities.html
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.