Closed Bug 1866578 Opened 2 years ago Closed 2 years ago

Perma esr115 Linux tsan TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/classic/new_session/default_values.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Type:
defect

Tracking

(firefox-esr115 fixed, firefox120 unaffected, firefox121 unaffected, firefox122 unaffected)

Status:
RESOLVED FIXED
Milestone:
115 Branch
Tracking Flags:
Tracking Status
firefox-esr115 --- fixed
firefox120 --- unaffected
firefox121 --- unaffected
firefox122 --- unaffected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, intermittent-testcase, regression)

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

[task 2023-11-24T20:28:41.918Z] 20:28:41     INFO - STDOUT: PASSED
[task 2023-11-24T20:28:41.918Z] 20:28:41     INFO - PID 22553 | 1700857721916	webdriver::server	DEBUG	-> DELETE /session/d25e42f4-3ed3-4d59-80d9-3d681c1fbdd6
[task 2023-11-24T20:28:41.929Z] 20:28:41     INFO - PID 22553 | 1700857721921	Marionette	DEBUG	0 -> [0,2,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2023-11-24T20:28:41.929Z] 20:28:41     INFO - PID 22553 | 1700857721924	Marionette	INFO	Stopped listening on port 36894
[task 2023-11-24T20:28:42.205Z] 20:28:42     INFO - PID 22553 | 1700857722204	Marionette	TRACE	Received observer notification quit-application
[task 2023-11-24T20:28:42.231Z] 20:28:42     INFO - PID 22553 | 1700857722230	Marionette	TRACE	Received observer notification quit-application
[task 2023-11-24T20:28:42.236Z] 20:28:42     INFO - PID 22553 | 1700857722236	Marionette	DEBUG	Marionette stopped listening
[task 2023-11-24T20:28:42.241Z] 20:28:42     INFO - PID 22553 | 1700857722240	Marionette	DEBUG	0 <- [1,2,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2023-11-24T20:28:42.356Z] 20:28:42     INFO - PID 22553 | 1700857722355	webdriver::server	DEBUG	Teardown session
[task 2023-11-24T20:28:42.374Z] 20:28:42     INFO - PID 22553 | 1700857722373	Marionette	DEBUG	Closed connection 0
[task 2023-11-24T20:28:45.558Z] 20:28:45     INFO - PID 22553 | 1700857725557	geckodriver::browser	DEBUG	Browser process stopped: exit status: 0
[task 2023-11-24T20:28:45.559Z] 20:28:45     INFO - PID 22553 | 1700857725558	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-11-24T20:28:45.565Z] 20:28:45     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/new_session/default_values.py::test_desired 
[task 2023-11-24T20:28:45.566Z] 20:28:45     INFO - PID 22553 | 1700857725561	webdriver::server	DEBUG	-> POST /session {"desiredCapabilities": {"moz:firefoxOptions": {"args": ["--profile", "/tmp/tmpcqsv74zo.mozrunner"]}}}
[task 2023-11-24T20:28:45.566Z] 20:28:45     INFO - PID 22553 | 1700857725561	webdriver::command	WARN	You are using deprecated legacy session negotiation patterns (desiredCapabilities/requiredCapabilities), see https://developer.mozilla.org/en-US/docs/Web/WebDriver/Capabilities#Legacy
[task 2023-11-24T20:28:45.567Z] 20:28:45     INFO - PID 22553 | 1700857725561	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2023-11-24T20:28:45.567Z] 20:28:45     INFO - PID 22553 | 1700857725561	geckodriver::capabilities	DEBUG	Found version 115.4
[task 2023-11-24T20:28:45.568Z] 20:28:45     INFO - PID 22553 | 1700857725561	geckodriver::browser	DEBUG	Backing up prefs to "/tmp/tmpcqsv74zo.mozrunner/user.geckodriver_backup"
[task 2023-11-24T20:28:45.569Z] 20:28:45     INFO - PID 22553 | 1700857725567	mozrunner::runner	INFO	Running command: MOZ_CRASHREPORTER="1" MOZ_CRASHREPORTER_NO_REPORT="1" MOZ_CRASHREPORTER_SHUTDOWN="1" MOZ_NO_REMOTE="1" "/builds/worker/workspace/build/application/firefox/firefox" "--marionette" "--profile" "/tmp/tmpcqsv74zo.mozrunner" "-no-remote"
[task 2023-11-24T20:28:45.569Z] 20:28:45     INFO - PID 22553 | 1700857725568	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2023-11-24T20:28:45.570Z] 20:28:45     INFO - PID 22553 | 1700857725568	geckodriver::browser	TRACE	Failed to open /tmp/tmpcqsv74zo.mozrunner/MarionetteActivePort
[task 2023-11-24T20:28:45.570Z] 20:28:45     INFO - PID 22553 | 1700857725568	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-11-24T20:28:45.669Z] 20:28:45     INFO - PID 22553 | 1700857725668	geckodriver::browser	TRACE	Failed to open /tmp/tmpcqsv74zo.mozrunner/MarionetteActivePort
[task 2023-11-24T20:28:45.670Z] 20:28:45     INFO - PID 22553 | 1700857725668	geckodriver::marionette	TRACE	Retrying in 100ms
<...>
[task 2023-11-24T20:28:47.071Z] 20:28:47     INFO - PID 22553 | 1700857727070	geckodriver::browser	TRACE	Failed to open /tmp/tmpcqsv74zo.mozrunner/MarionetteActivePort
[task 2023-11-24T20:28:47.072Z] 20:28:47     INFO - PID 22553 | 1700857727070	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-11-24T20:28:47.103Z] 20:28:47     INFO - PID 22553 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-11-24T20:28:47.135Z] 20:28:47     INFO - PID 22553 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-11-24T20:28:47.171Z] 20:28:47     INFO - PID 22553 | 1700857727170	geckodriver::browser	TRACE	Failed to open /tmp/tmpcqsv74zo.mozrunner/MarionetteActivePort
[task 2023-11-24T20:28:47.172Z] 20:28:47     INFO - PID 22553 | 1700857727170	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-11-24T20:28:47.247Z] 20:28:47     INFO - PID 22553 | 1700857727246	Marionette	INFO	Marionette enabled
[task 2023-11-24T20:28:47.251Z] 20:28:47     INFO - PID 22553 | 1700857727250	Marionette	TRACE	Received observer notification final-ui-startup
[task 2023-11-24T20:28:47.271Z] 20:28:47     INFO - PID 22553 | 1700857727270	geckodriver::browser	TRACE	Failed to open /tmp/tmpcqsv74zo.mozrunner/MarionetteActivePort
[task 2023-11-24T20:28:47.272Z] 20:28:47     INFO - PID 22553 | 1700857727270	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-11-24T20:28:47.371Z] 20:28:47     INFO - PID 22553 | 1700857727370	geckodriver::browser	TRACE	Failed to open /tmp/tmpcqsv74zo.mozrunner/MarionetteActivePort
[task 2023-11-24T20:28:47.372Z] 20:28:47     INFO - PID 22553 | 1700857727370	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-11-24T20:28:47.441Z] 20:28:47     INFO - PID 22553 | 1700857727440	Marionette	INFO	Listening on port 38384
[task 2023-11-24T20:28:47.443Z] 20:28:47     INFO - PID 22553 | 1700857727442	Marionette	DEBUG	Marionette is listening
[task 2023-11-24T20:28:47.472Z] 20:28:47     INFO - PID 22553 | Read port: 38384
[task 2023-11-24T20:28:47.502Z] 20:28:47     INFO - PID 22553 | 1700857727502	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:55030
[task 2023-11-24T20:28:47.511Z] 20:28:47     INFO - PID 22553 | 1700857727510	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:38384.
[task 2023-11-24T20:28:47.678Z] 20:28:47     INFO - PID 22553 | 1700857727677	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{}]
[task 2023-11-24T20:28:47.689Z] 20:28:47     INFO - PID 22553 | 1700857727689	Marionette	DEBUG	Waiting for initial application window
[task 2023-11-24T20:28:52.760Z] 20:28:52     INFO - PID 22553 | console.error: ({})
[task 2023-11-24T20:28:53.833Z] 20:28:53     INFO - PID 22553 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-11-24T20:28:55.681Z] 20:28:55     INFO - PID 22553 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-11-24T20:28:55.683Z] 20:28:55     INFO - PID 22553 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-11-24T20:28:55.684Z] 20:28:55     INFO - PID 22553 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-11-24T20:28:55.770Z] 20:28:55     INFO - PID 22553 | 1700857735769	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2023-11-24T20:28:55.787Z] 20:28:55     INFO - PID 22553 | 1700857735786	RemoteAgent	TRACE	[3] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2023-11-24T20:28:55.788Z] 20:28:55     INFO - PID 22553 | 1700857735787	RemoteAgent	TRACE	[3] ProgressListener Setting unload timer (40000ms)
[task 2023-11-24T20:28:55.789Z] 20:28:55     INFO - PID 22553 | 1700857735788	RemoteAgent	TRACE	[3] Document already finished loading: about:blank
[task 2023-11-24T20:28:55.789Z] 20:28:55     INFO - PID 22553 | 1700857735788	RemoteAgent	TRACE	[3] ProgressListener Stop: has error=false
[task 2023-11-24T20:28:55.823Z] 20:28:55     INFO - PID 22553 | 1700857735821	Marionette	DEBUG	0 <- [1,1,null,{"sessionId":"6333ce45-1160-420d-8bf5-008fe12a97ad","capabilities":{"browserName":"firefox","browserVersion":"115.4.0","platformName":"linux","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":false,"unhandledPromptBehavior":"dismiss and notify","moz:accessibilityChecks":false,"moz:buildID":"20231015231032","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":5404,"moz:profile":"/tmp/tmpcqsv74zo.mozrunner","moz:shutdownTimeout":360000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2023-11-24T20:28:55.838Z] 20:28:55     INFO - PID 22553 | 1700857735836	webdriver::server	DEBUG	<- 200 OK {"value":{"sessionId":"6333ce45-1160-420d-8bf5-008fe12a97ad","capabilities":{"acceptInsecureCerts":false,"browserNa ... ctability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
[task 2023-11-24T20:28:55.906Z] 20:28:55     INFO - STDOUT: FAILED
[task 2023-11-24T20:28:55.906Z] 20:28:55     INFO - PID 22553 | 1700857735905	webdriver::server	DEBUG	-> DELETE /session/6333ce45-1160-420d-8bf5-008fe12a97ad
[task 2023-11-24T20:28:55.914Z] 20:28:55     INFO - PID 22553 | 1700857735913	Marionette	DEBUG	0 -> [0,2,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2023-11-24T20:28:55.916Z] 20:28:55     INFO - PID 22553 | 1700857735915	Marionette	INFO	Stopped listening on port 38384
[task 2023-11-24T20:28:56.194Z] 20:28:56     INFO - PID 22553 | 1700857736193	Marionette	TRACE	Received observer notification quit-application
[task 2023-11-24T20:28:56.220Z] 20:28:56     INFO - PID 22553 | 1700857736219	Marionette	TRACE	Received observer notification quit-application
[task 2023-11-24T20:28:56.225Z] 20:28:56     INFO - PID 22553 | 1700857736225	Marionette	DEBUG	Marionette stopped listening
[task 2023-11-24T20:28:56.230Z] 20:28:56     INFO - PID 22553 | 1700857736229	Marionette	DEBUG	0 <- [1,2,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2023-11-24T20:28:56.343Z] 20:28:56     INFO - PID 22553 | 1700857736342	webdriver::server	DEBUG	Teardown session
[task 2023-11-24T20:28:56.360Z] 20:28:56     INFO - PID 22553 | 1700857736359	Marionette	DEBUG	Closed connection 0
[task 2023-11-24T20:28:59.446Z] 20:28:59     INFO - PID 22553 | 1700857739445	geckodriver::browser	DEBUG	Browser process stopped: exit status: 0
[task 2023-11-24T20:28:59.447Z] 20:28:59     INFO - PID 22553 | 1700857739446	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-11-24T20:28:59.454Z] 20:28:59     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/new_session/default_values.py::test_ignore_non_spec_fields_in_capabilities 
[task 2023-11-24T20:28:59.455Z] 20:28:59     INFO - PID 22553 | 1700857739448	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "/tmp/tmpcqsv74zo.mozrunner"]}}, "desiredCapabilities": {"pageLoadStrategy": "eager"}}}
[task 2023-11-24T20:28:59.456Z] 20:28:59     INFO - PID 22553 | 1700857739448	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2023-11-24T20:28:59.456Z] 20:28:59     INFO - PID 22553 | 1700857739449	geckodriver::capabilities	DEBUG	Found version 115.4
[task 2023-11-24T20:28:59.456Z] 20:28:59     INFO - PID 22553 | 1700857739449	geckodriver::browser	DEBUG	Backing up prefs to "/tmp/tmpcqsv74zo.mozrunner/user.geckodriver_backup"
[task 2023-11-24T20:28:59.457Z] 20:28:59     INFO - PID 22553 | 1700857739454	mozrunner::runner	INFO	Running command: MOZ_CRASHREPORTER="1" MOZ_CRASHREPORTER_NO_REPORT="1" MOZ_CRASHREPORTER_SHUTDOWN="1" MOZ_NO_REMOTE="1" "/builds/worker/workspace/build/application/firefox/firefox" "--marionette" "--profile" "/tmp/tmpcqsv74zo.mozrunner" "-no-remote"
[task 2023-11-24T20:28:59.457Z] 20:28:59     INFO - PID 22553 | 1700857739457	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2023-11-24T20:28:59.458Z] 20:28:59     INFO - PID 22553 | 1700857739457	geckodriver::browser	TRACE	Failed to open /tmp/tmpcqsv74zo.mozrunner/MarionetteActivePort
[task 2023-11-24T20:28:59.459Z] 20:28:59     INFO - PID 22553 | 1700857739458	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-11-24T20:28:59.560Z] 20:28:59     INFO - PID 22553 | 1700857739559	geckodriver::browser	TRACE	Failed to open /tmp/tmpcqsv74zo.mozrunner/MarionetteActivePort
[task 2023-11-24T20:28:59.561Z] 20:28:59     INFO - PID 22553 | 1700857739559	geckodriver::marionette	TRACE	Retrying in 100ms
<...>
[task 2023-11-24T20:29:01.263Z] 20:29:01     INFO - PID 22553 | 1700857741261	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-11-24T20:29:01.335Z] 20:29:01     INFO - PID 22553 | 1700857741334	Marionette	INFO	Listening on port 43487
[task 2023-11-24T20:29:01.336Z] 20:29:01     INFO - PID 22553 | 1700857741336	Marionette	DEBUG	Marionette is listening
[task 2023-11-24T20:29:01.362Z] 20:29:01     INFO - PID 22553 | Read port: 43487
[task 2023-11-24T20:29:01.385Z] 20:29:01     INFO - PID 22553 | 1700857741384	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:46520
[task 2023-11-24T20:29:01.396Z] 20:29:01     INFO - PID 22553 | 1700857741395	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:43487.
[task 2023-11-24T20:29:01.918Z] 20:29:01     INFO - PID 22553 | 1700857741917	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{}]
[task 2023-11-24T20:29:01.929Z] 20:29:01     INFO - PID 22553 | 1700857741928	Marionette	DEBUG	Waiting for initial application window
[task 2023-11-24T20:29:06.724Z] 20:29:06     INFO - PID 22553 | console.error: ({})
[task 2023-11-24T20:29:07.799Z] 20:29:07     INFO - PID 22553 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-11-24T20:29:09.662Z] 20:29:09     INFO - PID 22553 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-11-24T20:29:09.663Z] 20:29:09     INFO - PID 22553 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-11-24T20:29:09.664Z] 20:29:09     INFO - PID 22553 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-11-24T20:29:09.697Z] 20:29:09     INFO - PID 22553 | 1700857749696	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2023-11-24T20:29:09.717Z] 20:29:09     INFO - PID 22553 | 1700857749716	RemoteAgent	TRACE	[3] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2023-11-24T20:29:09.719Z] 20:29:09     INFO - PID 22553 | 1700857749717	RemoteAgent	TRACE	[3] ProgressListener Setting unload timer (40000ms)
[task 2023-11-24T20:29:09.719Z] 20:29:09     INFO - PID 22553 | 1700857749718	RemoteAgent	TRACE	[3] Document already finished loading: about:blank
[task 2023-11-24T20:29:09.720Z] 20:29:09     INFO - PID 22553 | 1700857749719	RemoteAgent	TRACE	[3] ProgressListener Stop: has error=false
[task 2023-11-24T20:29:09.754Z] 20:29:09     INFO - PID 22553 | 1700857749753	Marionette	DEBUG	0 <- [1,1,null,{"sessionId":"a91631b9-4d46-4845-9c45-3c5c65092e83","capabilities":{"browserName":"firefox","browserVersion":"115.4.0","platformName":"linux","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":false,"unhandledPromptBehavior":"dismiss and notify","moz:accessibilityChecks":false,"moz:buildID":"20231015231032","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":5667,"moz:profile":"/tmp/tmpcqsv74zo.mozrunner","moz:shutdownTimeout":360000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2023-11-24T20:29:09.775Z] 20:29:09     INFO - STDOUT: PASSED
[task 2023-11-24T20:29:09.775Z] 20:29:09     INFO - PID 22553 | 1700857749771	webdriver::server	DEBUG	<- 200 OK {"value":{"sessionId":"a91631b9-4d46-4845-9c45-3c5c65092e83","capabilities":{"acceptInsecureCerts":false,"browserNa ... ctability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
[task 2023-11-24T20:29:09.776Z] 20:29:09     INFO - PID 22553 | 1700857749773	webdriver::server	DEBUG	-> DELETE /session/a91631b9-4d46-4845-9c45-3c5c65092e83
[task 2023-11-24T20:29:09.777Z] 20:29:09     INFO - PID 22553 | 1700857749776	Marionette	DEBUG	0 -> [0,2,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2023-11-24T20:29:09.780Z] 20:29:09     INFO - PID 22553 | 1700857749779	Marionette	INFO	Stopped listening on port 43487
[task 2023-11-24T20:29:10.073Z] 20:29:10     INFO - PID 22553 | 1700857750073	Marionette	TRACE	Received observer notification quit-application
[task 2023-11-24T20:29:10.100Z] 20:29:10     INFO - PID 22553 | 1700857750100	Marionette	TRACE	Received observer notification quit-application
[task 2023-11-24T20:29:10.107Z] 20:29:10     INFO - PID 22553 | 1700857750106	Marionette	DEBUG	Marionette stopped listening
[task 2023-11-24T20:29:10.111Z] 20:29:10     INFO - PID 22553 | 1700857750110	Marionette	DEBUG	0 <- [1,2,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2023-11-24T20:29:10.228Z] 20:29:10     INFO - PID 22553 | 1700857750227	webdriver::server	DEBUG	Teardown session
[task 2023-11-24T20:29:10.246Z] 20:29:10     INFO - PID 22553 | 1700857750245	Marionette	DEBUG	Closed connection 0
[task 2023-11-24T20:29:10.451Z] 20:29:10     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/classic/new_session/default_values.py | expected OK
[task 2023-11-24T20:29:10.451Z] 20:29:10     INFO - TEST-INFO took 80002ms
[task 2023-11-24T20:29:10.456Z] 20:29:10     INFO - PID 22553 | ==5667==WARNING: Can't read from symbolizer at fd 11
<...>
[task 2023-11-24T20:29:10.496Z] 20:29:10     INFO - PID 22553 | ==5667==WARNING: Can't read from symbolizer at fd 11
[task 2023-11-24T20:29:10.552Z] 20:29:10     INFO - Closing logging queue
[task 2023-11-24T20:29:10.552Z] 20:29:10     INFO - queue closed
[task 2023-11-24T20:29:10.561Z] 20:29:10     INFO - PID 5934 | 1700857750560	geckodriver	INFO	Listening on 127.0.0.1:36215
[task 2023-11-24T20:29:10.562Z] 20:29:10     INFO - Starting runner
[task 2023-11-24T20:29:10.763Z] 20:29:10     INFO - TEST-START | /webdriver/tests/classic/new_session/invalid_capabilities.py

Hi Henrik! Can you please take a look at this? This is permafailing on esr115 starting with this push. It only happens Linux 18.04 x64 WebRender tsan opt.
Thank you!

Flags: needinfo?(hskupin)

Set release status flags based on info from the regressing bug 1849972

status-firefox120: --- → affected
status-firefox121: --- → affected
status-firefox122: --- → affected
status-firefox-esr115: --- → affected

Here is the overall log output for this particular test:

[task 2023-11-24T20:27:50.448Z] 20:27:50     INFO - TEST-START | /webdriver/tests/classic/new_session/default_values.py
[task 2023-11-24T20:27:50.497Z] 20:27:50     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/new_session/default_values.py::test_basic 
[task 2023-11-24T20:28:04.334Z] 20:28:04     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/new_session/default_values.py::test_repeat_new_session 
[task 2023-11-24T20:28:18.109Z] 20:28:18     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/new_session/default_values.py::test_missing_first_match 
[task 2023-11-24T20:28:31.802Z] 20:28:31     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/new_session/default_values.py::test_missing_always_match 
[task 2023-11-24T20:28:45.565Z] 20:28:45     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/new_session/default_values.py::test_desired 
[task 2023-11-24T20:28:59.454Z] 20:28:59     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/classic/new_session/default_values.py::test_ignore_non_spec_fields_in_capabilities 
[task 2023-11-24T20:29:10.451Z] 20:29:10     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/classic/new_session/default_values.py | expected OK

The timeout happens on the esr115 branch after 60s because the multiplier is 4 for TSAN builds, while on mozilla-central the multiplier is 8. I changed those default values over on bug 1848121.

As such I would suggest that we get the patch from bug 1848121 uplifted to mozilla-esr115.

Note that the releases from 120 to 122 are not affected given that the before-mentioned patch landed on 118.

status-firefox120: affected → unaffected
status-firefox121: affected → unaffected
status-firefox122: affected → unaffected
Depends on: 1848121
Flags: needinfo?(hskupin)

The patch on bug 1848121 got uplifted, and this timeout is no longer visible.

Status: NEW → RESOLVED
Closed: 2 years ago
status-firefox-esr115: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 115 Branch
You need to log in before you can comment on or make changes to this bug.