Closed Bug 1081702 Opened 5 years ago Closed 5 years ago

Intermittent browser_sorting_plugins.js | application crashed [@ mozalloc_abort(char const*)] after "FATAL ERROR: AsyncShutdown timeout in AddonManager: Waiting for providers to shut down"

Categories

(Toolkit :: Add-ons Manager, defect)

defect
Not set

Tracking

()

RESOLVED FIXED
mozilla36
Tracking Status
firefox33 --- fixed
firefox34 + fixed
firefox35 --- fixed
firefox36 --- fixed
firefox-esr31 --- unaffected

People

(Reporter: RyanVM, Assigned: Irving)

References

Details

(Keywords: crash, intermittent-failure)

Attachments

(1 file)

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

builder 	Ubuntu VM 12.04 mozilla-inbound pgo test mochitest-e10s-browser-chrome-3
buildid 	20141011193024
builduid 	c05a0d8293e14c1290c8d7e3acfe117c
results 	warnings (1)
revision 	c9fa1a711dc7
slave 		tst-linux32-spot-088
starttime 	Sun Oct 12 2014 00:00:09 GMT-0400 (Eastern Standard Time)

21:10:30 INFO - 248 INFO TEST-START | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/browser_sorting_plugins.js
21:10:30 INFO - 1413087030343 addons.manager DEBUG Registering shutdown blocker for <unnamed-provider>
21:10:31 INFO - Manager window unload handler
21:10:31 INFO - 1413087031843 Browser.Experiments.Experiments TRACE Experiments #0::updateManifest()
21:10:31 INFO - 1413087031847 Browser.Experiments.Experiments TRACE Experiments #0::_run
21:10:31 INFO - 1413087031854 Browser.Experiments.Experiments TRACE Experiments #0::_main iteration
21:10:31 INFO - 1413087031856 Browser.Experiments.Experiments TRACE Experiments #0::_loadManifest
21:10:31 INFO - 1413087031858 Browser.Experiments.Experiments TRACE Experiments #0::httpGetRequest(http://127.0.0.1:8888/experiments-dummy/manifest)
21:10:31 INFO - ###!!! [Child][DispatchAsyncMessage] Error: (msgtype=0xAA0001,name=PTexture::Msg___delete__) Route error: message sent to unknown actor ID
21:10:31 INFO - ###!!! [Child][DispatchAsyncMessage] Error: (msgtype=0xAA0001,name=PTexture::Msg___delete__) Route error: message sent to unknown actor ID
21:10:32 INFO - 1413087032058 Browser.Experiments.Experiments ERROR Experiments #0::httpGetRequest::onLoad() - Request to http://127.0.0.1:8888/experiments-dummy/manifest returned status 404
21:10:32 INFO - 1413087032066 Browser.Experiments.Experiments ERROR Experiments #0::_loadManifest - failure to fetch/parse manifest (continuing anyway): Error: Experiments - XHR status for http://127.0.0.1:8888/experiments-dummy/manifest is 404
21:10:32 INFO - 1413087032068 Browser.Experiments.Experiments TRACE Experiments #0::_evaluateExperiments
21:10:32 INFO - 249 INFO TEST-OK | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/browser_sorting_plugins.js | took 1772ms
21:10:32 INFO - 1413087032132 addons.manager ERROR Exception calling provider <unnamed-provider>.getAddonsByTypes: TypeError: this.timerLocations is null (chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/head.js:1041:4) JS Stack trace: MP_delayCallback@head.js:1041:5 < MP_getAddonsByTypes@head.js:887:5 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < this.Experiments.PreviousExperimentProvider.prototype<.getAddonsByTypes@Experiments.jsm:2304:5 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < SocialAddonProvider.getAddonsByTypes@SocialService.jsm:1031:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < PL_getAddonsByTypes@PluginProvider.jsm:135:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < OpenH264Provider.getAddonsByTypes@OpenH264Provider.jsm:362:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < LightweightThemeManager_getAddonsByTypes@LightweightThemeManager.jsm:388:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < getAddonsByTypes_getVisibleAddons@XPIProvider.jsm:3822:7 < makeSafe/<@XPIProviderUtils.js:146:17 < asyncMap@XPIProviderUtils.js:174:5 < this.XPIDatabase.getAddonList/<@XPIProviderUtils.js:1051:9 < Handler.prototype.process@Promise-backend.js:865:23 < this.PromiseWalker.walkerLoop@Promise-backend.js:744:7
21:10:32 INFO - 1413087032134 addons.manager WARN Exception calling callback: TypeError: a is undefined (resource://app/modules/experiments/Experiments.jsm:203:46) JS Stack trace: installedExperimentAddons/<@Experiments.jsm:203:47 < safeCall@AddonManager.jsm:173:5 < getAddonsByTypes_noMoreObjects@AddonManager.jsm:2135:9 < AOC_callNext@AddonManager.jsm:305:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < callProviderAsync@AddonManager.jsm:239:5 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < this.Experiments.PreviousExperimentProvider.prototype<.getAddonsByTypes@Experiments.jsm:2304:5 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < SocialAddonProvider.getAddonsByTypes@SocialService.jsm:1031:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < PL_getAddonsByTypes@PluginProvider.jsm:135:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < OpenH264Provider.getAddonsByTypes@OpenH264Provider.jsm:362:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < LightweightThemeManager_getAddonsByTypes@LightweightThemeManager.jsm:388:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < getAddonsByTypes_getVisibleAddons@XPIProvider.jsm:3822:7 < makeSafe/<@XPIProviderUtils.js:146:17 < asyncMap@XPIProviderUtils.js:174:5 < this.XPIDatabase.getAddonList/<@XPIProviderUtils.js:1051:9 < Handler.prototype.process@Promise-backend.js:865:23 < this.PromiseWalker.walkerLoop@Promise-backend.js:744:7
21:10:35 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
21:10:35 INFO - ###!!! [Child][DispatchAsyncMessage] Error: (msgtype=0xAA0001,name=PTexture::Msg___delete__) Route error: message sent to unknown actor ID
21:10:35 INFO - ###!!! [Child][DispatchAsyncMessage] Error: (msgtype=0xAA0001,name=PTexture::Msg___delete__) Route error: message sent to unknown actor ID
21:10:35 INFO - ###!!! [Child][DispatchAsyncMessage] Error: (msgtype=0xAA0001,name=PTexture::Msg___delete__) Route error: message sent to unknown actor ID
21:10:35 INFO - ###!!! [Child][DispatchAsyncMessage] Error: (msgtype=0xAA0001,name=PTexture::Msg___delete__) Route error: message sent to unknown actor ID
21:10:35 INFO - ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
21:10:36 INFO - 1413087036014 Browser.Experiments.Experiments TRACE Experiments #0::uninit: started
21:10:36 INFO - 1413087036016 Browser.Experiments.Experiments TRACE Experiments #0::uninit: finished with _loadTask
21:10:36 INFO - 1413087036023 Browser.Experiments.Experiments TRACE Experiments #0::uninit: no previous shutdown
21:10:36 INFO - 1413087036025 Browser.Experiments.Experiments TRACE Experiments #0::Unregistering instance with Addon Manager.
21:10:36 INFO - 1413087036026 Browser.Experiments.Experiments TRACE Experiments #0::Removing install listener from add-on manager.
21:10:36 INFO - 1413087036027 Browser.Experiments.Experiments TRACE Experiments #0::Removing addon listener from add-on manager.
21:10:36 INFO - 1413087036028 Browser.Experiments.Experiments TRACE Experiments #0::Finished unregistering with addon manager.
21:10:36 INFO - 1413087036029 Browser.Experiments.Experiments TRACE Experiments #0::Unregistering previous experiment add-on provider.
21:10:36 INFO - 1413087036031 Browser.Experiments.Experiments TRACE Experiments #0::uninit: waiting on _mainTask
21:10:36 INFO - 1413087036033 Browser.Experiments.Experiments TRACE PreviousExperimentProvider #0::shutdown()
21:10:37 INFO - ###!!! [Child][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
21:10:46 INFO - WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"Experiments.jsm shutdown","state":{"isShutdown":true,"isEnabled":true,"isRefresh":false,"isDirty":false,"isFirstEvaluate":false,"hasLoadTask":false,"hasMainTask":true,"hasTimer":false,"hasAddonProvider":false,"latestLogs":["10: onUninstalled() - addon id: addon1@tests.mozilla.org","10: onUninstalled() - addon id: 4@personas.mozilla.org","10: onUninstalled() - addon id: remote1@tests.mozilla.org","10: updateManifest()","10: _run","10: _main iteration","10: _loadManifest","10: httpGetRequest(http://127.0.0.1:8888/experiments-dummy/manifest)","60: httpGetRequest::onLoad() - Request to http://127.0.0.1:8888/experiments-dummy/manifest returned status 404","60: _loadManifest - failure to fetch/parse manifest (continuing anyway): Error: Experiments - XHR status for http://127.0.0.1:8888/experiments-dummy/manifest is 404","10: _evaluateExperiments","10: uninit: started","10: uninit: finished with _loadTask","10: uninit: no previous shutdown","10: Unregistering instance with Addon Manager.","10: Removing install listener from add-on manager.","10: Removing addon listener from add-on manager.","10: Finished unregistering with addon manager.","10: Unregistering previous experiment add-on provider.","10: uninit: waiting on _mainTask"],"experiments":{},"terminateReason":null},"filename":"resource://app/modules/experiments/Experiments.jsm","lineNumber":435,"stack":["resource://app/modules/experiments/Experiments.jsm:Experiments.Experiments.prototype.init:435","resource://app/modules/experiments/Experiments.jsm:Experiments.Experiments:416","resource://app/modules/experiments/Experiments.jsm:Experiments.instance:250","resource://app/components/ExperimentsService.js:ExperimentsService.prototype._delayedInit:65","resource://services-common/utils.js:notify:182","null:null:0"]}] Barrier: AddonManager: Waiting for providers to shut down.
21:10:46 INFO - WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"AddonManager: shutting down.","state":[{"name":"AddonManager: Waiting for providers to shut down.","state":[{"name":"Experiments.jsm shutdown","state":{"isShutdown":true,"isEnabled":true,"isRefresh":false,"isDirty":false,"isFirstEvaluate":false,"hasLoadTask":false,"hasMainTask":true,"hasTimer":false,"hasAddonProvider":false,"latestLogs":["10: onUninstalled() - addon id: addon1@tests.mozilla.org","10: onUninstalled() - addon id: 4@personas.mozilla.org","10: onUninstalled() - addon id: remote1@tests.mozilla.org","10: updateManifest()","10: _run","10: _main iteration","10: _loadManifest","10: httpGetRequest(http://127.0.0.1:8888/experiments-dummy/manifest)","60: httpGetRequest::onLoad() - Request to http://127.0.0.1:8888/experiments-dummy/manifest returned status 404","60: _loadManifest - failure to fetch/parse manifest (continuing anyway): Error: Experiments - XHR status for http://127.0.0.1:8888/experiments-dummy/manifest is 404","10: _evaluateExperiments","10: uninit: started","10: uninit: finished with _loadTask","10: uninit: no previous shutdown","10: Unregistering instance with Addon Manager.","10: Removing install listener from add-on manager.","10: Removing addon listener from add-on manager.","10: Finished unregistering with addon manager.","10: Unregistering previous experiment add-on provider.","10: uninit: waiting on _mainTask"],"experiments":{},"terminateReason":null},"filename":"resource://app/modules/experiments/Experiments.jsm","lineNumber":435,"stack":["resource://app/modules/experiments/Experiments.jsm:Experiments.Experiments.prototype.init:435","resource://app/modules/experiments/Experiments.jsm:Experiments.Experiments:416","resource://app/modules/experiments/Experiments.jsm:Experiments.instance:250","resource://app/components/ExperimentsService.js:ExperimentsService.prototype._delayedInit:65","resource://services-common/utils.js:notify:182","null:null:0"]}]},{"name":"AddonRepository: async shutdown","state":"pending"}],"filename":"resource://gre/modules/AddonManager.jsm","lineNumber":813,"stack":["resource://gre/modules/AddonManager.jsm:AMI_startup:813","resource://gre/modules/AddonManager.jsm:AMP_startup:2393","resource://gre/components/addonManager.js:AMC_observe:55","null:null:0"]}] Barrier: profile-before-change
21:11:37 INFO - FATAL ERROR: AsyncShutdown timeout in AddonManager: Waiting for providers to shut down. Conditions: [{"name":"Experiments.jsm shutdown","state":{"isShutdown":true,"isEnabled":true,"isRefresh":false,"isDirty":false,"isFirstEvaluate":false,"hasLoadTask":false,"hasMainTask":true,"hasTimer":false,"hasAddonProvider":false,"latestLogs":["10: onUninstalled() - addon id: addon1@tests.mozilla.org","10: onUninstalled() - addon id: 4@personas.mozilla.org","10: onUninstalled() - addon id: remote1@tests.mozilla.org","10: updateManifest()","10: _run","10: _main iteration","10: _loadManifest","10: httpGetRequest(http://127.0.0.1:8888/experiments-dummy/manifest)","60: httpGetRequest::onLoad() - Request to http://127.0.0.1:8888/experiments-dummy/manifest returned status 404","60: _loadManifest - failure to fetch/parse manifest (continuing anyway): Error: Experiments - XHR status for http://127.0.0.1:8888/experiments-dummy/manifest is 404","10: _evaluateExperiments","10: uninit: started","10: uninit: finished with _loadTask","10: uninit: no previous shutdown","10: Unregistering instance with Addon Manager.","10: Removing install listener from add-on manager.","10: Removing addon listener from add-on manager.","10: Finished unregistering with addon manager.","10: Unregistering previous experiment add-on provider.","10: uninit: waiting on _mainTask"],"experiments":{},"terminateReason":null},"filename":"resource://app/modules/experiments/Experiments.jsm","lineNumber":435,"stack":["resource://app/modules/experiments/Experiments.jsm:Experiments.Experiments.prototype.init:435","resource://app/modules/experiments/Experiments.jsm:Experiments.Experiments:416","resource://app/modules/experiments/Experiments.jsm:Experiments.instance:250","resource://app/components/ExperimentsService.js:ExperimentsService.prototype._delayedInit:65","resource://services-common/utils.js:notify:182","null:null:0"]}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
21:11:37 INFO - [Parent 1843] ###!!! ABORT: file resource://app/modules/experiments/Experiments.jsm, line 435
21:11:37 INFO - [Parent 1843] ###!!! ABORT: file resource://app/modules/experiments/Experiments.jsm, line 435
21:11:38 INFO - TEST-INFO | Main app process: killed by SIGSEGV
21:11:38 INFO - 250 INFO checking window state
21:11:38 INFO - 251 INFO Console message: [JavaScript Error: "1413087032066 Browser.Experiments.Experiments ERROR Experiments #0::_loadManifest - failure to fetch/parse manifest (continuing anyway): Error: Experiments - XHR status for http://127.0.0.1:8888/experiments-dummy/manifest is 404" {file: "resource://gre/modules/Log.jsm" line: 749}]
21:11:38 INFO - 252 INFO Console message: 1413087032068 Browser.Experiments.Experiments TRACE Experiments #0::_evaluateExperiments
21:11:38 INFO - 253 INFO Console message: [JavaScript Error: "1413087032132 addons.manager ERROR Exception calling provider <unnamed-provider>.getAddonsByTypes: TypeError: this.timerLocations is null (chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/head.js:1041:4) JS Stack trace: MP_delayCallback@head.js:1041:5 < MP_getAddonsByTypes@head.js:887:5 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < this.Experiments.PreviousExperimentProvider.prototype<.getAddonsByTypes@Experiments.jsm:2304:5 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < SocialAddonProvider.getAddonsByTypes@SocialService.jsm:1031:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < PL_getAddonsByTypes@PluginProvider.jsm:135:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < OpenH264Provider.getAddonsByTypes@OpenH264Provider.jsm:362:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < LightweightThemeManager_getAddonsByTypes@LightweightThemeManager.jsm:388:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < getAddonsByTypes_getVisibleAddons@XPIProvider.jsm:3822:7 < makeSafe/<@XPIProviderUtils.js:146:17 < asyncMap@XPIProviderUtils.js:174:5 < this.XPIDatabase.getAddonList/<@XPIProviderUtils.js:1051:9 < Handler.prototype.process@Promise-backend.js:865:23 < this.PromiseWalker.walkerLoop@Promise-backend.js:744:7" {file: "resource://gre/modules/Log.jsm" line: 749}]
21:11:38 INFO - 254 INFO Console message: 1413087032134 addons.manager WARN Exception calling callback: TypeError: a is undefined (resource://app/modules/experiments/Experiments.jsm:203:46) JS Stack trace: installedExperimentAddons/<@Experiments.jsm:203:47 < safeCall@AddonManager.jsm:173:5 < getAddonsByTypes_noMoreObjects@AddonManager.jsm:2135:9 < AOC_callNext@AddonManager.jsm:305:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < callProviderAsync@AddonManager.jsm:239:5 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < this.Experiments.PreviousExperimentProvider.prototype<.getAddonsByTypes@Experiments.jsm:2304:5 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < SocialAddonProvider.getAddonsByTypes@SocialService.jsm:1031:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < PL_getAddonsByTypes@PluginProvider.jsm:135:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < OpenH264Provider.getAddonsByTypes@OpenH264Provider.jsm:362:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < LightweightThemeManager_getAddonsByTypes@LightweightThemeManager.jsm:388:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < getAddonsByTypes_getVisibleAddons@XPIProvider.jsm:3822:7 < makeSafe/<@XPIProviderUtils.js:146:17 < asyncMap@XPIProviderUtils.js:174:5 < this.XPIDatabase.getAddonList/<@XPIProviderUtils.js:1051:9 < Handler.prototype.process@Promise-backend.js:865:23 < this.PromiseWalker.walkerLoop@Promise-backend.js:744:7
21:11:38 INFO - 255 INFO TEST-START | Shutdown
21:11:38 INFO - 256 INFO Browser Chrome Test Summary
21:11:38 INFO - 257 INFO Passed: 8967
21:11:38 INFO - 258 INFO Failed: 0
21:11:38 INFO - 259 INFO Todo: 27
21:11:38 INFO - 260 INFO *** End BrowserChrome Test Results ***
21:11:38 INFO - 261 ERROR TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/browser_sorting_plugins.js | application terminated with exit code 11
21:11:38 INFO - runtests.py | Application ran for: 0:09:07.669811
21:11:38 INFO - zombiecheck | Reading PID log: /tmp/tmpBH2Asypidlog
21:11:38 INFO - ==> process 1843 launched child process 1879
21:11:38 INFO - zombiecheck | Checking for orphan process with PID: 1879
21:11:38 INFO - mozcrash Downloading symbols from: https://ftp-ssl.mozilla.org/pub/mozilla.org/firefox/tinderbox-builds/mozilla-inbound-linux-pgo/1413081024/firefox-35.0a1.en-US.linux-i686.crashreporter-symbols.zip
21:12:07 INFO - mozcrash Saved minidump as /builds/slave/test/build/blobber_upload_dir/0dbc1545-642f-b27f-7dede456-53c95f66.dmp
21:12:07 INFO - mozcrash Saved app info as /builds/slave/test/build/blobber_upload_dir/0dbc1545-642f-b27f-7dede456-53c95f66.extra
21:12:07 WARNING - PROCESS-CRASH | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/browser_sorting_plugins.js | application crashed [@ mozalloc_abort(char const*)]
21:12:07 INFO - Crash dump filename: /tmp/tmpZFd8DP.mozrunner/minidumps/0dbc1545-642f-b27f-7dede456-53c95f66.dmp
21:12:07 INFO - Operating system: Linux
21:12:07 INFO - 0.0.0 Linux 3.2.0-23-generic-pae #36-Ubuntu SMP Tue Apr 10 22:19:09 UTC 2012 i686
21:12:07 INFO - CPU: x86
21:12:07 INFO - GenuineIntel family 6 model 62 stepping 4
21:12:07 INFO - 1 CPU
21:12:07 INFO - Crash reason: SIGSEGV
21:12:07 INFO - Crash address: 0x0
21:12:07 INFO - Thread 0 (crashed)
21:12:07 INFO - 0 libmozalloc.so!mozalloc_abort(char const*) [mozalloc_abort.cpp:c9fa1a711dc7 : 37 + 0x0]
21:12:07 INFO - eip = 0xb730706a esp = 0xbff47110 ebp = 0xbff47128 ebx = 0xb730a750
21:12:07 INFO - esi = 0xb75b4d9c edi = 0xbff47168 eax = 0x0000000a ecx = 0xffffffff
21:12:07 INFO - edx = 0xb75b58ac efl = 0x00010286
21:12:07 INFO - Found by: given as instruction pointer in context
21:12:07 INFO - 1 libxul.so!NS_DebugBreak [nsDebugImpl.cpp:c9fa1a711dc7 : 469 + 0xd]
21:12:07 INFO - eip = 0xb36702f3 esp = 0xbff47130 ebp = 0xbff47578 ebx = 0xb6de46dc
21:12:07 INFO - esi = 0xbff47150 edi = 0xbff47168
21:12:07 INFO - Found by: call frame info
21:12:07 INFO - 2 libxul.so!nsDebugImpl::Abort(char const*, int) [nsDebugImpl.cpp:c9fa1a711dc7 : 150 + 0x1e]
21:12:07 INFO - eip = 0xb3670688 esp = 0xbff47580 ebp = 0xbff475a8 ebx = 0xb6de46dc
21:12:07 INFO - esi = 0xbff476e0 edi = 0xbff476d8
21:12:07 INFO - Found by: call frame info
21:12:07 INFO - 3 libxul.so + 0x625b1b
21:12:07 INFO - eip = 0xb36b7b1c esp = 0xbff475b0 ebp = 0xbff475d8 ebx = 0xb6de46dc
21:12:07 INFO - esi = 0xbff476e0 edi = 0xbff476d8
21:12:07 INFO - Found by: call frame info
21:12:07 INFO - 4 libxul.so!XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) [XPCWrappedNative.cpp:c9fa1a711dc7 : 2395 + 0x15]
21:12:07 INFO - eip = 0xb33f81f9 esp = 0xbff475e0 ebp = 0xbff47788
21:12:07 INFO - Found by: previous frame's frame pointer
21:12:07 INFO - 5 libxul.so!XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) [XPCWrappedNativeJSOps.cpp:c9fa1a711dc7 : 1247 + 0xd]
21:12:07 INFO - eip = 0xb33fac0b esp = 0xbff47790 ebp = 0xbff47878 ebx = 0xb6de46dc
21:12:07 INFO - esi = 0xbff47804 edi = 0x00000000
21:12:07 INFO - Found by: call frame info
21:12:07 INFO - 6 libxul.so!js::Invoke(JSContext*, JS::CallArgs, js::MaybeConstruct) [jscntxtinlines.h:c9fa1a711dc7 : 231 + 0x15]
21:12:07 INFO - eip = 0xb3619d05 esp = 0xbff47880 ebp = 0xbff47b28 ebx = 0xb6de46dc
21:12:07 INFO - esi = 0xb7260aa0 edi = 0xb33faa70
21:12:07 INFO - Found by: call frame info
21:12:07 INFO - 7 libxul.so!Interpret [Interpreter.cpp:c9fa1a711dc7 : 2547 + 0x27]
21:12:07 INFO - eip = 0xb360f0fd esp = 0xbff47b30 ebp = 0xbff47fc8 ebx = 0xb6de46dc
21:12:07 INFO - esi = 0xb7260aa0 edi = 0x00000000
21:12:07 INFO - Found by: call frame info
21:12:07 INFO - 8 libxul.so!js::RunScript(JSContext*, js::RunState&) [Interpreter.cpp:c9fa1a711dc7 : 432 + 0x8]
21:12:07 INFO - eip = 0xb3619ad1 esp = 0xbff47fd0 ebp = 0xbff48018 ebx = 0xb6de46dc
21:12:07 INFO - esi = 0xb7260aa0 edi = 0xbff4807c
21:12:07 INFO - Found by: call frame info
21:12:07 INFO - 9 libxul.so!js::Invoke(JSContext*, JS::CallArgs, js::MaybeConstruct) [Interpreter.cpp:c9fa1a711dc7 : 501 + 0x11]
21:12:07 INFO - eip = 0xb3619de8 esp = 0xbff48020 ebp = 0xbff482c8 ebx = 0xb6de46dc
21:12:07 INFO - esi = 0xb7260aa0 edi = 0xbff4807c
21:12:07 INFO - Found by: call frame info
21:12:07 INFO - 10 libxul.so!js::CallOrConstructBoundFunction(JSContext*, unsigned int, JS::Value*) [jsfun.cpp:c9fa1a711dc7 : 1563 + 0x15]
21:12:07 INFO - eip = 0xb35cb577 esp = 0xbff482d0 ebp = 0xbff48398 ebx = 0xb6de46dc
21:12:07 INFO - esi = 0xb7260aa0 edi = 0x00000000
21:12:07 INFO - Found by: call frame info
Georg, do you have time to look at this?
Component: Add-ons Manager → Client: Desktop
Flags: needinfo?(georg.fritzsche)
Product: Toolkit → Firefox Health Report
So interesting parts are the two stacks below.
We start out with the same "this.timerLocations is null" from bug x, but after the patch from there we end up invoking the AddonListCallback() for getAddonsByTypes() with |undefined| instead of |[]| as we should here:
http://hg.mozilla.org/mozilla-central/annotate/29fbfc1b31aa/browser/experiments/Experiments.jsm#l203

Irving, i think we need to talk about what to do here as i'm not familiar with the AsyncObjectCaller etc.


21:11:38 INFO - 253 INFO Console message: [JavaScript Error: "1413087032132 addons.manager ERROR Exception calling provider <unnamed-provider>.getAddonsByTypes: TypeError: this.timerLocations is null (chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser/test-window/head.js:1041:4) JS Stack trace: MP_delayCallback@head.js:1041:5 < MP_getAddonsByTypes@head.js:887:5 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < this.Experiments.PreviousExperimentProvider.prototype<.getAddonsByTypes@Experiments.jsm:2304:5 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < SocialAddonProvider.getAddonsByTypes@SocialService.jsm:1031:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < PL_getAddonsByTypes@PluginProvider.jsm:135:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < OpenH264Provider.getAddonsByTypes@OpenH264Provider.jsm:362:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < LightweightThemeManager_getAddonsByTypes@LightweightThemeManager.jsm:388:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < getAddonsByTypes_getVisibleAddons@XPIProvider.jsm:3822:7 < makeSafe/<@XPIProviderUtils.js:146:17 < asyncMap@XPIProviderUtils.js:174:5 < this.XPIDatabase.getAddonList/<@XPIProviderUtils.js:1051:9 < Handler.prototype.process@Promise-backend.js:865:23 < this.PromiseWalker.walkerLoop@Promise-backend.js:744:7" {file: "resource://gre/modules/Log.jsm" line: 749}]


21:11:38 INFO - 254 INFO Console message: 1413087032134 addons.manager WARN Exception calling callback: TypeError: a is undefined (resource://app/modules/experiments/Experiments.jsm:203:46) JS Stack trace: installedExperimentAddons/<@Experiments.jsm:203:47 < safeCall@AddonManager.jsm:173:5 < getAddonsByTypes_noMoreObjects@AddonManager.jsm:2135:9 < AOC_callNext@AddonManager.jsm:305:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < callProviderAsync@AddonManager.jsm:239:5 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < this.Experiments.PreviousExperimentProvider.prototype<.getAddonsByTypes@Experiments.jsm:2304:5 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < SocialAddonProvider.getAddonsByTypes@SocialService.jsm:1031:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < PL_getAddonsByTypes@PluginProvider.jsm:135:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < OpenH264Provider.getAddonsByTypes@OpenH264Provider.jsm:362:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < LightweightThemeManager_getAddonsByTypes@LightweightThemeManager.jsm:388:7 < callProviderAsync@AddonManager.jsm:235:12 < getAddonsByTypes_nextObject@AddonManager.jsm:2127:1 < AOC_callNext@AddonManager.jsm:311:7 < getAddonsByTypes_concatAddons@AddonManager.jsm:2130:11 < getAddonsByTypes_getVisibleAddons@XPIProvider.jsm:3822:7 < makeSafe/<@XPIProviderUtils.js:146:17 < asyncMap@XPIProviderUtils.js:174:5 < this.XPIDatabase.getAddonList/<@XPIProviderUtils.js:1051:9 < Handler.prototype.process@Promise-backend.js:865:23 < this.PromiseWalker.walkerLoop@Promise-backend.js:744:7
Flags: needinfo?(georg.fritzsche)
[Tracking Requested - why for this release]:
This may affect more scenarios then the ones originally adressed by bug 1074135.
Depends on: 1074135
Depends on: 1075625
Bah, this is another latent bug in AddonManager - we have always mistakenly pushed an 'undefined' onto the list of found objects if a provider doesn't support the callback being called, we just never had a case where we had a provider that didn't implement these callbacks. Now that we're also returning undefined if the provider throws, we see the problem.

The quick fix is to false-check and not add these to the array; the "correct" fix is to replace AsyncObjectCaller with something comprehensible by mortals (or something based on Promises).
Assignee: nobody → irving
Status: NEW → ASSIGNED
Attachment #8508770 - Flags: review?(dtownsend+bugmail)
Attachment #8508770 - Flags: review?(bmcbride)
Irving/Georg - Do you know if this impacts all branches and how far back this issue goes?
This bug was revealed by the fixes in bug 1074135, which are currently on beta (they were uplifted to 34 just before merge day). Note that this is a different manifestation of the same problem - the Experiments provider does not shut down cleanly, we're just moving the error report from place to place (and making it more visible and less disruptive) until we fix bug 1075625.

33 will be affected if we choose to uplift 1074135, which is currently being discussed.
Attachment #8508770 - Flags: review?(dtownsend+bugmail)
Attachment #8508770 - Flags: review?(bmcbride)
Attachment #8508770 - Flags: review+
Component: Client: Desktop → Add-ons Manager
Keywords: checkin-needed
OS: Linux → All
Product: Firefox Health Report → Toolkit
Hardware: x86 → All
Please nominate this for Aurora/Beta/Release approval when you get a chance.
Flags: needinfo?(irving)
Comment on attachment 8508770 [details] [diff] [review]
Quick fix: null check before pushing results onto arrays

Approval Request Comment
[Feature/regressing bug #]: 1057312
[User impact if declined]: Errors during Experiments provider shutdown
[Describe test coverage new/current, TBPL]: no significant test failures on mozilla-inbound https://treeherder.mozilla.org/ui/#/jobs?repo=mozilla-inbound&revision=0957962d7079
[Risks and why]: very low risk, only error checks added
[String/UUID change made/needed]: None
Flags: needinfo?(irving)
Attachment #8508770 - Flags: approval-mozilla-release?
Attachment #8508770 - Flags: approval-mozilla-beta?
Attachment #8508770 - Flags: approval-mozilla-aurora?
https://hg.mozilla.org/mozilla-central/rev/0957962d7079
Status: ASSIGNED → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla36
Comment on attachment 8508770 [details] [diff] [review]
Quick fix: null check before pushing results onto arrays

Only approving up to Beta as I'm not clear on whether this is riding along in 33.0.1
Attachment #8508770 - Flags: approval-mozilla-beta?
Attachment #8508770 - Flags: approval-mozilla-beta+
Attachment #8508770 - Flags: approval-mozilla-aurora?
Attachment #8508770 - Flags: approval-mozilla-aurora+
Comment on attachment 8508770 [details] [diff] [review]
Quick fix: null check before pushing results onto arrays

Dear Sheriff, please land that also in GECKO330_2014101104_RELBRANCH
Thanks
Attachment #8508770 - Flags: approval-mozilla-release? → approval-mozilla-release+
You need to log in before you can comment on or make changes to this bug.