perma-failing in xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js when Gecko 63 merges to Beta on 2018-08-27

VERIFIED FIXED in Firefox 63

Status

defect
P1
blocker
VERIFIED FIXED
Last year
11 months ago

People

(Reporter: noemi_erli, Assigned: rpl)

Tracking

unspecified
mozilla63
Dependency tree / graph
Bug Flags:
qe-verify -

Firefox Tracking Flags

(thunderbird_esr52 unaffected, thunderbird_esr60 unaffected, firefox-esr52 unaffected, firefox-esr60 unaffected, firefox61 unaffected, firefox62 unaffected, firefox63 verified)

Details

Attachments

(1 attachment)

Central as Beta simulation: https://treeherder.mozilla.org/#/jobs?repo=try&revision=101320153a44d8d4928fe8b8b82cf70679c7b569&filter-resultStatus=testfailed&filter-resultStatus=busted&filter-resultStatus=exception&filter-resultStatus=retry&filter-resultStatus=usercancel&filter-resultStatus=runnable&selectedJob=192525843

Failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=192525843&repo=try&lineNumber=1451

[task 2018-08-07T13:26:01.982Z] 13:26:01  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | xpcshell return code: 0
[task 2018-08-07T13:26:01.983Z] 13:26:01     INFO -  TEST-INFO took 13339ms
[task 2018-08-07T13:26:01.983Z] 13:26:01     INFO -  >>>>>>>
[task 2018-08-07T13:26:01.983Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | xpcw: cd /sdcard/tests/xpc/toolkit/components/extensions/test/xpcshell
[task 2018-08-07T13:26:01.985Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | xpcw: xpcshell -r /sdcard/tests/xpc/c/httpd.manifest --greomni /data/local/xpcb/target.apk -m -s -e const _HEAD_JS_PATH = "/sdcard/tests/xpc/head.js"; -e const _MOZINFO_JS_PATH = "/sdcard/tests/xpc/p/mozinfo.json"; -e const _TESTING_MODULES_DIR = "/sdcard/tests/xpc/m"; -f /sdcard/tests/xpc/head.js -e const _SERVER_ADDR = "localhost" -e const _HEAD_FILES = ["/sdcard/tests/xpc/toolkit/components/extensions/test/xpcshell/head.js", "/sdcard/tests/xpc/toolkit/components/extensions/test/xpcshell/head_telemetry.js", "/sdcard/tests/xpc/toolkit/components/extensions/test/xpcshell/head_storage.js"]; -e const _JSDEBUGGER_PORT = 0; -e const _TEST_FILE = ["test_ext_storage_idb_data_migration.js"]; -e const _TEST_NAME = "xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js" -e _execute_test(); quit(0);
[task 2018-08-07T13:26:01.985Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | JavaScript strict warning: resource://testing-common/AddonTestUtils.jsm, line 310: ReferenceError: reference to undefined property "testScope"
[task 2018-08-07T13:26:01.985Z] 13:26:01     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "testScope"" {file: "resource://testing-common/AddonTestUtils.jsm" line: 310}]"
[task 2018-08-07T13:26:01.986Z] 13:26:01     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2018-08-07T13:26:01.986Z] 13:26:01     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2018-08-07T13:26:01.987Z] 13:26:01     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2018-08-07T13:26:01.987Z] 13:26:01     INFO -  running event loop
[task 2018-08-07T13:26:01.988Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | Starting check_remote
[task 2018-08-07T13:26:01.988Z] 13:26:01     INFO -  (xpcshell/head.js) | test check_remote pending (2)
[task 2018-08-07T13:26:01.989Z] 13:26:01     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | check_remote - [check_remote : 1] useRemoteWebExtensions matches - false == false
[task 2018-08-07T13:26:01.989Z] 13:26:01     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | check_remote - [check_remote : 1] testing from extension process - true == true
[task 2018-08-07T13:26:01.990Z] 13:26:01     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2018-08-07T13:26:01.990Z] 13:26:01     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2018-08-07T13:26:01.990Z] 13:26:01     INFO -  (xpcshell/head.js) | test check_remote finished (2)
[task 2018-08-07T13:26:01.991Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | Starting setup
[task 2018-08-07T13:26:01.991Z] 13:26:01     INFO -  (xpcshell/head.js) | test setup pending (2)
[task 2018-08-07T13:26:01.991Z] 13:26:01     INFO -  "Low disk mode is: free"
[task 2018-08-07T13:26:01.991Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648349964	addons.manager	DEBUG	Application has been upgraded
[task 2018-08-07T13:26:01.992Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648349975	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]
[task 2018-08-07T13:26:01.992Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350034	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/LightweightThemeManager.jsm: ["LightweightThemeManager"]
[task 2018-08-07T13:26:01.993Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350109	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm
[task 2018-08-07T13:26:01.993Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350120	addons.manager	DEBUG	Starting provider: XPIProvider
[task 2018-08-07T13:26:01.994Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350124	addons.xpi	DEBUG	startup
[task 2018-08-07T13:26:01.995Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350134	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 197"  data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:197
[task 2018-08-07T13:26:01.996Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | callProvider()@resource://gre/modules/AddonManager.jsm:18
[task 2018-08-07T13:26:01.996Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | _startProvider()@resource://gre/modules/AddonManager.jsm:51
[task 2018-08-07T13:26:01.997Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | startup()@resource://gre/modules/AddonManager.jsm:69
[task 2018-08-07T13:26:01.997Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | startup()@resource://gre/modules/AddonManager.jsm:303
[task 2018-08-07T13:26:01.997Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | observe()@jar:jar:file:///data/local/xpcb/target.apk!/assets/omni.ja!/components/addonManager.js:2
[task 2018-08-07T13:26:01.998Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:773
[task 2018-08-07T13:26:01.998Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | setup()@test_ext_storage_idb_data_migration.js:85
[task 2018-08-07T13:26:01.998Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | run_next_test/_run_next_test/<()@/sdcard/tests/xpc/head.js:1441
[task 2018-08-07T13:26:02.000Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | _run_next_test()@/sdcard/tests/xpc/head.js:1441
[task 2018-08-07T13:26:02.000Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | run()@/sdcard/tests/xpc/head.js:692
[task 2018-08-07T13:26:02.000Z] 13:26:01     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | _do_main()@/sdcard/tests/xpc/head.js:219
[task 2018-08-07T13:26:02.000Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | _execute_test()@/sdcard/tests/xpc/head.js:533
[task 2018-08-07T13:26:02.000Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | -e:1
[task 2018-08-07T13:26:02.001Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350200	addons.xpi	INFO	SystemAddonLocation directory is missing
[task 2018-08-07T13:26:02.001Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350382	addons.xpi	INFO	Removing all system add-on upgrades.
[task 2018-08-07T13:26:02.002Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350424	addons.xpi	DEBUG	checkForChanges
[task 2018-08-07T13:26:02.002Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350432	addons.xpi	DEBUG	Loaded add-on state: ${}
[task 2018-08-07T13:26:02.003Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350447	addons.xpi	DEBUG	scanForChanges changed: false, state: {}
[task 2018-08-07T13:26:02.003Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350488	addons.xpi	DEBUG	Empty XPI database, setting schema version preference to 27
[task 2018-08-07T13:26:02.003Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350491	addons.xpi	DEBUG	No changes found
[task 2018-08-07T13:26:02.004Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350609	addons.manager	DEBUG	Registering shutdown blocker for XPIProvider
[task 2018-08-07T13:26:02.004Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350613	addons.manager	DEBUG	Provider finished startup: XPIProvider
[task 2018-08-07T13:26:02.005Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350615	addons.manager	DEBUG	Starting provider: LightweightThemeManager
[task 2018-08-07T13:26:02.005Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350660	addons.manager	DEBUG	Registering shutdown blocker for LightweightThemeManager
[task 2018-08-07T13:26:02.006Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350668	addons.manager	DEBUG	Provider finished startup: LightweightThemeManager
[task 2018-08-07T13:26:02.006Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350669	addons.manager	DEBUG	Starting provider: GMPProvider
[task 2018-08-07T13:26:02.007Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350719	addons.manager	DEBUG	Registering shutdown blocker for GMPProvider
[task 2018-08-07T13:26:02.007Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350724	addons.manager	DEBUG	Provider finished startup: GMPProvider
[task 2018-08-07T13:26:02.008Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | JavaScript strict warning: resource://gre/modules/LightweightThemeManager.jsm, line 70: ReferenceError: reference to undefined property "version"
[task 2018-08-07T13:26:02.008Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648350818	addons.manager	DEBUG	Completed startup sequence
[task 2018-08-07T13:26:02.009Z] 13:26:02     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2018-08-07T13:26:02.009Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648349964	addons.manager	DEBUG	Application has been upgraded"
[task 2018-08-07T13:26:02.010Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648349975	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
[task 2018-08-07T13:26:02.010Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350034	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/LightweightThemeManager.jsm: ["LightweightThemeManager"]"
[task 2018-08-07T13:26:02.011Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350109	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
[task 2018-08-07T13:26:02.011Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350120	addons.manager	DEBUG	Starting provider: XPIProvider"
[task 2018-08-07T13:26:02.011Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350124	addons.xpi	DEBUG	startup"
[task 2018-08-07T13:26:02.012Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350134	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 197"  data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:197
[task 2018-08-07T13:26:02.012Z] 13:26:02     INFO -  callProvider()@resource://gre/modules/AddonManager.jsm:18
[task 2018-08-07T13:26:02.013Z] 13:26:02     INFO -  _startProvider()@resource://gre/modules/AddonManager.jsm:51
[task 2018-08-07T13:26:02.013Z] 13:26:02     INFO -  startup()@resource://gre/modules/AddonManager.jsm:69
[task 2018-08-07T13:26:02.014Z] 13:26:02     INFO -  startup()@resource://gre/modules/AddonManager.jsm:303
[task 2018-08-07T13:26:02.014Z] 13:26:02     INFO -  observe()@jar:jar:file:///data/local/xpcb/target.apk!/assets/omni.ja!/components/addonManager.js:2
[task 2018-08-07T13:26:02.014Z] 13:26:02     INFO -  promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:773
[task 2018-08-07T13:26:02.015Z] 13:26:02     INFO -  setup()@test_ext_storage_idb_data_migration.js:85
[task 2018-08-07T13:26:02.015Z] 13:26:02     INFO -  run_next_test/_run_next_test/<()@/sdcard/tests/xpc/head.js:1441
[task 2018-08-07T13:26:02.015Z] 13:26:02     INFO -  _run_next_test()@/sdcard/tests/xpc/head.js:1441
[task 2018-08-07T13:26:02.016Z] 13:26:02     INFO -  run()@/sdcard/tests/xpc/head.js:692
[task 2018-08-07T13:26:02.016Z] 13:26:02     INFO -  _do_main()@/sdcard/tests/xpc/head.js:219
[task 2018-08-07T13:26:02.017Z] 13:26:02     INFO -  _execute_test()@/sdcard/tests/xpc/head.js:533
[task 2018-08-07T13:26:02.017Z] 13:26:02     INFO -  -e:1"
[task 2018-08-07T13:26:02.017Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350200	addons.xpi	INFO	SystemAddonLocation directory is missing"
[task 2018-08-07T13:26:02.018Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350382	addons.xpi	INFO	Removing all system add-on upgrades."
[task 2018-08-07T13:26:02.018Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350424	addons.xpi	DEBUG	checkForChanges"
[task 2018-08-07T13:26:02.019Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350432	addons.xpi	DEBUG	Loaded add-on state: ${}"
[task 2018-08-07T13:26:02.019Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350447	addons.xpi	DEBUG	scanForChanges changed: false, state: {}"
[task 2018-08-07T13:26:02.019Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350488	addons.xpi	DEBUG	Empty XPI database, setting schema version preference to 27"
[task 2018-08-07T13:26:02.020Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350491	addons.xpi	DEBUG	No changes found"
[task 2018-08-07T13:26:02.020Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350609	addons.manager	DEBUG	Registering shutdown blocker for XPIProvider"
[task 2018-08-07T13:26:02.021Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350613	addons.manager	DEBUG	Provider finished startup: XPIProvider"
[task 2018-08-07T13:26:02.021Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350615	addons.manager	DEBUG	Starting provider: LightweightThemeManager"
[task 2018-08-07T13:26:02.022Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350660	addons.manager	DEBUG	Registering shutdown blocker for LightweightThemeManager"
[task 2018-08-07T13:26:02.022Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350668	addons.manager	DEBUG	Provider finished startup: LightweightThemeManager"
[task 2018-08-07T13:26:02.022Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350669	addons.manager	DEBUG	Starting provider: GMPProvider"
[task 2018-08-07T13:26:02.023Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350719	addons.manager	DEBUG	Registering shutdown blocker for GMPProvider"
[task 2018-08-07T13:26:02.023Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350724	addons.manager	DEBUG	Provider finished startup: GMPProvider"
[task 2018-08-07T13:26:02.024Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "version"" {file: "resource://gre/modules/LightweightThemeManager.jsm" line: 70}]"
[task 2018-08-07T13:26:02.024Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648350818	addons.manager	DEBUG	Completed startup sequence"
[task 2018-08-07T13:26:02.025Z] 13:26:02     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2018-08-07T13:26:02.025Z] 13:26:02     INFO -  (xpcshell/head.js) | test setup finished (2)
[task 2018-08-07T13:26:02.025Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | Starting test_storage_local_data_migration
[task 2018-08-07T13:26:02.026Z] 13:26:02     INFO -  (xpcshell/head.js) | test test_storage_local_data_migration pending (2)
[task 2018-08-07T13:26:02.026Z] 13:26:02     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (2)
[task 2018-08-07T13:26:02.026Z] 13:26:02     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 45] The old json file has been created - true == true
[task 2018-08-07T13:26:02.027Z] 13:26:02     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 53] No data recorded for histogram WEBEXT_STORAGE_LOCAL_IDB_MIGRATE_RESULT_COUNT - 0 == 0
[task 2018-08-07T13:26:02.027Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | JavaScript strict warning: resource://gre/modules/addons/XPIInstall.jsm, line 62: ReferenceError: reference to undefined property "description"
[task 2018-08-07T13:26:02.028Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "description"" {file: "resource://gre/modules/addons/XPIInstall.jsm" line: 62}]"
[task 2018-08-07T13:26:02.028Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648354811	addons.xpi-utils	DEBUG	Starting async load of XPI database /sdcard/tests/xpc/p/extensions.json
[task 2018-08-07T13:26:02.029Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648354811	addons.xpi-utils	DEBUG	Starting async load of XPI database /sdcard/tests/xpc/p/extensions.json"
[task 2018-08-07T13:26:02.029Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648354825	addons.xpi-utils	DEBUG	Rebuilding XPI database with no extensions
[task 2018-08-07T13:26:02.030Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648354830	addons.xpi	DEBUG	Install of temporary addon in /sdcard/tests/xpc/p/temp/generated-extension.xpi completed.
[task 2018-08-07T13:26:02.030Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648354832	addons.xpi-utils	DEBUG	Make addon app-temporary:extension-to-be-migrated@mozilla.org visible
[task 2018-08-07T13:26:02.031Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648354837	addons.xpi	DEBUG	XPIStates adding add-on extension-to-be-migrated@mozilla.org in {}: /sdcard/tests/xpc/p/temp/generated-extension.xpi
[task 2018-08-07T13:26:02.031Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | JavaScript strict warning: resource://gre/modules/addons/XPIProvider.jsm, line 44: ReferenceError: reference to undefined property "version"
[task 2018-08-07T13:26:02.032Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648354854	addons.xpi	DEBUG	Updating XPIState for {"id":"extension-to-be-migrated@mozilla.org","syncGUID":"{7603c2b0-fe11-4e74-ac70-fe8563e9baf7}","version":"1.0","type":"webextension","updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Generated extension","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"installDate":1533648354830,"applyBackgroundUpdates":1,"path":"/sdcard/tests/xpc/p/temp/generated-extension.xpi","skinnable":false,"sourceURI":null,"releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"toolkit@mozilla.org","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"signedState":0,"seen":true,"dependencies":[],"hasEmbeddedWebExtension":false,"userPermissions":{"permissions":["storage"],"origins":[]},"icons":{},"iconURL":null,"icon64URL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"location":"app-temporary"}
[task 2018-08-07T13:26:02.033Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648354864	addons.xpi	DEBUG	Loading bootstrap scope from /sdcard/tests/xpc/p/temp/generated-extension.xpi
[task 2018-08-07T13:26:02.033Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648354870	addons.xpi	DEBUG	Calling bootstrap method install on extension-to-be-migrated@mozilla.org version 1.0
[task 2018-08-07T13:26:02.034Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648354875	addons.xpi	DEBUG	Calling bootstrap method startup on extension-to-be-migrated@mozilla.org version 1.0
[task 2018-08-07T13:26:02.034Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648354825	addons.xpi-utils	DEBUG	Rebuilding XPI database with no extensions"
[task 2018-08-07T13:26:02.035Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648354830	addons.xpi	DEBUG	Install of temporary addon in /sdcard/tests/xpc/p/temp/generated-extension.xpi completed."
[task 2018-08-07T13:26:02.035Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648354832	addons.xpi-utils	DEBUG	Make addon app-temporary:extension-to-be-migrated@mozilla.org visible"
[task 2018-08-07T13:26:02.036Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648354837	addons.xpi	DEBUG	XPIStates adding add-on extension-to-be-migrated@mozilla.org in {}: /sdcard/tests/xpc/p/temp/generated-extension.xpi"
[task 2018-08-07T13:26:02.036Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "version"" {file: "resource://gre/modules/addons/XPIProvider.jsm" line: 44}]"
[task 2018-08-07T13:26:02.037Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648354854	addons.xpi	DEBUG	Updating XPIState for {"id":"extension-to-be-migrated@mozilla.org","syncGUID":"{7603c2b0-fe11-4e74-ac70-fe8563e9baf7}","version":"1.0","type":"webextension","updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Generated extension","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"installDate":1533648354830,"applyBackgroundUpdates":1,"path":"/sdcard/tests/xpc/p/temp/generated-extension.xpi","skinnable":false,"sourceURI":null,"releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"toolkit@mozilla.org","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"signedState":0,"seen":true,"dependencies":[],"hasEmbeddedWebExtension":false,"userPermissions":{"permissions":["storage"],"origins":[]},"icons":{},"iconURL":null,"icon64URL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"location":"app-temporary"}"
[task 2018-08-07T13:26:02.038Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648354864	addons.xpi	DEBUG	Loading bootstrap scope from /sdcard/tests/xpc/p/temp/generated-extension.xpi"
[task 2018-08-07T13:26:02.038Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648354870	addons.xpi	DEBUG	Calling bootstrap method install on extension-to-be-migrated@mozilla.org version 1.0"
[task 2018-08-07T13:26:02.039Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648354875	addons.xpi	DEBUG	Calling bootstrap method startup on extension-to-be-migrated@mozilla.org version 1.0"
[task 2018-08-07T13:26:02.039Z] 13:26:02     INFO -  "Extension attached"
[task 2018-08-07T13:26:02.039Z] 13:26:02     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | 1533648357646	addons.xpi-utils	DEBUG	XPI Database saved, setting schema version preference to 27
[task 2018-08-07T13:26:02.040Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) 1533648357646	addons.xpi-utils	DEBUG	XPI Database saved, setting schema version preference to 27"
[task 2018-08-07T13:26:02.040Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) Migrating storage.local data for "Generated extension" (ID: extension-to-be-migrated@mozilla.org, moz-extension://e4acbf3d-6672-46ea-aafd-0ea6a658ad71/)..."
[task 2018-08-07T13:26:02.041Z] 13:26:02     INFO -  "CONSOLE_MESSAGE: (info) storage.local data successfully migrated to IDB Backend for "Generated extension" (ID: extension-to-be-migrated@mozilla.org, moz-extension://e4acbf3d-6672-46ea-aafd-0ea6a658ad71/)."
[task 2018-08-07T13:26:02.041Z] 13:26:02     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 291] Got the expected data after the storage.local data migration - Expected: test_value1, Actual: test_value1 - true == true
[task 2018-08-07T13:26:02.042Z] 13:26:02     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 291] Got the expected data after the storage.local data migration - Expected: 1000, Actual: 1000 - true == true
[task 2018-08-07T13:26:02.042Z] 13:26:02     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 291] Got the expected data after the storage.local data migration - Expected: true, Actual: true - true == true
[task 2018-08-07T13:26:02.043Z] 13:26:02     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 151] Data stored in the ExtensionStorageIDB backend as expected - false == false
[task 2018-08-07T13:26:02.043Z] 13:26:02     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 154] The old json storage file name should not exist anymore - false == false
[task 2018-08-07T13:26:02.044Z] 13:26:02     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 157] The old json storage file name should have been renamed as .migrated - true == true
[task 2018-08-07T13:26:02.044Z] 13:26:02     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 160] Got the extensions.webextensions.ExtensionStorageIDB.migrated preference set to true as expected - true == true
[task 2018-08-07T13:26:02.045Z] 13:26:02  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 60] Got the expected count on category "success" for histogram WEBEXT_STORAGE_LOCAL_IDB_MIGRATE_RESULT_COUNT - 0 == 1
[task 2018-08-07T13:26:02.045Z] 13:26:02     INFO -  test_ext_storage_idb_data_migration.js:assertMigrationHistogramCount:60
[task 2018-08-07T13:26:02.045Z] 13:26:02     INFO -  test_ext_storage_idb_data_migration.js:test_storage_local_data_migration:163
[task 2018-08-07T13:26:02.046Z] 13:26:02     INFO -  /sdcard/tests/xpc/head.js:_do_main:219
[task 2018-08-07T13:26:02.046Z] 13:26:02     INFO -  /sdcard/tests/xpc/head.js:_execute_test:533
[task 2018-08-07T13:26:02.046Z] 13:26:02     INFO -  -e:null:1
[task 2018-08-07T13:26:02.047Z] 13:26:02     INFO -  exiting test
[task 2018-08-07T13:26:02.047Z] 13:26:02     INFO -  Unexpected exception NS_ERROR_ABORT:
[task 2018-08-07T13:26:02.047Z] 13:26:02     INFO -  _abort_failed_test@/sdcard/tests/xpc/head.js:746:9
[task 2018-08-07T13:26:02.048Z] 13:26:02     INFO -  do_report_result@/sdcard/tests/xpc/head.js:853:5
[task 2018-08-07T13:26:02.048Z] 13:26:02     INFO -  Assert<@/sdcard/tests/xpc/head.js:55:5
[task 2018-08-07T13:26:02.049Z] 13:26:02     INFO -  proto.report@resource://testing-common/Assert.jsm:214:5
[task 2018-08-07T13:26:02.049Z] 13:26:02     INFO -  equal@resource://testing-common/Assert.jsm:246:3
[task 2018-08-07T13:26:02.049Z] 13:26:02     INFO -  assertMigrationHistogramCount@test_ext_storage_idb_data_migration.js:60:3
[task 2018-08-07T13:26:02.050Z] 13:26:02     INFO -  test_storage_local_data_migration@test_ext_storage_idb_data_migration.js:163:3
[task 2018-08-07T13:26:02.050Z] 13:26:02     INFO -  _do_main@/sdcard/tests/xpc/head.js:219:3
[task 2018-08-07T13:26:02.050Z] 13:26:02     INFO -  _execute_test@/sdcard/tests/xpc/head.js:533:5
[task 2018-08-07T13:26:02.051Z] 13:26:02     INFO -  @-e:1:1
[task 2018-08-07T13:26:02.051Z] 13:26:02     INFO -  exiting test
[task 2018-08-07T13:26:02.052Z] 13:26:02  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_storage_idb_data_migration.js | test_storage_local_data_migration - [test_storage_local_data_migration : 228] Extension left running at test shutdown - "running" == "unloaded"
[task 2018-08-07T13:26:02.052Z] 13:26:02     INFO -  resource://testing-common/ExtensionXPCShellUtils.jsm:ExtensionWrapper/<:228
[task 2018-08-07T13:26:02.052Z] 13:26:02     INFO -  /sdcard/tests/xpc/head.js:_execute_test/<:597
[task 2018-08-07T13:26:02.053Z] 13:26:02     INFO -  /sdcard/tests/xpc/head.js:_execute_test:594

This issue blocks https://bugzilla.mozilla.org/show_bug.cgi?id=1470208
Flags: needinfo?(lgreco)
I looked into this (and reproduced the failure locally), it looks like the issue behind this failure is the same of another similar one we fixed in Bug 1380287 one year ago.

I'm assigning this issue to myself and I'm going to attach a patch for review (with a fix similar to the one applied by Bug 1380287 on the test_chrome_ext_contentscript_telemetry test one year ago).
Assignee: nobody → lgreco
Status: NEW → ASSIGNED
Flags: needinfo?(lgreco)
Priority: -- → P1
This patch contains a small fix needed to fix an Android-only failure related to the test
test_ext_storage_idb_data_migration.js when running on a non-Nightly build.

After digging into it a bit I notice that the reason behind this failure is similar to
the one we fixed an year ago in Bug 1380287 for another test telemetry-related.
Comment on attachment 8998629 [details]
Bug 1481485 - Fix Android-only failure related to telemetry tests when running on Beta.

Alessio Placitelli [:Dexter] has approved the revision.
Attachment #8998629 - Flags: review+
Pushed by luca.greco@alcacoop.it:
https://hg.mozilla.org/integration/autoland/rev/dcab8343b3db
Fix Android-only failure related to telemetry tests when running on Beta. r=Dexter
Added qe-verify- (this is a test-only fix and it will be verified by re-running the "central as beta simulation", once the fix lands on mozilla-central).
Flags: qe-verify-
https://hg.mozilla.org/mozilla-central/rev/dcab8343b3db
Status: ASSIGNED → RESOLVED
Closed: 11 months ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla63
You need to log in before you can comment on or make changes to this bug.