1470014 - Intermittent gtest | application crashed [@ LateWriteObserver::Observe(mozilla::IOInterposeObserver::Observation &)]

Status: RESOLVED INCOMPLETE

(Testing :: GTest, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: aiakab [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=184044686&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/IoV1nctHSeG9eMYG6PqAXQ/runs/0/artifacts/public/logs/live_backing.log

20:52:19     INFO - Running post-action listener: _resource_record_post_action
20:52:19     INFO - Running post-action listener: _start_resource_monitoring
20:52:19     INFO - Starting resource monitoring.
20:52:19     INFO - [mozharness: 2018-06-20 20:52:19.078000Z] Finished create-virtualenv step (success)
20:52:19     INFO - [mozharness: 2018-06-20 20:52:19.078000Z] Running install step.
20:52:19     INFO - Running pre-action listener: _resource_record_pre_action
20:52:19     INFO - Running main action method: install
20:52:19     INFO - Getting output from command: ['Z:\\task_1529527319\\build\\venv\\Scripts\\pip', 'freeze']
20:52:19     INFO - Copy/paste: Z:\task_1529527319\build\venv\Scripts\pip freeze
20:52:19     INFO - Reading from file tmpfile_stdout
20:52:19     INFO - Using _rmtree_windows ...
20:52:19     INFO - Running command: del /F /Q "Z:\task_1529527319\tmpfile_stderr"
20:52:19     INFO - Return code: 0
20:52:19     INFO - Using _rmtree_windows ...
20:52:19     INFO - Running command: del /F /Q "Z:\task_1529527319\tmpfile_stdout"
20:52:19     INFO - Return code: 0
20:52:19     INFO - Detecting whether we're running mozinstall >=1.0...
20:52:19     INFO - Getting output from command: ['Z:\\task_1529527319\\build\\venv\\Scripts\\mozinstall', '-h']
20:52:19     INFO - Copy/paste: Z:\task_1529527319\build\venv\Scripts\mozinstall -h
20:52:19     INFO - Reading from file tmpfile_stdout
20:52:19     INFO - Output received:
20:52:19     INFO -  Usage: mozinstall-script.py [options] installer
20:52:19     INFO -  Options:
20:52:19     INFO -    -h, --help            show this help message and exit
20:52:19     INFO -    -d DEST, --destination=DEST
20:52:19     INFO -                          Directory to install application into. [default:
20:52:19     INFO -                          "Z:\task_1529527319"]
20:52:19     INFO -    --app=APP             Application being installed. [default: firefox]
20:52:19     INFO - Using _rmtree_windows ...
20:52:19     INFO - Running command: del /F /Q "Z:\task_1529527319\tmpfile_stderr"
20:52:19     INFO - Return code: 0
20:52:19     INFO - Using _rmtree_windows ...
20:52:19     INFO - Running command: del /F /Q "Z:\task_1529527319\tmpfile_stdout"
20:52:19     INFO - Return code: 0
20:52:19     INFO - mkdir: Z:\task_1529527319\build\application
20:52:19     INFO - Getting output from command: ['Z:\\task_1529527319\\build\\venv\\Scripts\\mozinstall', 'Z:\\task_1529527319\\build\\installer.zip', '--destination', 'Z:\\task_1529527319\\build\\application']
20:52:19     INFO - Copy/paste: Z:\task_1529527319\build\venv\Scripts\mozinstall Z:\task_1529527319\build\installer.zip --destination Z:\task_1529527319\build\application
20:52:21     INFO - Reading from file tmpfile_stdout
20:52:21     INFO - Output received:
20:52:21     INFO -  Z:\task_1529527319\build\application\firefox\firefox.exe
20:52:21     INFO - Using _rmtree_windows ...
20:52:21     INFO - Running command: del /F /Q "Z:\task_1529527319\tmpfile_stderr"
20:52:21     INFO - Return code: 0
20:52:21     INFO - Using _rmtree_windows ...
20:52:21     INFO - Running command: del /F /Q "Z:\task_1529527319\tmpfile_stdout"
20:52:21     INFO - Return code: 0
20:52:21     INFO - Running post-action listener: _resource_record_post_action
20:52:21     INFO - [mozharness: 2018-06-20 20:52:21.491000Z] Finished install step (success)
20:52:21     INFO - [mozharness: 2018-06-20 20:52:21.491000Z] Running stage-files step.
20:52:21     INFO - Running pre-action listener: _resource_record_pre_action
20:52:21     INFO - Running main action method: stage_files
20:52:21     INFO - Moving Z:\task_1529527319\build\tests\bin\plugins\gmp-clearkey to Z:\task_1529527319\build\application\firefox
20:52:21    ERROR - shutil error: Destination path 'Z:\task_1529527319\build\application\firefox\gmp-clearkey' already exists
20:52:21     INFO - Moving Z:\task_1529527319\build\tests\bin\plugins\gmp-fake to Z:\task_1529527319\build\application\firefox
20:52:21     INFO - Moving Z:\task_1529527319\build\tests\bin\plugins\gmp-fakeopenh264 to Z:\task_1529527319\build\application\firefox
20:52:21     INFO - Moving Z:\task_1529527319\build\tests\gtest\dependentlibs.list.gtest to Z:\task_1529527319\build\application\firefox
20:52:21     INFO - copying tree: Z:\task_1529527319\build\tests\gtest\gtest_bin to Z:\task_1529527319\build\application\firefox
20:52:21     INFO - mkdir: Z:\task_1529527319\build\application\firefox\gtest
20:52:21     INFO - copying tree: Z:\task_1529527319\build\tests\gtest\gtest_bin\gtest to Z:\task_1529527319\build\application\firefox\gtest
20:52:21     INFO - rmtree: Z:\task_1529527319\build\application\firefox\gtest
20:52:21     INFO - Using _rmtree_windows ...
20:52:21     INFO - retry: Calling _rmtree_windows with args: ('Z:\\task_1529527319\\build\\application\\firefox\\gtest',), kwargs: {}, attempt #1
20:52:21     INFO - Running command: rmdir /S /Q "Z:\task_1529527319\build\application\firefox\gtest"
20:52:21     INFO - Return code: 0
20:52:21     INFO - Running post-action listener: _resource_record_post_action
20:52:21     INFO - [mozharness: 2018-06-20 20:52:21.635000Z] Finished stage-files step (success)
20:52:21     INFO - [mozharness: 2018-06-20 20:52:21.635000Z] Running run-tests step.
20:52:21     INFO - Running pre-action listener: _resource_record_pre_action
20:52:21     INFO - Running pre-action listener: _set_gcov_prefix
20:52:21     INFO - Running main action method: run_tests
20:52:21     INFO - Running pre test command run mouse & screen adjustment script with 'c:\mozilla-build\python\python.exe Z:\task_1529527319\mozharness\external_tools\mouse_and_screen_resolution.py --configuration-file Z:\task_1529527319\mozharness\external_tools\machine-configuration.json'
20:52:21     INFO - Running command: ('c:\\mozilla-build\\python\\python.exe', 'Z:\\task_1529527319\\mozharness\\external_tools\\mouse_and_screen_resolution.py', '--configuration-file', 'Z:\\task_1529527319\\mozharness\\external_tools\\machine-configuration.json') in Z:\task_1529527319\build
20:52:21     INFO - Copy/paste: c:\mozilla-build\python\python.exe Z:\task_1529527319\mozharness\external_tools\mouse_and_screen_resolution.py --configuration-file Z:\task_1529527319\mozharness\external_tools\machine-configuration.json
20:52:24     INFO -  Screen resolution (current): (1024, 768)
20:52:24     INFO -  Changing the screen resolution...
20:52:24     INFO -  Screen resolution (new): (1280, 1024)
20:52:24     INFO -  Mouse position (current): (640, 512)
20:52:24     INFO -  Mouse position (new): (1010, 10)
20:52:24     INFO - Return code: 0
20:52:24     INFO - Running pre test command set windows VisualFX with 'powershell -command "&{&Set-ItemProperty -Path 'HKCU:Software\Microsoft\Windows\CurrentVersion\Explorer\VisualEffects' -Name VisualFXSetting -Value 1}"'
20:52:24     INFO - Running command: ('powershell', '-command', '"&{&Set-ItemProperty -Path \'HKCU:Software\\Microsoft\\Windows\\CurrentVersion\\Explorer\\VisualEffects\' -Name VisualFXSetting -Value 1}"') in Z:\task_1529527319\build
20:52:24     INFO - Copy/paste: powershell -command "\"&{&Set-ItemProperty -Path 'HKCU:Software\Microsoft\Windows\CurrentVersion\Explorer\VisualEffects' -Name VisualFXSetting -Value 1}\""
20:52:26     INFO -  &{&Set-ItemProperty -Path 'HKCU:Software\Microsoft\Windows\CurrentVersion\Explorer\VisualEffects' -Name VisualFXSetting -Value 1}
20:52:26     INFO - Return code: 0

Comment 1

[Eliza Balazs [:ebalazs_]]

Fixed by the backout: https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&revision=59322b179c0fdb4423d42b624da7bf64ee5a2327&filter-resultStatus=testfailed&filter-resultStatus=busted&filter-resultStatus=exception&filter-resultStatus=retry&filter-resultStatus=usercancel&filter-resultStatus=running&filter-resultStatus=pending&filter-resultStatus=runnable

Comment 2

[Intermittent Failures Robot]

13 failures in 521 pushes (0.025 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 6
* mozilla-central: 4
* mozilla-inbound: 3

Platform breakdown:
* windows10-64: 13

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1470014&startday=2018-06-18&endday=2018-06-24&tree=trunk

Comment 3

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup