1469915 - Perma-failing ccov TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/extensions/test/xpcshell/test_temporary.js | Test timed out

Status: RESOLVED DUPLICATE of bug 1469904

(Toolkit :: Add-ons Manager, defect)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

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

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

16:15:18     INFO -  TEST-START | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_temporary.js
16:20:18  WARNING -  TEST-UNEXPECTED-TIMEOUT | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_temporary.js | Test timed out
16:20:18     INFO -  TEST-INFO took 300000ms
16:20:18     INFO -  >>>>>>>
16:20:18     INFO -  PID 9456 | [9456, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2682
16:20:18     INFO -  PID 9456 | JavaScript strict warning: resource://testing-common/AddonTestUtils.jsm, line 310: ReferenceError: reference to undefined property "testScope"
16:20:18     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "testScope"" {file: "resource://testing-common/AddonTestUtils.jsm" line: 310}]"
16:20:18     INFO -  PID 9456 | [9456, LoadRoots] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3464
16:20:18     INFO -  PID 9456 | [9456, LoadRoots] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3464
16:20:18     INFO -  PID 9456 | [9456, LoadRoots] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3464
16:20:18     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
16:20:18     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
16:20:18     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
16:20:18     INFO -  running event loop
16:20:18     INFO -  xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_temporary.js | Starting test_new_temporary
16:20:18     INFO -  (xpcshell/head.js) | test test_new_temporary pending (2)
16:20:18     INFO -  PID 9456 | 1529511319375	addons.manager	DEBUG	Application has been upgraded
16:20:18     INFO -  PID 9456 | 1529511319437	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]
16:20:18     INFO -  PID 9456 | 1529511319461	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/LightweightThemeManager.jsm: ["LightweightThemeManager"]
16:20:18     INFO -  PID 9456 | 1529511319494	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm
16:20:18     INFO -  PID 9456 | 1529511319508	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm
16:20:18     INFO -  PID 9456 | 1529511319511	addons.manager	DEBUG	Starting provider: XPIProvider
16:20:18     INFO -  PID 9456 | 1529511319514	addons.xpi	DEBUG	startup
16:20:18     INFO -  PID 9456 | 1529511319515	addons.xpi	WARN	List of valid built-in add-ons could not be parsed.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2093"  data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2093
16:20:18     INFO -  PID 9456 | callProvider()@resource://gre/modules/AddonManager.jsm:206
16:20:18     INFO -  PID 9456 | _startProvider()@resource://gre/modules/AddonManager.jsm:654
16:20:18     INFO -  PID 9456 | startup()@resource://gre/modules/AddonManager.jsm:813
16:20:18     INFO -  PID 9456 | startup()@resource://gre/modules/AddonManager.jsm:2808
16:20:18     INFO -  PID 9456 | observe()@jar:file:///Z:/task_1529508782/build/application/firefox/omni.ja!/components/addonManager.js:66
16:20:18     INFO -  PID 9456 | promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:768
16:20:18     INFO -  PID 9456 | 1529511319532	addons.xpi	INFO	SystemAddonLocation directory is missing
16:20:18     INFO -  PID 9456 | 1529511319622	addons.xpi	INFO	Removing all system add-on upgrades.
16:20:18     INFO -  PID 9456 | 1529511319628	addons.xpi	DEBUG	checkForChanges
16:20:18     INFO -  PID 9456 | 1529511319631	addons.xpi	DEBUG	Loaded add-on state: ${}
16:20:18     INFO -  PID 9456 | 1529511319634	addons.xpi	DEBUG	scanForChanges changed: false, state: {}
16:20:18     INFO -  PID 9456 | 1529511319638	addons.xpi	DEBUG	Empty XPI database, setting schema version preference to 27
16:20:18     INFO -  PID 9456 | 1529511319638	addons.xpi	DEBUG	No changes found
16:20:18     INFO -  PID 9456 | 1529511319700	addons.manager	DEBUG	Registering shutdown blocker for XPIProvider
16:20:18     INFO -  PID 9456 | 1529511319702	addons.manager	DEBUG	Provider finished startup: XPIProvider
16:20:18     INFO -  PID 9456 | 1529511319703	addons.manager	DEBUG	Starting provider: LightweightThemeManager
16:20:18     INFO -  PID 9456 | 1529511319703	addons.manager	DEBUG	Registering shutdown blocker for LightweightThemeManager
16:20:18     INFO -  PID 9456 | 1529511319705	addons.manager	DEBUG	Provider finished startup: LightweightThemeManager
16:20:18     INFO -  PID 9456 | 1529511319709	addons.manager	DEBUG	Starting provider: GMPProvider
16:20:18     INFO -  PID 9456 | 1529511319721	addons.manager	DEBUG	Registering shutdown blocker for GMPProvider
16:20:18     INFO -  PID 9456 | 1529511319723	addons.manager	DEBUG	Provider finished startup: GMPProvider
16:20:18     INFO -  PID 9456 | 1529511319723	addons.manager	DEBUG	Starting provider: PluginProvider
16:20:18     INFO -  PID 9456 | 1529511319724	addons.manager	DEBUG	Registering shutdown blocker for PluginProvider
16:20:18     INFO -  PID 9456 | 1529511319726	addons.manager	DEBUG	Provider finished startup: PluginProvider
16:20:18     INFO -  PID 9456 | JavaScript strict warning: resource://gre/modules/LightweightThemeManager.jsm, line 694: ReferenceError: reference to undefined property "version"
16:20:18     INFO -  PID 9456 | 1529511319757	addons.manager	DEBUG	Completed startup sequence
16:20:18     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
16:20:18     INFO -  "CONSOLE_MESSAGE: (info) 1529511319375	addons.manager	DEBUG	Application has been upgraded"
16:20:18     INFO -  "CONSOLE_MESSAGE: (info) 1529511319437	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
16:20:18     INFO -  "CONSOLE_MESSAGE: (info) 1529511319461	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/LightweightThemeManager.jsm: ["LightweightThemeManager"]"
16:20:18     INFO -  "CONSOLE_MESSAGE: (info) 1529511319494	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
16:20:18     INFO -  "CONSOLE_MESSAGE: (info) 1529511319508	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm"
16:20:18     INFO -  "CONSOLE_MESSAGE: (info) 1529511319511	addons.manager	DEBUG	Starting provider: XPIProvider"
16:20:18     INFO -  "CONSOLE_MESSAGE: (info) 1529511319514	addons.xpi	DEBUG	startup"
16:20:18     INFO -  "CONSOLE_MESSAGE: (info) 1529511319515	addons.xpi	WARN	List of valid built-in add-ons could not be parsed.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2093"  data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2093
16:20:18     INFO -  callProvider()@resource://gre/modules/AddonManager.jsm:206
16:20:18     INFO -  _startProvider()@resource://gre/modules/AddonManager.jsm:654
16:20:18     INFO -  startup()@resource://gre/modules/AddonManager.jsm:813
16:20:18     INFO -  startup()@resource://gre/modules/AddonManager.jsm:2808
16:20:18     INFO -  observe()@jar:file:///Z:/task_1529508782/build/application/firefox/omni.ja!/components/addonManager.js:66
16:20:18     INFO -  promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:768"

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 19

Platform breakdown:
* windows10-64-ccov: 19

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

Comment 2

[Intermittent Failures Robot]

25 failures in 718 pushes (0.035 failures/push) were associated with this bug in the last 7 days. 

This is the #49 most frequent failure this week.   

Repository breakdown:
* mozilla-central: 22
* try: 2
* mozilla-inbound: 1

Platform breakdown:
* windows10-64-ccov: 24
* windows10-64: 1

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

Comment 3

[Eliza Balazs [:ebalazs_]]

In the last 7 days there are 30 failures on this bug.
They occur on windows10-64-ccov - debug.
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=185889493&repo=mozilla-central&lineNumber=3397
INFO -  TEST-START | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_temporary.js
02:12:29  WARNING -  TEST-UNEXPECTED-TIMEOUT | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_temporary.js | Test timed out
02:12:29     INFO -  TEST-INFO took 300000ms
02:12:29     INFO -  >>>>>>>
02:12:29     INFO -  PID 4224 | [4224, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2682
02:12:29     INFO -  PID 4224 | JavaScript strict warning: resource://testing-common/AddonTestUtils.jsm, line 310: ReferenceError: reference to undefined property "testScope"
02:12:29     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "testScope"" {file: "resource://testing-common/AddonTestUtils.jsm" line: 310}]"
02:12:29     INFO -  PID 4224 | [4224, LoadRoots] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3459
02:12:29     INFO -  PID 4224 | [4224, LoadRoots] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3459
02:12:29     INFO -  PID 4224 | [4224, LoadRoots] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3459
02:12:29     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
02:12:29     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
02:12:29     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
02:12:29     INFO -  running event loop
02:12:29     INFO -  xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_temporary.js | Starting test_new_temporary
ddurst: Can you have a look at this?

Comment 6

[Intermittent Failures Robot]

36 failures in 622 pushes (0.058 failures/push) were associated with this bug in the last 7 days. 

This is the #31 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:
* mozilla-central: 36

Platform breakdown:
* windows10-64-ccov: 36

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1469915&startday=2018-07-02&endday=2018-07-08&tree=trunk

Comment 7

[Luca Greco [:rpl] [:luca] [:lgreco]]

Updated the whiteboard field by removing "needswork:owner", as this bugzilla issue has been marked as a duplicate of Bug 1469904, as part of which this test file has been disabled on windows (and so Bug 1469904 is already tracking this intermittent).