1872817 - Intermittent SUT ERROR - unable to terminate process! | remove Z:\caches\DHaXTf9-RZ6x6SDXiLoy4g\src\thing\thing\core\firefox.exe: Access is denied.

Status: NEW

(Testing :: Mozbase, defect)

Description

[Cristian Tuns]

Push with failures: https://treeherder.mozilla.org/jobs?repo=mozilla-central&group_state=expanded&searchStr=windows%2C2012%2Cshippable%2Copt%2Cstartup-test-win32%2Csut&revision=cc67c788cded515a24f96c72c8a12b87a2cd1b3a&selectedTaskRun=R3lGNbUJTjGF4NB53RVQZw.0
Failure log: https://firefox-ci-tc.services.mozilla.com/tasks/R3lGNbUJTjGF4NB53RVQZw/runs/0/logs/public/logs/live.log

[task 2024-01-03T07:31:36.923Z] 07:31:36     INFO -  'base_work_dir': 'Z:\\task_170426214900156\\build\\src',
[task 2024-01-03T07:31:36.923Z] 07:31:36     INFO -  'config_files': (),
[task 2024-01-03T07:31:36.923Z] 07:31:36     INFO -  'log_level': 'info',
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO -  'log_to_console': True,
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO -  'opt_config_files': (),
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO -  'requires_gpu': False,
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO -  'run_for': 30,
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO -  'thing_args': (),
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO -  'thing_to_run': 'core/firefox.exe',
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO -  'thing_url': 'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HVBteuUyQRCUBjY1C9bojw/artifacts/public/build/target.installer.exe',
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO -  'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO -  'work_dir': 'build'}
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO - [mozharness: 2024-01-03 07:31:36.924179Z] Running download step.
[task 2024-01-03T07:31:36.924Z] 07:31:36     INFO - Running main action method: download
[task 2024-01-03T07:31:59.604Z] 07:31:59     INFO - [mozharness: 2024-01-03 07:31:59.604397Z] Finished download step (success)
[task 2024-01-03T07:31:59.604Z] 07:31:59     INFO - [mozharness: 2024-01-03 07:31:59.604397Z] Running run-thing step.
[task 2024-01-03T07:31:59.604Z] 07:31:59     INFO - Running main action method: run_thing
[task 2024-01-03T07:31:59.609Z] 07:31:59     INFO - Running Z:\task_170426214900156\build\src\thing\thing\core\firefox.exe with args []
[task 2024-01-03T07:33:01.654Z] 07:33:01     INFO - timeout detected: killing pid 8604
[task 2024-01-03T07:33:06.655Z] 07:33:06    ERROR - unable to terminate process!
[task 2024-01-03T07:33:11.656Z] 07:33:11    ERROR - unable to terminate process!
[task 2024-01-03T07:33:16.659Z] 07:33:16    ERROR - unable to terminate process!
[task 2024-01-03T07:37:08.947Z] 07:37:08     INFO - PASS: Z:\task_170426214900156\build\src\thing\thing\core\firefox.exe ran successfully for 30 seconds
[task 2024-01-03T07:37:08.947Z] 07:37:08     INFO - [mozharness: 2024-01-03 07:37:08.947606Z] Finished run-thing step (success)
[task 2024-01-03T07:37:09.025Z] Creating default state directory: C:\Users\task_170426214900156/.mozbuild
[task 2024-01-03T07:37:09.025Z] Creating local state directory: C:\Users\task_170426214900156\.mozbuild\srcdirs\src-14726e2670f7
[task 2024-01-03T07:37:09.025Z] Site not up-to-date reason: "C:\Users\task_170426214900156\.mozbuild\srcdirs\src-14726e2670f7\_virtualenvs\common" does not exist
[taskcluster 2024-01-03T07:37:09.120Z]    Exit Code: 0
[taskcluster 2024-01-03T07:37:09.120Z]    User Time: 15.625ms
[taskcluster 2024-01-03T07:37:09.120Z]  Kernel Time: 15.625ms
[taskcluster 2024-01-03T07:37:09.120Z]    Wall Time: 17m4.1064861s
[taskcluster 2024-01-03T07:37:09.120Z]       Result: SUCCEEDED
[taskcluster 2024-01-03T07:37:09.120Z] === Task Finished ===
[taskcluster 2024-01-03T07:37:09.120Z] Task Duration: 17m4.108615s
[taskcluster 2024-01-03T07:37:09.120Z] [mounts] Preserving cache: Moving "Z:\\task_170426214900156\\build" to "Z:\\caches\\DHaXTf9-RZ6x6SDXiLoy4g"
[taskcluster 2024-01-03T07:37:12.538Z] [mounts] Removing cache gecko-level-3-checkouts-sparse from cache table
[taskcluster 2024-01-03T07:37:12.538Z] [mounts] Deleting cache gecko-level-3-checkouts-sparse file(s) at Z:\caches\DHaXTf9-RZ6x6SDXiLoy4g
[taskcluster 2024-01-03T07:37:13.899Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2024-04-02T05:11:55.254Z
[taskcluster:error] goroutine 1 [running]:
[taskcluster:error] runtime/debug.Stack()
[taskcluster:error] 	/task_169029279444702/go/go/src/runtime/debug/stack.go:24 +0x65
[taskcluster:error] main.(*TaskRun).Run.func2()
[taskcluster:error] 	/task_169029279444702/taskcluster/workers/generic-worker/main.go:931 +0xb3
[taskcluster:error] panic({0x108cb80, 0xc000300c90})
[taskcluster:error] 	/task_169029279444702/go/go/src/runtime/panic.go:890 +0x263
[taskcluster:error] main.(*WritableDirectoryCache).Unmount(0xc000361360, 0xc000324b80)
[taskcluster:error] 	/task_169029279444702/taskcluster/workers/generic-worker/mounts.go:592 +0x337
[taskcluster:error] main.(*TaskMount).Stop(0xc000324b80, 0xc0003062a0)
[taskcluster:error] 	/task_169029279444702/taskcluster/workers/generic-worker/mounts.go:441 +0x28f
[taskcluster:error] main.(*TaskRun).Run.func3({{0x123ce28?, 0xc000324b80?}, {0x123d5e0?, 0x1614008?}})
[taskcluster:error] 	/task_169029279444702/taskcluster/workers/generic-worker/main.go:1019 +0x9d
[taskcluster:error] main.(*TaskRun).Run(0xc000570000)
[taskcluster:error] 	/task_169029279444702/taskcluster/workers/generic-worker/main.go:1103 +0x12cb
[taskcluster:error] main.RunWorker()
[taskcluster:error] 	/task_169029279444702/taskcluster/workers/generic-worker/main.go:428 +0xb48
[taskcluster:error] main.main()
[taskcluster:error] 	/task_169029279444702/taskcluster/workers/generic-worker/main.go:157 +0x54a
[taskcluster:error] 
[taskcluster:error] &fs.PathError{Op:"remove", Path:"Z:\\caches\\DHaXTf9-RZ6x6SDXiLoy4g\\src\\thing\\thing\\core\\firefox.exe", Err:0x5}
[taskcluster:error] remove Z:\caches\DHaXTf9-RZ6x6SDXiLoy4g\src\thing\thing\core\firefox.exe: Access is denied.

Comment 1

[Intermittent Failures Robot]

1 failures in 2274 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows2012-32-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1872817&startday=2024-01-01&endday=2024-01-07&tree=all

Comment 2

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

The severity field is not set for this bug.
:gbrown, could you have a look please?

For more information, please visit BugBot documentation.

Comment 3

[Intermittent Failures Robot]

1 failures in 3599 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows2012-64-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1872817&startday=2024-01-15&endday=2024-01-21&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 4034 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows2012-32-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1872817&startday=2024-01-22&endday=2024-01-28&tree=all

Comment 5

[Intermittent Failures Robot]

1 failures in 3952 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows2012-32-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1872817&startday=2024-01-29&endday=2024-02-04&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 4009 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows2012-64-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1872817&startday=2024-02-05&endday=2024-02-11&tree=all