1963926 - Frequent macOS Shippable PermissionError: [Errno 1] Operation not permitted

Status: REOPENED

(Testing :: web-platform-tests, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2025-05-01T19:53:38.283Z] 19:53:38     INFO - PID 1305 | 1746129218282	webdriver::server	DEBUG	-> DELETE /session/84632309-4ce9-4fd0-bb80-4c7940caf4cd
[task 2025-05-01T19:53:38.284Z] 19:53:38     INFO - PID 1305 | 1746129218283	Marionette	DEBUG	0 -> [0,244,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2025-05-01T19:53:38.285Z] 19:53:38     INFO - PID 1305 | 1746129218283	Marionette	INFO	Stopped listening on port 49564
[task 2025-05-01T19:53:38.286Z] 19:53:38     INFO - PID 1305 | 1746129218284	Marionette	TRACE	[4] MarionetteCommands actor destroyed for window id 8589934593
[task 2025-05-01T19:53:38.287Z] 19:53:38     INFO - PID 1305 | 1746129218285	WebDriver BiDi	DEBUG	Unregistered session handler: /session/84632309-4ce9-4fd0-bb80-4c7940caf4cd
[task 2025-05-01T19:53:38.287Z] 19:53:38     INFO - PID 1305 | 1746129218286	RemoteAgent	DEBUG	WebDriverBiDiConnection d3c0a6b8-b41a-4016-b509-d1fa5f72ea33 closed
[task 2025-05-01T19:53:38.289Z] 19:53:38     INFO - PID 1305 | 1746129218287	RemoteAgent	TRACE	MessageHandler ROOT for session 84632309-4ce9-4fd0-bb80-4c7940caf4cd is being destroyed
[task 2025-05-01T19:53:38.290Z] 19:53:38     INFO - PID 1305 | 1746129218288	RemoteAgent	TRACE	Unregistered MessageHandler ROOT for session 84632309-4ce9-4fd0-bb80-4c7940caf4cd
[task 2025-05-01T19:53:38.310Z] 19:53:38     INFO - PID 1305 | 1746129218309	RemoteAgent	TRACE	Received observer notification common-dialog-loaded
[task 2025-05-01T19:53:47.694Z] 19:53:47  WARNING - Forcibly terminating runner process
[task 2025-05-01T19:53:47.724Z] 19:53:47     INFO - STDERR: Could not terminate process: 1305
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - Failed to stop either the runner or the browser process
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - Traceback (most recent call last):
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/venv/lib/python3.11/site-packages/mozprocess/processhandler.py", line 200, in send_sig
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR -     os.killpg(pid, sig)
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - PermissionError: [Errno 1] Operation not permitted
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - During handling of the above exception, another exception occurred:
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - Traceback (most recent call last):
[task 2025-05-01T19:53:47.730Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/venv/lib/python3.11/site-packages/mozprocess/processhandler.py", line 210, in send_sig
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -     os.waitpid(-pid, 0)
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - ChildProcessError: [Errno 10] No child processes
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - During handling of the above exception, another exception occurred:
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - Traceback (most recent call last):
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/tests/web-platform/tests/tools/wptrunner/wptrunner/testrunner.py", line 961, in stop_runner
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -     self.browser.stop(force=True)
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/tests/web-platform/tests/tools/wptrunner/wptrunner/testrunner.py", line 290, in stop
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -     self.browser.stop(force=force)
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/tests/web-platform/tests/tools/wptrunner/wptrunner/browsers/firefox.py", line 1026, in stop
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -     super().stop(force=force)
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/tests/web-platform/tests/tools/wptrunner/wptrunner/browsers/base.py", line 399, in stop
[task 2025-05-01T19:53:47.731Z] 19:53:47    ERROR -     proc.kill(9, timeout=5)
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/venv/lib/python3.11/site-packages/mozprocess/processhandler.py", line 895, in kill
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -     self.proc.kill(sig=sig, timeout=timeout)
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/venv/lib/python3.11/site-packages/mozprocess/processhandler.py", line 243, in kill
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -     self.send_signal(sig)
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/venv/lib/python3.11/site-packages/mozprocess/processhandler.py", line 240, in send_signal
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -     send_sig(sig or signal.SIGKILL)
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/venv/lib/python3.11/site-packages/mozprocess/processhandler.py", line 212, in send_sig
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -     return send_sig(sig, retries + 1)
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -            ^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -   File "/opt/worker/tasks/task_174612879688402/build/venv/lib/python3.11/site-packages/mozprocess/processhandler.py", line 200, in send_sig
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR -     os.killpg(pid, sig)
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR - PermissionError: [Errno 1] Operation not permitted
[task 2025-05-01T19:53:47.732Z] 19:53:47    ERROR - 
[task 2025-05-01T19:53:47.737Z] 19:53:47     INFO - Starting WebDriver: /opt/worker/tasks/task_174612879688402/fetches/geckodriver --host 127.0.0.1 --port 0 -vv --binary /opt/worker/tasks/task_174612879688402/build/application/Firefox.app/Contents/MacOS/firefox
[task 2025-05-01T19:53:47.986Z] 19:53:47     INFO - PID 1694 | 1746129227743	geckodriver	INFO	Listening on 127.0.0.1:49598
[task 2025-05-01T19:53:47.987Z] 19:53:47     INFO - PID 1694 | 1746129227743	webdriver::server	TRACE	Build standard route for /status
[task 2025-05-01T19:53:47.987Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session
[task 2025-05-01T19:53:47.988Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session/{sessionId}
[task 2025-05-01T19:53:47.989Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session/{sessionId}/url
[task 2025-05-01T19:53:47.989Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session/{sessionId}/url
[task 2025-05-01T19:53:47.990Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session/{sessionId}/back
[task 2025-05-01T19:53:47.991Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session/{sessionId}/forward
[task 2025-05-01T19:53:47.992Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session/{sessionId}/refresh
[task 2025-05-01T19:53:47.992Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session/{sessionId}/title
[task 2025-05-01T19:53:47.993Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session/{sessionId}/source
[task 2025-05-01T19:53:47.994Z] 19:53:47     INFO - PID 1694 | 1746129227744	webdriver::server	TRACE	Build standard route for /session/{sessionId}/window

Comment 1

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Table

	no_variant
macosx1470-64--/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1963926&startday=2025-04-28&endday=2025-05-04&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 3

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Table

	no_variant
macosx1470-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1963926&startday=2025-06-30&endday=2025-07-06&tree=all

Comment 4

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=530552698&repo=autoland

Comment 5

[Serban Stanca [:SerbanS]]

This is frequently failing as it can be seen here.

Hi Brad! Could you check this one out?

Thank you!

Comment 6

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 7

[Intermittent Failures Robot]

28 failures were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 21
• mozilla-central: 7

Table

	no_variant
macosx1470-x86_64/opt	28

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1963926&startday=2025-10-06&endday=2025-10-12&tree=all

Comment 8

[Erich Gubler [:ErichDonGubler] (he/him)]

:bradwerth: I see quite a few failures in CI for bug 1993808. Hopefully that can give you a lead!

Comment 9

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 10

[Intermittent Failures Robot]

60 failures were associated with this bug in the last 7 days.
This is the #30 most frequent failure this week.

This failure happened more than 30 times this week! Resolving this bug is a high priority.

Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled.

Repository breakdown:

• autoland: 45
• try: 3
• mozilla-central: 12

Table

	no_variant
macosx1470-x86_64/opt	60

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1963926&startday=2025-10-13&endday=2025-10-19&tree=all