Closed Bug 1691654 Opened 4 years ago Closed 4 years ago

Intermittent devtools/server/tests/xpcshell/test_extension_storage_actor.js | test_panel_live_reload - [test_panel_live_reload : 1062] Got the expected results on populated storage.local - [] deepEqual [{"area":"local"," | before xpcshell return code: 0

Categories

(DevTools :: General, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1620767

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: smolnar [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=329253683&repo=mozilla-esr78
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/a026KuSHSuCESUMEzrZEiQ/runs/0/artifacts/public/logs/live_backing.log


INFO -  TEST-START | devtools/server/tests/xpcshell/test_extension_storage_actor.js
[task 2021-02-08T18:16:08.697Z] 18:16:08  WARNING -  TEST-UNEXPECTED-FAIL | devtools/server/tests/xpcshell/test_extension_storage_actor.js | xpcshell return code: 0
[task 2021-02-08T18:16:08.697Z] 18:16:08     INFO -  TEST-INFO took 5763ms
[task 2021-02-08T18:16:08.698Z] 18:16:08     INFO -  >>>>>>>
[task 2021-02-08T18:16:08.698Z] 18:16:08     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-02-08T18:16:08.699Z] 18:16:08     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2021-02-08T18:16:08.699Z] 18:16:08     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2021-02-08T18:16:08.700Z] 18:16:08     INFO -  running event loop
[task 2021-02-08T18:16:08.700Z] 18:16:08     INFO -  devtools/server/tests/xpcshell/test_extension_storage_actor.js | Starting setup
[task 2021-02-08T18:16:08.701Z] 18:16:08     INFO -  (xpcshell/head.js) | test setup pending (2)
...
INFO -  XULStore@resource://gre/modules/XULStore.jsm:24:10
[task 2021-02-08T18:16:08.729Z] 18:16:08     INFO -  "
[task 2021-02-08T18:16:08.729Z] 18:16:08     INFO -  TEST-PASS | devtools/server/tests/xpcshell/test_extension_storage_actor.js | test_extension_store_exists - [test_extension_store_exists : 1] 3 == 3
[task 2021-02-08T18:16:08.729Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: resource://gre/modules/XULStore.jsm:66: error: Error: Can't find profile directory.
[task 2021-02-08T18:16:08.730Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: head_dbg.js observed a console message: Error: Can't find profile directory.
[task 2021-02-08T18:16:08.730Z] 18:16:08     INFO -  TEST-PASS | devtools/server/tests/xpcshell/test_extension_storage_actor.js | test_extension_store_exists - [test_extension_store_exists : 240] Should have an extensionStorage store - [object Object] == true
[task 2021-02-08T18:16:08.730Z] 18:16:08     INFO -  PID 15688 | console.error: "Error while calling actor 'webExtensionTarget's method 'detach'" (void 0)
<...>
 observe@resource://gre/modules/ExtensionPageChild.jsm:407:12
[task 2021-02-08T18:16:08.887Z] 18:16:08     INFO -  "
[task 2021-02-08T18:16:08.887Z] 18:16:08     INFO -  TEST-PASS | devtools/server/tests/xpcshell/test_extension_storage_actor.js | test_panel_live_reload - [test_panel_live_reload : 1] 3 == 3
[task 2021-02-08T18:16:08.887Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: resource://gre/modules/ConduitsChild.jsm:108: error: sendRemoveListener on closed conduit test_panel_live_reload@xpcshell.mozilla.org.1786706395136
[task 2021-02-08T18:16:08.887Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: head_dbg.js observed a console message: sendRemoveListener on closed conduit test_panel_live_reload@xpcshell.mozilla.org.1786706395136
[task 2021-02-08T18:16:08.887Z] 18:16:08     INFO -  "CONSOLE_MESSAGE: (info) 1612808168448	addons.xpi	DEBUG	Calling bootstrap method startup on test_panel_live_reload@xpcshell.mozilla.org version 2.0"
[task 2021-02-08T18:16:08.887Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: head_dbg.js observed a console message: 1612808168448	addons.xpi	DEBUG	Calling bootstrap method startup on test_panel_live_reload@xpcshell.mozilla.org version 2.0
[task 2021-02-08T18:16:08.887Z] 18:16:08     INFO -  "Extension attached"
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  PID 15688 | JavaScript error: resource://devtools/server/actors/targets/browsing-context.js, line 390: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIDocShell.domWindow]
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIDocShell.domWindow]" {file: "resource://devtools/server/actors/targets/browsing-context.js" line: 390}]"
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  TEST-PASS | devtools/server/tests/xpcshell/test_extension_storage_actor.js | test_panel_live_reload - [test_panel_live_reload : 1] 3 == 3
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: resource://devtools/server/actors/targets/browsing-context.js:390: error: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIDocShell.domWindow]
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: head_dbg.js observed a console message: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIDocShell.domWindow]
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  "CONSOLE_MESSAGE: (info) 1612808168483	addons.xpi	DEBUG	Install of temporary addon in c:\\users\\task_1612806880\\appdata\\local\\temp\\xpc-profile-mumrzo\\tmp\\generated-extension-1.xpi completed."
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: head_dbg.js observed a console message: 1612808168483	addons.xpi	DEBUG	Install of temporary addon in c:\\users\\task_1612806880\\appdata\\local\\temp\\xpc-profile-mumrzo\\tmp\\generated-extension-1.xpi completed.
[task 2021-02-08T18:16:08.888Z] 18:16:08  WARNING -  TEST-UNEXPECTED-FAIL | devtools/server/tests/xpcshell/test_extension_storage_actor.js | test_panel_live_reload - [test_panel_live_reload : 1062] Got the expected results on populated storage.local - [] deepEqual [{"area":"local","name":"a","value":{"str":"123"},"isValueEditable":true}]
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  Z:/task_1612806880/build/tests/xpcshell/tests/devtools/server/tests/xpcshell/test_extension_storage_actor.js:test_panel_live_reload:1062
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  Z:\task_1612806880\build\tests\xpcshell\head.js:_do_main:248
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  Z:\task_1612806880\build\tests\xpcshell\head.js:_execute_test:577
[task 2021-02-08T18:16:08.888Z] 18:16:08     INFO -  -e:null:1
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  exiting test
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  Unexpected exception NS_ERROR_ABORT:
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  _abort_failed_test@Z:\task_1612806880\build\tests\xpcshell\head.js:833:20
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  do_report_result@Z:\task_1612806880\build\tests\xpcshell\head.js:934:5
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  Assert<@Z:\task_1612806880\build\tests\xpcshell\head.js:73:21
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  proto.report@resource://testing-common/Assert.jsm:233:10
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  deepEqual@resource://testing-common/Assert.jsm:310:8
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  test_panel_live_reload@Z:/task_1612806880/build/tests/xpcshell/tests/devtools/server/tests/xpcshell/test_extension_storage_actor.js:1062:10
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  _do_main@Z:\task_1612806880\build\tests\xpcshell\head.js:248:6
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  _execute_test@Z:\task_1612806880\build\tests\xpcshell\head.js:577:5
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  @-e:1:1
[task 2021-02-08T18:16:08.889Z] 18:16:08     INFO -  exiting test
[task 2021-02-08T18:16:08.890Z] 18:16:08  WARNING -  TEST-UNEXPECTED-FAIL | devtools/server/tests/xpcshell/test_extension_storage_actor.js | test_panel_live_reload - [test_panel_live_reload : 332] Extension left running at test shutdown - "running" == "unloaded"
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  resource://testing-common/ExtensionXPCShellUtils.jsm:ExtensionWrapper/<:332
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  Z:\task_1612806880\build\tests\xpcshell\head.js:_execute_test/<:641
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  Z:\task_1612806880\build\tests\xpcshell\head.js:_execute_test:650
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  -e:null:1
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  exiting test
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  NS_ERROR_ABORT:
INFO -  _abort_failed_test@Z:\task_1612806880\build\tests\xpcshell\head.js:833:20
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  do_report_result@Z:\task_1612806880\build\tests\xpcshell\head.js:934:5
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  Assert<@Z:\task_1612806880\build\tests\xpcshell\head.js:73:21
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  proto.report@resource://testing-common/Assert.jsm:233:10
[task 2021-02-08T18:16:08.890Z] 18:16:08     INFO -  equal@resource://testing-common/Assert.jsm:275:8
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  ExtensionWrapper/<@resource://testing-common/ExtensionXPCShellUtils.jsm:332:24
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  _execute_test/<@Z:\task_1612806880\build\tests\xpcshell\head.js:641:28
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  _execute_test@Z:\task_1612806880\build\tests\xpcshell\head.js:650:5
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  @-e:1:1
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "[Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIFile.remove]"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "JS frame :: resource://testing-common/ExtensionXPCShellUtils.jsm :: init/< :: line 919"  data: no]"]
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  init/<@resource://testing-common/ExtensionXPCShellUtils.jsm:919:14
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  _execute_test/<@Z:\\task_1612806880\\build\\tests\\xpcshell\\head.js:641:28
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  _execute_test@Z:\\task_1612806880\\build\\tests\\xpcshell\\head.js:653:16
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  @-e:1:1
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  "
[task 2021-02-08T18:16:08.891Z] 18:16:08     INFO -  TEST-PASS | devtools/server/tests/xpcshell/test_extension_storage_actor.js | test_panel_live_reload - [test_panel_live_reload : 554] 3 == 3
<...>
INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "sendRemoveListener on closed conduit test_panel_live_reload@xpcshell.mozilla.org.1786706395138" {file: "resource://gre/modules/ConduitsChild.jsm" line: 108}]
[task 2021-02-08T18:16:08.893Z] 18:16:08     INFO -  _send@resource://gre/modules/ConduitsChild.jsm:108:13
[task 2021-02-08T18:16:08.893Z] 18:16:08     INFO -  removeListener@resource://gre/modules/ExtensionChild.jsm:762:34
[task 2021-02-08T18:16:08.893Z] 18:16:08     INFO -  removeListener@resource://gre/modules/ExtensionChild.jsm:985:40
[task 2021-02-08T18:16:08.893Z] 18:16:08     INFO -  getAPI/register/<.storage.onChanged@chrome://extensions/content/child/ext-storage.js:337:22
[task 2021-02-08T18:16:08.893Z] 18:16:08     INFO -  removeListener@resource://gre/modules/ExtensionCommon.jsm:2521:7
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  revoke@resource://gre/modules/ExtensionCommon.jsm:2543:12
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  close@resource://gre/modules/ExtensionCommon.jsm:2548:10
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  unload@resource://gre/modules/ExtensionCommon.jsm:913:11
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  unload@resource://gre/modules/ExtensionPageChild.jsm:261:11
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  unload@resource://gre/modules/ExtensionPageChild.jsm:310:11
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  destroyExtensionContext@resource://gre/modules/ExtensionPageChild.jsm:494:15
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  observe@resource://gre/modules/ExtensionPageChild.jsm:407:12
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  "
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  TEST-PASS | devtools/server/tests/xpcshell/test_extension_storage_actor.js | test_panel_live_reload - [test_panel_live_reload : 1] 3 == 3
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: resource://gre/modules/ConduitsChild.jsm:108: error: sendRemoveListener on closed conduit test_panel_live_reload@xpcshell.mozilla.org.1786706395138
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: head_dbg.js observed a console message: sendRemoveListener on closed conduit test_panel_live_reload@xpcshell.mozilla.org.1786706395138
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  "CONSOLE_MESSAGE: (info) 1612808168500	addons.manager	DEBUG	Async provider shutdown done"
[task 2021-02-08T18:16:08.894Z] 18:16:08     INFO -  PID 15688 | DBG-TEST: head_dbg.js observed a console message: 1612808168500	addons.manager	DEBUG	Async provider shutdown done
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  PID 15688 | Handler function threw an exception: [Exception... "Unexpected error"  nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)"  location: "JS frame :: resource://devtools/server/actors/descriptors/webextension.js :: _extensionFrameDisconnect :: line 222"  data: no]
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  PID 15688 | Stack: _extensionFrameDisconnect@resource://devtools/server/actors/descriptors/webextension.js:222:18
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  PID 15688 | connectToFrame/</destroy<@resource://devtools/server/connectors/frame-connector.js:275:18
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  PID 15688 | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  PID 15688 | onMessageManagerClose@resource://devtools/server/connectors/frame-connector.js:288:9
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  PID 15688 | _execute_test@Z:\\task_1612806880\\build\\tests\\xpcshell\\head.js:673:19
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  PID 15688 | @-e:1:1
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  PID 15688 | Line: 222, column: 0
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  PID 15688 | console.error: ({})
[task 2021-02-08T18:16:08.895Z] 18:16:08     INFO -  <<<<<<<
[task 2021-02-08T18:16:08.905Z] 18:16:08     INFO -  TEST-START | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_geodefaults.js```
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.