Closed Bug 1422442 Opened 7 years ago Closed 7 years ago

Intermittent toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | xpcshell return code: 1512146035494

Categories

(WebExtensions :: Android, defect, P5)

Product:
WebExtensions
Component:
Android
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

Bug 1422442 - Fix Intermittent test_ext_browserSettings.js by starting the addon manager earlier,
59 bytes, text/x-review-board-request
aswan
: review+
Details
Here is part of the log: [task 2017-12-01T16:33:58.104Z] 16:33:58 WARNING - TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | xpcshell return code: 1512146035494 2203 [task 2017-12-01T16:33:58.105Z] 16:33:58 INFO - TEST-INFO took 7023ms 2204 [task 2017-12-01T16:33:58.105Z] 16:33:58 INFO - >>>>>>> 2205 [task 2017-12-01T16:33:58.105Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | xpcw: cd /storage/sdcard/tests/xpc/toolkit/components/extensions/test/xpcshell 2206 [task 2017-12-01T16:33:58.106Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | xpcw: xpcshell -r /storage/sdcard/tests/xpc/c/httpd.manifest --greomni /data/local/xpcb/target.apk -m -s -e const _HEAD_JS_PATH = "/storage/sdcard/tests/xpc/head.js"; -e const _MOZINFO_JS_PATH = "/storage/sdcard/tests/xpc/p/mozinfo.json"; -e const _TESTING_MODULES_DIR = "/storage/sdcard/tests/xpc/m"; -f /storage/sdcard/tests/xpc/head.js -e const _SERVER_ADDR = "localhost" -e const _HEAD_FILES = ["/storage/sdcard/tests/xpc/toolkit/components/extensions/test/xpcshell/head.js", "/storage/sdcard/tests/xpc/toolkit/components/extensions/test/xpcshell/head_telemetry.js"]; -e const _JSDEBUGGER_PORT = 0; -e const _TEST_FILE = ["test_ext_browserSettings.js"]; -e const _TEST_NAME = "xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js" -e _execute_test(); quit(0); 2207 [task 2017-12-01T16:33:58.106Z] 16:33:58 INFO - (xpcshell/head.js) | test MAIN run_test pending (1) 2208 [task 2017-12-01T16:33:58.106Z] 16:33:58 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2) 2209 [task 2017-12-01T16:33:58.107Z] 16:33:58 INFO - (xpcshell/head.js) | test MAIN run_test finished (2) 2210 [task 2017-12-01T16:33:58.107Z] 16:33:58 INFO - running event loop 2211 [task 2017-12-01T16:33:58.107Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | Starting check_remote 2212 [task 2017-12-01T16:33:58.107Z] 16:33:58 INFO - (xpcshell/head.js) | test check_remote pending (2) 2213 [task 2017-12-01T16:33:58.108Z] 16:33:58 INFO - TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | check_remote - [check_remote : 241] useRemoteWebExtensions matches - false == false 2214 [task 2017-12-01T16:33:58.108Z] 16:33:58 INFO - TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | check_remote - [check_remote : 241] testing from extension process - true == true 2215 [task 2017-12-01T16:33:58.108Z] 16:33:58 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2) 2216 [task 2017-12-01T16:33:58.109Z] 16:33:58 INFO - (xpcshell/head.js) | test run_next_test 1 pending (2) 2217 [task 2017-12-01T16:33:58.109Z] 16:33:58 INFO - (xpcshell/head.js) | test check_remote finished (2) 2218 [task 2017-12-01T16:33:58.109Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | Starting test_browser_settings 2219 [task 2017-12-01T16:33:58.109Z] 16:33:58 INFO - (xpcshell/head.js) | test test_browser_settings pending (2) 2220 [task 2017-12-01T16:33:58.110Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034108 addons.manager DEBUG Application has been upgraded 2221 [task 2017-12-01T16:33:58.110Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034496 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"] 2222 [task 2017-12-01T16:33:58.110Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034557 addons.manager DEBUG Loaded provider scope for resource://gre/modules/LightweightThemeManager.jsm: ["LightweightThemeManager"] 2223 [task 2017-12-01T16:33:58.110Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034647 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm 2224 [task 2017-12-01T16:33:58.111Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034659 addons.manager DEBUG Starting provider: XPIProvider 2225 [task 2017-12-01T16:33:58.111Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034669 addons.xpi DEBUG startup 2226 [task 2017-12-01T16:33:58.111Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034683 addons.xpi INFO SystemAddonInstallLocation directory is missing 2227 [task 2017-12-01T16:33:58.112Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034688 addons.xpi INFO Removing all system add-on upgrades. 2228 [task 2017-12-01T16:33:58.113Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034699 addons.xpi WARN Failed to add built-in install location app-system-defaults: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIProperties.get]" nsresult: "0x80004005 (NS_ERROR_FAILURE)" location: "JS frame :: resource://gre/modules/FileUtils.jsm :: FileUtils_getDir :: line 64" data: no] Stack trace: FileUtils_getDir()@resource://gre/modules/FileUtils.jsm:64 < addBuiltInInstallLocation()@resource://gre/modules/addons/XPIProvider.jsm:2044 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2121 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:740 < startup()@resource://gre/modules/AddonManager.jsm:907 < startup()@resource://gre/modules/AddonManager.jsm:3089 < observe()@jar:jar:file:///data/local/xpcb/target.apk!/assets/omni.ja!/components/addonManager.js:65 < promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:585 < test_browser_settings()@test_ext_browserSettings.js:70 < asyncFunction()@resource://gre/modules/Task.jsm:241 < Task_spawn()@resource://gre/modules/Task.jsm:166 < _run_next_test()@/storage/sdcard/tests/xpc/head.js:1494 < run()@/storage/sdcard/tests/xpc/head.js:703 < _do_main()@/storage/sdcard/tests/xpc/head.js:224 < _execute_test()@/storage/sdcard/tests/xpc/head.js:546 < -e:1 2229 [task 2017-12-01T16:33:58.113Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034761 addons.xpi DEBUG checkForChanges 2230 [task 2017-12-01T16:33:58.113Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034772 addons.xpi DEBUG Loaded add-on state: ${} 2231 [task 2017-12-01T16:33:58.113Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | JavaScript strict warning: resource://gre/modules/addons/XPIProvider.jsm, line 1569: ReferenceError: reference to undefined property "app-temporary" 2232 [task 2017-12-01T16:33:58.113Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034806 addons.xpi DEBUG getInstallState changed: false, state: {} 2233 [task 2017-12-01T16:33:58.114Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034820 addons.xpi INFO SystemAddonInstallLocation directory is missing 2234 [task 2017-12-01T16:33:58.114Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034824 addons.xpi DEBUG Empty XPI database, setting schema version preference to 23 2235 [task 2017-12-01T16:33:58.114Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146034835 addons.xpi DEBUG No changes found 2236 [task 2017-12-01T16:33:58.114Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146035017 addons.manager DEBUG Registering shutdown blocker for XPIProvider 2237 [task 2017-12-01T16:33:58.115Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146035026 addons.manager DEBUG Provider finished startup: XPIProvider 2238 [task 2017-12-01T16:33:58.115Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146035031 addons.manager DEBUG Starting provider: LightweightThemeManager 2239 [task 2017-12-01T16:33:58.115Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146035037 addons.manager DEBUG Registering shutdown blocker for LightweightThemeManager 2240 [task 2017-12-01T16:33:58.115Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146035044 addons.manager DEBUG Provider finished startup: LightweightThemeManager 2241 [task 2017-12-01T16:33:58.116Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146035054 addons.manager DEBUG Starting provider: GMPProvider 2242 [task 2017-12-01T16:33:58.116Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146035127 addons.manager DEBUG Registering shutdown blocker for GMPProvider 2243 [task 2017-12-01T16:33:58.116Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146035138 addons.manager DEBUG Provider finished startup: GMPProvider 2244 [task 2017-12-01T16:33:58.117Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | 1512146035203 addons.manager DEBUG Completed startup sequence 2245 [task 2017-12-01T16:33:58.117Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | JavaScript strict warning: resource://testing-common/ExtensionXPCShellUtils.jsm, line 471: ReferenceError: reference to undefined property "id" 2246 [task 2017-12-01T16:33:58.117Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | console.error: 2247 [task 2017-12-01T16:33:58.117Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | [Exception... "aID must be a non-empty string" nsresult: "0x80070057 (NS_ERROR_ILLEGAL_VALUE)" location: "JS frame :: resource://gre/modules/AddonManager.jsm :: getAddonByID :: line 2403" data: no]: getAddonByID@resource://gre/modules/AddonManager.jsm:2403:13 2248 [task 2017-12-01T16:33:58.118Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | getAddonByID@resource://gre/modules/AddonManager.jsm:3583:7 2249 [task 2017-12-01T16:33:58.118Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | onEvent@resource://testing-common/ExtensionXPCShellUtils.jsm:471:9 2250 [task 2017-12-01T16:33:58.118Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | emit@resource://gre/modules/EventEmitter.jsm:152:11 2251 [task 2017-12-01T16:33:58.118Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | promiseStartupManager@resource://testing-common/AddonTestUtils.jsm:587:5 2252 [task 2017-12-01T16:33:58.119Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | test_browser_settings@test_ext_browserSettings.js:70:9 2253 [task 2017-12-01T16:33:58.119Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | asyncFunction@resource://gre/modules/Task.jsm:241:18 2254 [task 2017-12-01T16:33:58.119Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | Task_spawn@resource://gre/modules/Task.jsm:166:12 2255 [task 2017-12-01T16:33:58.119Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | _run_next_test@/storage/sdcard/tests/xpc/head.js:1494:9 2256 [task 2017-12-01T16:33:58.120Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | run@/storage/sdcard/tests/xpc/head.js:703:9 2257 [task 2017-12-01T16:33:58.120Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | _do_main@/storage/sdcard/tests/xpc/head.js:224:3 2258 [task 2017-12-01T16:33:58.120Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | _execute_test@/storage/sdcard/tests/xpc/head.js:546:5 2259 [task 2017-12-01T16:33:58.120Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | @-e:1:1 2260 [task 2017-12-01T16:33:58.121Z] 16:33:58 INFO - (xpcshell/head.js) | test run_next_test 1 finished (2) 2261 [task 2017-12-01T16:33:58.121Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034108 addons.manager DEBUG Application has been upgraded" 2262 [task 2017-12-01T16:33:58.121Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034496 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]" 2263 [task 2017-12-01T16:33:58.122Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034557 addons.manager DEBUG Loaded provider scope for resource://gre/modules/LightweightThemeManager.jsm: ["LightweightThemeManager"]" 2264 [task 2017-12-01T16:33:58.122Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034647 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm" 2265 [task 2017-12-01T16:33:58.122Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034659 addons.manager DEBUG Starting provider: XPIProvider" 2266 [task 2017-12-01T16:33:58.122Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034669 addons.xpi DEBUG startup" 2267 [task 2017-12-01T16:33:58.123Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034683 addons.xpi INFO SystemAddonInstallLocation directory is missing" 2268 [task 2017-12-01T16:33:58.123Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034688 addons.xpi INFO Removing all system add-on upgrades." 2269 [task 2017-12-01T16:33:58.124Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034699 addons.xpi WARN Failed to add built-in install location app-system-defaults: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIProperties.get]" nsresult: "0x80004005 (NS_ERROR_FAILURE)" location: "JS frame :: resource://gre/modules/FileUtils.jsm :: FileUtils_getDir :: line 64" data: no] Stack trace: FileUtils_getDir()@resource://gre/modules/FileUtils.jsm:64 < addBuiltInInstallLocation()@resource://gre/modules/addons/XPIProvider.jsm:2044 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2121 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:740 < startup()@resource://gre/modules/AddonManager.jsm:907 < startup()@resource://gre/modules/AddonManager.jsm:3089 < observe()@jar:jar:file:///data/local/xpcb/target.apk!/assets/omni.ja!/components/addonManager.js:65 < promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:585 < test_browser_settings()@test_ext_browserSettings.js:70 < asyncFunction()@resource://gre/modules/Task.jsm:241 < Task_spawn()@resource://gre/modules/Task.jsm:166 < _run_next_test()@/storage/sdcard/tests/xpc/head.js:1494 < run()@/storage/sdcard/tests/xpc/head.js:703 < _do_main()@/storage/sdcard/tests/xpc/head.js:224 < _execute_test()@/storage/sdcard/tests/xpc/head.js:546 < -e:1" 2270 [task 2017-12-01T16:33:58.124Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034761 addons.xpi DEBUG checkForChanges" 2271 [task 2017-12-01T16:33:58.124Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034772 addons.xpi DEBUG Loaded add-on state: ${}" 2272 [task 2017-12-01T16:33:58.125Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "app-temporary"" {file: "resource://gre/modules/addons/XPIProvider.jsm" line: 1569}]" 2273 [task 2017-12-01T16:33:58.125Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034806 addons.xpi DEBUG getInstallState changed: false, state: {}" 2274 [task 2017-12-01T16:33:58.125Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034820 addons.xpi INFO SystemAddonInstallLocation directory is missing" 2275 [task 2017-12-01T16:33:58.125Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034824 addons.xpi DEBUG Empty XPI database, setting schema version preference to 23" 2276 [task 2017-12-01T16:33:58.125Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146034835 addons.xpi DEBUG No changes found" 2277 [task 2017-12-01T16:33:58.126Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146035017 addons.manager DEBUG Registering shutdown blocker for XPIProvider" 2278 [task 2017-12-01T16:33:58.126Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146035026 addons.manager DEBUG Provider finished startup: XPIProvider" 2279 [task 2017-12-01T16:33:58.126Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146035031 addons.manager DEBUG Starting provider: LightweightThemeManager" 2280 [task 2017-12-01T16:33:58.127Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146035037 addons.manager DEBUG Registering shutdown blocker for LightweightThemeManager" 2281 [task 2017-12-01T16:33:58.127Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146035044 addons.manager DEBUG Provider finished startup: LightweightThemeManager" 2282 [task 2017-12-01T16:33:58.127Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146035054 addons.manager DEBUG Starting provider: GMPProvider" 2283 [task 2017-12-01T16:33:58.128Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146035127 addons.manager DEBUG Registering shutdown blocker for GMPProvider" 2284 [task 2017-12-01T16:33:58.128Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146035138 addons.manager DEBUG Provider finished startup: GMPProvider" 2285 [task 2017-12-01T16:33:58.128Z] 16:33:58 INFO - "CONSOLE_MESSAGE: (info) 1512146035203 addons.manager DEBUG Completed startup sequence" 2286 [task 2017-12-01T16:33:58.129Z] 16:33:58 INFO - xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js | {"action":"log","time":1512146035494,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"\\"CONSOLE_MESSAGE: (warn) [JavaScript Warning: \\"ReferenceError: reference to undefined property \\"id\\"\\" {file: \\"resource://testing-common/ExtensionXPCShellUtils.jsm\\" line: 471}]\\"" 2287 [task 2017-12-01T16:33:58.129Z] 16:33:58 INFO - <<<<<<< 2288 [task 2017-12-01T16:33:58.150Z] 16:33:58 INFO - mozdevice getDirectory() failed after ['pull', '/storage/sdcard/tests/xpc/minidumps', '/tmp/tmphR8wuq/minidumps'] 2289 [task 2017-12-01T16:33:58.150Z] 16:33:58 INFO - mozdevice rc=1 out=['adb: error: failed to get feature set: no devices/emulators found'] 2290 [task 2017-12-01T16:33:58.159Z] 16:33:58 INFO - Following exceptions were raised: 2291 [task 2017-12-01T16:33:58.160Z] 16:33:58 INFO - Traceback (most recent call last): 2292 [task 2017-12-01T16:33:58.163Z] 16:33:58 INFO - File "/builds/worker/workspace/build/tests/xpcshell/runxpcshelltests.py", line 181, in run 2293 [task 2017-12-01T16:33:58.163Z] 16:33:58 INFO - self.run_test() 2294 [task 2017-12-01T16:33:58.163Z] 16:33:58 INFO - File "/builds/worker/workspace/build/tests/xpcshell/runxpcshelltests.py", line 777, in run_test 2295 [task 2017-12-01T16:33:58.163Z] 16:33:58 INFO - if self.checkForCrashes(self.tempDir, self.symbolsPath, test_name=name): 2296 [task 2017-12-01T16:33:58.163Z] 16:33:58 INFO - File "/builds/worker/workspace/build/tests/xpcshell/remotexpcshelltests.py", line 181, in checkForCrashes 2297 [task 2017-12-01T16:33:58.163Z] 16:33:58 INFO - self.device.getDirectory(self.remoteMinidumpDir, dumpDir) 2298 [task 2017-12-01T16:33:58.163Z] 16:33:58 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozdevice/devicemanagerADB.py", line 545, in getDirectory 2299 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - dir_util.copy_tree(localDir, originalLocal) 2300 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - File "/usr/lib/python2.7/distutils/dir_util.py", line 128, in copy_tree 2301 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - "cannot copy tree '%s': not a directory" % src 2302 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - DistutilsFileError: cannot copy tree '/tmp/tmphR8wuq/minidumps': not a directory 2303 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - Traceback (most recent call last): 2304 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - File "/builds/worker/workspace/build/tests/xpcshell/remotexpcshelltests.py", line 628, in <module> 2305 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - main() 2306 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - File "/builds/worker/workspace/build/tests/xpcshell/remotexpcshelltests.py", line 623, in main 2307 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - mobileArgs=xpcsh.mobileArgs): 2308 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - File "/builds/worker/workspace/build/tests/xpcshell/runxpcshelltests.py", line 1370, in runTests 2309 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - mobileArgs, **kwargs) 2310 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - File "/builds/worker/workspace/build/tests/xpcshell/runxpcshelltests.py", line 1565, in runTestList 2311 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - raise exceptions[0] 2312 [task 2017-12-01T16:33:58.164Z] 16:33:58 INFO - distutils.errors.DistutilsFileError: cannot copy tree '/tmp/tmphR8wuq/minidumps': not a directory 2313 [task 2017-12-01T16:33:58.190Z] 16:33:58 ERROR - Return code: 1 2314 [task 2017-12-01T16:33:58.190Z] 16:33:58 ERROR - No tests run or test summary not found 2315 [task 2017-12-01T16:33:58.190Z] 16:33:58 INFO - TinderboxPrint: xpcshell<br/><em class="testfail">T-FAIL</em> 2316 [task 2017-12-01T16:33:58.190Z] 16:33:58 INFO - ##### xpcshell log ends 2317 [task 2017-12-01T16:33:58.191Z] 16:33:58 INFO - ##### test-1 emulator log begins 2318 [task 2017-12-01T16:33:58.191Z] 16:33:58 INFO - Reading from file /tmp/tmpaocr04 2319 [task 2017-12-01T16:33:58.191Z] 16:33:58 INFO - emulator: found SDK root at /builds/worker/workspace/build/android-sdk-linux 2320 [task 2017-12-01T16:33:58.192Z] 16:33:58 INFO - emulator: Android virtual device file at: /builds/worker/workspace/build/.android/avd/test-1.ini 2321 [task 2017-12-01T16:33:58.192Z] 16:33:58 INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.ini: parsing as .ini file 2322 [task 2017-12-01T16:33:58.192Z] 16:33:58 INFO - emulator: 1: KEY='avd.ini.encoding' VALUE='UTF-8' 2323 [task 2017-12-01T16:33:58.192Z] 16:33:58 INFO - emulator: 2: KEY='path' VALUE='/builds/worker/workspace/build/.android/avd/test-1.avd' 2324 [task 2017-12-01T16:33:58.192Z] 16:33:58 INFO - emulator: 3: KEY='path.rel' VALUE='avd/test-1.avd' 2325 [task 2017-12-01T16:33:58.193Z] 16:33:58 INFO - emulator: 4: KEY='target' VALUE='android-18' 2326 [task 2017-12-01T16:33:58.193Z] 16:33:58 INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.ini: parsing finished 2327 [task 2017-12-01T16:33:58.193Z] 16:33:58 INFO - emulator: virtual device content at /builds/worker/workspace/build/.android/avd/test-1.avd 2328 [task 2017-12-01T16:33:58.193Z] 16:33:58 INFO - emulator: virtual device config file: /builds/worker/workspace/build/.android/avd/test-1.avd/config.ini 2329 [task 2017-12-01T16:33:58.193Z] 16:33:58 INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.avd/config.ini: parsing as .ini file 2330 [task 2017-12-01T16:33:58.194Z] 16:33:58 INFO - emulator: 1: KEY='avd.ini.encoding' VALUE='UTF-8' 2331 [task 2017-12-01T16:33:58.194Z] 16:33:58 INFO - emulator: 2: KEY='abi.type' VALUE='armeabi-v7a' 2332 [task 2017-12-01T16:33:58.194Z] 16:33:58 INFO - emulator: 3: KEY='disk.dataPartition.size' VALUE='600M' 2333 [task 2017-12-01T16:33:58.194Z] 16:33:58 INFO - emulator: 4: KEY='hw.accelerometer' VALUE='yes' 2334 [task 2017-12-01T16:33:58.194Z] 16:33:58 INFO - emulator: 5: KEY='hw.audioInput' VALUE='yes' 2335 [task 2017-12-01T16:33:58.195Z] 16:33:58 INFO - emulator: 6: KEY='hw.battery' VALUE='yes' 2336 [task 2017-12-01T16:33:58.195Z] 16:33:58 INFO - emulator: 7: KEY='hw.camera.back' VALUE='emulated' 2337 [task 2017-12-01T16:33:58.195Z] 16:33:58 INFO - emulator: 8: KEY='hw.camera.front' VALUE='emulated' 2338 [task 2017-12-01T16:33:58.195Z] 16:33:58 INFO - emulator: 9: KEY='hw.cpu.arch' VALUE='arm' 2339 [task 2017-12-01T16:33:58.195Z] 16:33:58 INFO - emulator: 10: KEY='hw.cpu.model' VALUE='cortex-a8' 2340 [task 2017-12-01T16:33:58.195Z] 16:33:58 INFO - emulator: 11: KEY='hw.dPad' VALUE='yes' 2341 [task 2017-12-01T16:33:58.196Z] 16:33:58 INFO - emulator: 12: KEY='hw.device.hash2' VALUE='MD5:cb16ddb36f0092e8ab3125e954cd726d' 2342 [task 2017-12-01T16:33:58.196Z] 16:33:58 INFO - emulator: 13: KEY='hw.device.manufacturer' VALUE='User' 2343 [task 2017-12-01T16:33:58.196Z] 16:33:58 INFO - emulator: 14: KEY='hw.device.name' VALUE='mozilla-device' 2344 [task 2017-12-01T16:33:58.196Z] 16:33:58 INFO - emulator: 15: KEY='hw.gps' VALUE='yes' 2345 [task 2017-12-01T16:33:58.196Z] 16:33:58 INFO - emulator: 16: KEY='hw.gpu.enabled' VALUE='yes' 2346 [task 2017-12-01T16:33:58.197Z] 16:33:58 INFO - emulator: 17: KEY='hw.keyboard' VALUE='yes' 2347 [task 2017-12-01T16:33:58.197Z] 16:33:58 INFO - emulator: 18: KEY='hw.keyboard.lid' VALUE='yes' 2348 [task 2017-12-01T16:33:58.197Z] 16:33:58 INFO - emulator: 19: KEY='hw.lcd.density' VALUE='320' 2349 [task 2017-12-01T16:33:58.197Z] 16:33:58 INFO - emulator: 20: KEY='hw.mainKeys' VALUE='yes' 2350 [task 2017-12-01T16:33:58.197Z] 16:33:58 INFO - emulator: 21: KEY='hw.ramSize' VALUE='1024' 2351 [task 2017-12-01T16:33:58.198Z] 16:33:58 INFO - emulator: 22: KEY='hw.sdCard' VALUE='yes' 2352 [task 2017-12-01T16:33:58.198Z] 16:33:58 INFO - emulator: 23: KEY='hw.sensors.orientation' VALUE='yes' 2353 [task 2017-12-01T16:33:58.198Z] 16:33:58 INFO - emulator: 24: KEY='hw.sensors.proximity' VALUE='yes' 2354 [task 2017-12-01T16:33:58.198Z] 16:33:58 INFO - emulator: 25: KEY='hw.trackBall' VALUE='no' 2355 [task 2017-12-01T16:33:58.198Z] 16:33:58 INFO - emulator: 26: KEY='image.sysdir.1' VALUE='' 2356 [task 2017-12-01T16:33:58.199Z] 16:33:58 INFO - emulator: 27: KEY='sdcard.size' VALUE='600M' 2357 [task 2017-12-01T16:33:58.199Z] 16:33:58 INFO - emulator: 28: KEY='skin.dynamic' VALUE='yes' 2358 [task 2017-12-01T16:33:58.199Z] 16:33:58 INFO - emulator: 29: KEY='skin.name' VALUE='800x1280' 2359 [task 2017-12-01T16:33:58.199Z] 16:33:58 INFO - emulator: 30: KEY='skin.path' VALUE='800x1280' 2360 [task 2017-12-01T16:33:58.199Z] 16:33:58 INFO - emulator: 31: KEY='tag.display' VALUE='Default' 2361 [task 2017-12-01T16:33:58.200Z] 16:33:58 INFO - emulator: 32: KEY='tag.id' VALUE='default' 2362 [task 2017-12-01T16:33:58.200Z] 16:33:58 INFO - emulator: 33: KEY='vm.heapSize' VALUE='128' 2363 [task 2017-12-01T16:33:58.200Z] 16:33:58 INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.avd/config.ini: parsing finished 2364 [task 2017-12-01T16:33:58.200Z] 16:33:58 INFO - emulator: using core hw config path: /builds/worker/workspace/build/.android/avd/test-1.avd/hardware-qemu.ini 2365 [task 2017-12-01T16:33:58.200Z] 16:33:58 INFO - emulator: Found target field in root AVD .ini file: 'android-18' 2366 [task 2017-12-01T16:33:58.200Z] 16:33:58 INFO - emulator: Found AVD target API level: 18 2367 [task 2017-12-01T16:33:58.200Z] 16:33:58 INFO - emulator: found image search path: 2368 [task 2017-12-01T16:33:58.201Z] 16:33:58 INFO - emulator: found a total of 1 search paths for this AVD 2369 [task 2017-12-01T16:33:58.201Z] 16:33:58 INFO - emulator: no build.prop in search dir: /builds/worker/workspace/build/android-sdk-linux/ 2370 [task 2017-12-01T16:33:58.201Z] 16:33:58 INFO - emulator: No build.prop property file found. 2371 [task 2017-12-01T16:33:58.201Z] 16:33:58 INFO - emulator: no boot.prop in search dir: /builds/worker/workspace/build/android-sdk-linux/ 2372 [task 2017-12-01T16:33:58.201Z] 16:33:58 INFO - emulator: No boot.prop property file found. 2373 [task 2017-12-01T16:33:58.201Z] 16:33:58 INFO - emulator: Cannot find target CPU architecture, defaulting to 'arm' 2374 [task 2017-12-01T16:33:58.202Z] 16:33:58 INFO - emulator: Cannot find target CPU ABI, defaulting to 'armeabi' 2375 [task 2017-12-01T16:33:58.202Z] 16:33:58 INFO - emulator: 'magic' skin format detected: 800x1280 2376 [task 2017-12-01T16:33:58.202Z] 16:33:58 INFO - emulator: autoconfig: -skin 800x1280 2377 [task 2017-12-01T16:33:58.202Z] 16:33:58 INFO - emulator: autoconfig: -skindir (null) 2378 [task 2017-12-01T16:33:58.202Z] 16:33:58 INFO - emulator: no skin-specific hardware.ini in (null) 2379 [task 2017-12-01T16:33:58.202Z] 16:33:58 INFO - emulator: writing default keyset file to /builds/worker/workspace/build/.android/default.keyset 2380 [task 2017-12-01T16:33:58.203Z] 16:33:58 INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.avd/emulator-user.ini: parsing as .ini file 2381 [task 2017-12-01T16:33:58.203Z] 16:33:58 INFO - emulator: 1: KEY='window.x' VALUE='71' 2382 [task 2017-12-01T16:33:58.203Z] 16:33:58 INFO - emulator: 2: KEY='window.y' VALUE='52' 2383 [task 2017-12-01T16:33:58.203Z] 16:33:58 INFO - emulator: 3: KEY='uuid' VALUE='1426023745105' 2384 [task 2017-12-01T16:33:58.203Z] 16:33:58 INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.avd/emulator-user.ini: parsing finished 2385 [task 2017-12-01T16:33:58.203Z] 16:33:58 INFO - emulator: trying to load skin file '/builds/worker/workspace/build/android-sdk-linux/tools/lib/emulator/skins/dynamic//layout' 2386 [task 2017-12-01T16:33:58.204Z] 16:33:58 INFO - emulator: loaded dynamic skin width=800 height=1280 bpp=16 2387 [task 2017-12-01T16:33:58.204Z] 16:33:58 INFO - 2388 [task 2017-12-01T16:33:58.204Z] 16:33:58 INFO - emulator: autoconfig: -kernel /builds/worker/workspace/build/.android/avd/test-1.avd/kernel-qemu 2389 [task 2017-12-01T16:33:58.204Z] 16:33:58 INFO - emulator: Auto-detect: Kernel image requires legacy device naming scheme. 2390 [task 2017-12-01T16:33:58.204Z] 16:33:58 INFO - emulator: Auto-detect: Kernel does support YAFFS2 partitions. 2391 [task 2017-12-01T16:33:58.204Z] 16:33:58 INFO - emulator: autoconfig: -ramdisk /builds/worker/workspace/build/.android/avd/test-1.avd/ramdisk.img 2392 [task 2017-12-01T16:33:58.205Z] 16:33:58 INFO - emulator: Using initial system image: /builds/worker/workspace/build/.android/avd/test-1.avd/system.img 2393 [task 2017-12-01T16:33:58.205Z] 16:33:58 INFO - emulator: autoconfig: -data /builds/worker/workspace/build/.android/avd/test-1.avd/userdata-qemu.img 2394 [task 2017-12-01T16:33:58.205Z] 16:33:58 INFO - emulator: autoconfig: -initdata /builds/worker/workspace/build/.android/avd/test-1.avd/userdata.img 2395 [task 2017-12-01T16:33:58.205Z] 16:33:58 INFO - emulator: autoconfig: -cache /builds/worker/workspace/build/.android/avd/test-1.avd/cache.img 2396 [task 2017-12-01T16:33:58.205Z] 16:33:58 INFO - emulator: autoconfig: -sdcard /builds/worker/workspace/build/.android/avd/test-1.avd/sdcard.img 2397 [task 2017-12-01T16:33:58.205Z] 16:33:58 INFO - emulator: Physical RAM size: 1024MB
I'm not sure if its the cause of this error but it looks like this test tries to load an extension using the addon manager before starting the addon manager. That should probably fail in a more obvious way but I wonder if changing the sequence of events here would fix this particular problem: https://searchfox.org/mozilla-central/rev/1b7f664804adf9b0393df94e9ec92043ceb707d3/toolkit/components/extensions/test/xpcshell/test_ext_browserSettings.js#62-71
Flags: needinfo?(bob.silverberg)
(In reply to Andrew Swan [:aswan] from comment #2) > I'm not sure if its the cause of this error but it looks like this test > tries to load an extension using the addon manager before starting the addon > manager. That should probably fail in a more obvious way but I wonder if > changing the sequence of events here would fix this particular problem: > https://searchfox.org/mozilla-central/rev/ > 1b7f664804adf9b0393df94e9ec92043ceb707d3/toolkit/components/extensions/test/ > xpcshell/test_ext_browserSettings.js#62-71 It seems worth trying. I am attaching a patch for this.
Flags: needinfo?(bob.silverberg)
Comment on attachment 8934172 [details] Bug 1422442 - Fix Intermittent test_ext_browserSettings.js by starting the addon manager earlier, https://reviewboard.mozilla.org/r/205116/#review210662
Attachment #8934172 - Flags: review?(aswan) → review+
I think this bug is really a dup of bug 1410723, which I hope to resolve soon. In particular, "mozdevice rc=1 out=['adb: error: failed to get feature set: no devices/emulators found']" means the android emulator has become unresponsive, which really shouldn't be the fault of your test. Still, thanks for the patch and by all means, go ahead with that if it improves the test.
See Also: → 1410723
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Product: Toolkit → WebExtensions
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: