1579605 - Intermittent WindowsError: [Error 5] Access is denied: 'Z:\\task_1567829384\\build\\application\\firefox\\firefox.exe'

Status: RESOLVED INCOMPLETE

(Firefox Build System :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=265498908&repo=mozilla-beta
Full log: https://queue.taskcluster.net/v1/task/CCI6uvdxSjKYy4JzOxVwOQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-09-07T05:22:08.394Z] 05:22:08 INFO -
[task 2019-09-07T05:22:08.394Z] 05:22:08 INFO - SUMMARY
[task 2019-09-07T05:22:08.394Z] 05:22:08 INFO - -------
[task 2019-09-07T05:22:08.394Z] 05:22:08 INFO - passed: 6
[task 2019-09-07T05:22:08.394Z] 05:22:08 INFO - failed: 0
[task 2019-09-07T05:22:08.395Z] 05:22:08 INFO - todo: 0
[task 2019-09-07T05:22:08.395Z] 05:22:08 INFO - SUITE-END | took 34s
[task 2019-09-07T05:22:08.669Z] 05:22:08 INFO - Return code: 0
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - # TBPL SUCCESS #
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - Running post-action listener: _package_coverage_data
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - Running post-action listener: _resource_record_post_action
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - Running post-action listener: process_java_coverage_data
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - [mozharness: 2019-09-07 05:22:08.670000Z] Finished run-tests step (success)
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - [mozharness: 2019-09-07 05:22:08.670000Z] Running uninstall step.
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - Running pre-action listener: _resource_record_pre_action
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - Running main action method: uninstall
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - Getting output from command: ['Z:\task_1567829384\build\venv\Scripts\mozuninstall', 'Z:\task_1567829384\build\application']
[task 2019-09-07T05:22:08.670Z] 05:22:08 INFO - Copy/paste: Z:\task_1567829384\build\venv\Scripts\mozuninstall Z:\task_1567829384\build\application
[task 2019-09-07T05:22:16.644Z] 05:22:16 INFO - Reading from file tmpfile_stdout
[task 2019-09-07T05:22:16.645Z] 05:22:16 INFO - Output received:
[task 2019-09-07T05:22:16.645Z] 05:22:16 INFO - rmtree() failed for "('Z:\task_1567829384\build\application',)". Reason: Access is denied (13). Retrying...
[task 2019-09-07T05:22:16.645Z] 05:22:16 INFO - rmtree() failed for "('Z:\task_1567829384\build\application',)". Reason: Access is denied (13). Retrying...
[task 2019-09-07T05:22:16.645Z] 05:22:16 INFO - rmtree() failed for "('Z:\task_1567829384\build\application',)". Reason: Access is denied (13). Retrying...
[task 2019-09-07T05:22:16.645Z] 05:22:16 INFO - rmtree() failed for "('Z:\task_1567829384\build\application',)". Reason: Access is denied (13). Retrying...
[task 2019-09-07T05:22:16.645Z] 05:22:16 INFO - rmtree() failed for "('Z:\task_1567829384\build\application',)". Reason: Access is denied (13). Retrying...
[task 2019-09-07T05:22:16.645Z] 05:22:16 ERROR - Errors received:
[task 2019-09-07T05:22:16.645Z] 05:22:16 INFO - Reading from file tmpfile_stderr
[task 2019-09-07T05:22:16.645Z] 05:22:16 ERROR - Traceback (most recent call last):
[task 2019-09-07T05:22:16.645Z] 05:22:16 ERROR - File "Z:\task_1567829384\build\venv\Scripts\mozuninstall-script.py", line 9, in <module>
[task 2019-09-07T05:22:16.645Z] 05:22:16 ERROR - load_entry_point('mozInstall==2.0.0', 'console_scripts', 'mozuninstall')()
[task 2019-09-07T05:22:16.645Z] 05:22:16 ERROR - File "z:\task_1567829384\build\venv\lib\site-packages\mozinstall\mozinstall.py", line 374, in uninstall_cli
[task 2019-09-07T05:22:16.645Z] 05:22:16 ERROR - uninstall(argv[0])
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - File "z:\task_1567829384\build\venv\lib\site-packages\mozinstall\mozinstall.py", line 252, in uninstall
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - mozfile.remove(install_folder)
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - File "z:\task_1567829384\build\venv\lib\site-packages\mozfile\mozfile.py", line 232, in remove
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - _call_with_windows_retry(shutil.rmtree, (path,))
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - File "z:\task_1567829384\build\venv\lib\site-packages\mozfile\mozfile.py", line 191, in _call_with_windows_retry
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - _call_windows_retry(*args, **kwargs)
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - File "z:\task_1567829384\build\venv\lib\site-packages\mozfile\mozfile.py", line 149, in _call_windows_retry
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - func(*args)
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - File "c:\mozilla-build\python\Lib\shutil.py", line 247, in rmtree
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - rmtree(fullname, ignore_errors, onerror)
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - File "c:\mozilla-build\python\Lib\shutil.py", line 252, in rmtree
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - onerror(os.remove, fullname, sys.exc_info())
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - File "c:\mozilla-build\python\Lib\shutil.py", line 250, in rmtree
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - os.remove(fullname)
[task 2019-09-07T05:22:16.646Z] 05:22:16 ERROR - WindowsError: [Error 5] Access is denied: 'Z:\task_1567829384\build\application\firefox\firefox.exe'
[task 2019-09-07T05:22:16.647Z] 05:22:16 INFO - Using _rmtree_windows ...
[task 2019-09-07T05:22:16.647Z] 05:22:16 INFO - Running command: del /F /Q "Z:\task_1567829384\tmpfile_stderr"
[task 2019-09-07T05:22:16.654Z] 05:22:16 INFO - Return code: 0
[task 2019-09-07T05:22:16.654Z] 05:22:16 INFO - Using _rmtree_windows ...
[task 2019-09-07T05:22:16.654Z] 05:22:16 INFO - Running command: del /F /Q "Z:\task_1567829384\tmpfile_stdout"
[task 2019-09-07T05:22:16.661Z] 05:22:16 INFO - Return code: 0
[task 2019-09-07T05:22:16.661Z] 05:22:16 ERROR - Return code: 1
[task 2019-09-07T05:22:16.661Z] 05:22:16 WARNING - setting return code to 3
[task 2019-09-07T05:22:16.661Z] 05:22:16 FATAL - Halting on failure while running ['Z:\task_1567829384\build\venv\Scripts\mozuninstall', 'Z:\task_1567829384\build\application']
[task 2019-09-07T05:22:16.661Z] 05:22:16 FATAL - Running post_fatal callback...
[task 2019-09-07T05:22:16.661Z] 05:22:16 FATAL - Exiting 3

last test pass: telemetry\marionette\tests\unit\test_ping_server_received_ping.py TestPingServer.test_ping_server_received_ping | took 3359ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579605&startday=2019-09-02&endday=2019-09-08&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows7-32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579605&startday=2019-09-09&endday=2019-09-15&tree=all

Comment 3

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

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

Comment 4

[Alexandru Michis [:malexandru]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=278422052&repo=try&lineNumber=2479

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows7-32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579605&startday=2019-11-25&endday=2019-12-01&tree=all

Comment 6

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

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