Open Bug 1605518 Opened 5 years ago Updated 3 years ago

Intermittent toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | Test timed out.

Categories

(WebExtensions :: General, defect, P5)

defect

Tracking

(Not tracked)

REOPENED

People

(Reporter: intermittent-bug-filer, Unassigned, NeedInfo)

References

Details

(Keywords: intermittent-failure, Whiteboard: [retriggered][stockwell unknown])

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=282166927&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TAJ9FQBURZCS6M8yPCwcJg/runs/0/artifacts/public/logs/live_backing.log


[task 2019-12-20T21:55:38.766Z] 21:55:38 INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html
[task 2019-12-20T21:55:38.783Z] 21:55:38 INFO - GECKO(2042) | [Parent 2042, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-20T21:55:38.802Z] 21:55:38 INFO - GECKO(2042) | [Child 2044, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-20T21:55:39.187Z] 21:55:39 INFO - GECKO(2042) | [Parent 2042, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-20T21:55:39.187Z] 21:55:39 INFO - GECKO(2042) | [Child 2044, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-20T21:55:39.898Z] 21:55:39 INFO - GECKO(2042) | [Parent 2042, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-20T21:55:39.898Z] 21:55:39 INFO - GECKO(2042) | [Child 2044, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-20T22:00:55.556Z] 22:00:55 INFO - TEST-INFO | started process screencapture
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - TEST-INFO | screencapture: exit 0
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - Buffered messages logged at 21:55:38
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - add_task | Entering test test_drawWindow
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - Extension loaded
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - Extension loaded
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - Buffered messages logged at 21:55:39
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | drawWindow() call was successful: #ff9 == rgb(255,255,153)
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | drawWindow() method not awailable without permission
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - add_task | Leaving test test_drawWindow
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - add_task | Entering test test_tainted_canvas
[task 2019-12-20T22:00:55.684Z] 22:00:55 INFO - Extension loaded
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - Extension loaded
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | toDataURL() call was successful.
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | getImageData() returned the correct colour (transparent).
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - Buffered messages finished
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | Test timed out.
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - setTimeout handler
TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:388:20
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:375:50
[task 2019-12-20T22:00:55.685Z] 22:00:55 INFO - RunSet.runtests@SimpleTest/setup.js:201:14

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=311668106&repo=autoland&lineNumber=14370

[task 2020-07-31T16:13:42.436Z] 16:13:42 INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html
[task 2020-07-31T16:19:11.676Z] 16:19:11 INFO - TEST-INFO | started process screentopng
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - TEST-INFO | screentopng: exit 0
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - Buffered messages logged at 16:13:42
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - add_task | Entering test test_drawWindow
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - Extension loaded
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - Extension loaded
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | drawWindow() call was successful: #ff9 == rgb(255,255,153)
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | drawWindow() method not awailable without permission
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - Buffered messages logged at 16:13:43
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - add_task | Leaving test test_drawWindow
[task 2020-07-31T16:19:11.834Z] 16:19:11 INFO - add_task | Entering test test_tainted_canvas
[task 2020-07-31T16:19:11.835Z] 16:19:11 INFO - Extension loaded
[task 2020-07-31T16:19:11.836Z] 16:19:11 INFO - Extension loaded
[task 2020-07-31T16:19:11.836Z] 16:19:11 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | toDataURL() call was successful.
[task 2020-07-31T16:19:11.836Z] 16:19:11 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | getImageData() returned the correct colour (transparent).
[task 2020-07-31T16:19:11.836Z] 16:19:11 INFO - Buffered messages finished
[task 2020-07-31T16:19:11.836Z] 16:19:11 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | Test timed out.
[task 2020-07-31T16:19:11.836Z] 16:19:11 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-07-31T16:19:11.836Z] 16:19:11 INFO - reportError@SimpleTest/TestRunner.js:138:22
[task 2020-07-31T16:19:11.836Z] 16:19:11 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:160:18
[task 2020-07-31T16:19:12.680Z] 16:19:12 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-31T16:19:12.680Z] 16:19:12 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | Extension left running at test shutdown
[task 2020-07-31T16:19:12.681Z] 16:19:12 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-07-31T16:19:12.681Z] 16:19:12 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:117:18
[task 2020-07-31T16:19:12.681Z] 16:19:12 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1587:13
[task 2020-07-31T16:19:12.681Z] 16:19:12 INFO - SimpleTest.finish@SimpleTest/SimpleTest.js:1601:3
[task 2020-07-31T16:19:12.681Z] 16:19:12 INFO - killTest@SimpleTest/TestRunner.js:147:22
[task 2020-07-31T16:19:12.730Z] 16:19:12 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-31T16:19:12.731Z] 16:19:12 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | message queue is empty - got "["success"]", expected "[]"
[task 2020-07-31T16:19:12.731Z] 16:19:12 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:495:14
[task 2020-07-31T16:19:12.731Z] 16:19:12 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:32:18
[task 2020-07-31T16:19:12.732Z] 16:19:12 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1587:13
[task 2020-07-31T16:19:12.732Z] 16:19:12 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-31T16:19:12.732Z] 16:19:12 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | no tasks awaiting on messages - got "["error"]", expected "[]"
[task 2020-07-31T16:19:12.733Z] 16:19:12 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:495:14
[task 2020-07-31T16:19:12.733Z] 16:19:12 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:36:18
[task 2020-07-31T16:19:12.733Z] 16:19:12 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1587:13
[task 2020-07-31T16:19:12.733Z] 16:19:12 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-31T16:19:12.734Z] 16:19:12 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | Extension left running at test shutdown
[task 2020-07-31T16:19:12.734Z] 16:19:12 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-07-31T16:19:12.734Z] 16:19:12 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:117:18
[task 2020-07-31T16:19:12.735Z] 16:19:12 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1587:13
[task 2020-07-31T16:19:12.735Z] 16:19:12 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1597:7
[task 2020-07-31T16:19:12.758Z] 16:19:12 INFO - GECKO(5216) | MEMORY STAT | vsize 2541MB | residentFast 131MB | heapAllocated 14MB
[task 2020-07-31T16:19:14.762Z] 16:19:14 INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | took 332324ms
[task 2020-07-31T16:19:17.769Z] 16:19:17 INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2020-07-31T16:19:17.770Z] 16:19:17 ERROR - /tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html logged result after SimpleTest.finish(): Extension left running at test shutdown
[task 2020-07-31T16:19:17.770Z] 16:19:17 ERROR - /tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html logged result after SimpleTest.finish(): message queue is empty
[task 2020-07-31T16:19:17.770Z] 16:19:17 ERROR - /tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html logged result after SimpleTest.finish(): no tasks awaiting on messages
[task 2020-07-31T16:19:17.771Z] 16:19:17 ERROR - /tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html logged result after SimpleTest.finish(): Extension left running at test shutdown

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

(In reply to Cosmin Sabou [:CosminS] from comment #9)

Looks to be from Bug 1653530. Luca, could you please have a look over it? Thank you.
Th link: https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception%2Csuccess%2Cusercancel%2Crunning%2Cpending%2Crunnable&searchStr=linux%2C18.04%2Cx64%2Cwebrender%2Copt%2Cmochitests%2Ctest-linux1804-64-qr%2Fopt-mochitest-plain-e10s%2C1&tochange=b3b3ebe25dc3acbb2f016a3905215eee08280315&fromchange=f7817f1be22afc6d149471f646dbcc69d49bd1cb&selectedTaskRun=NDDM4FKkTq6YjKv-cTmgXA.0
It hasn't failed since the 31st: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-07-26&endday=2020-08-02&tree=trunk&bug=1605518

It should be unlikely for Bug 1653530 patch (which does only introduce a new mochitest browser defined at browser level) to be able to make a mochitest plain test defined at toolkit level to fail (they are not even going to run in the same job or chunk).

I tried to run this test locally with --verify and it does get stuck pretty often for me (and pretty soon, just after a couple of execution in the first group of the --verify runs) even on older mozilla-central commits.

It seems that when the test does fail, it gets stuck because the second extension (which does not have the <all_urls> permissions) is supposed to trigger a DOM Security Error but it doesn't always trigger it and so it sends a success message instead of the error message that we are expecting from it (and, based on the errors that follows the timeout on the try failure logs here: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=311668106&repo=autoland&lineNumber=14370, it seems that the same thing is happening on try when the test does fail).

I did try record the failure with rr, but unfortunately while running under rr the test does pass consistently :-(

I also took a look to recent changes to the surrounding of the two methods involved (HTMLCanvasElement::ToDataURL and HTMLCanvasElement::CallerCanRead), but I didn't found anything recent or suspicious yet.

Hey Tomislav,
do you have any other ideas about what we could look for as a root cause for the second extension not triggering the DOM security error intermittently?

Flags: needinfo?(lgreco) → needinfo?(tomica)

I did a full build locally to be able to add some additional logging and trace it down where we decided that the call to HTMLCanvasElement::ToDataURL was ok and we didn't have to raise the DOM security error that we were expecting and it looks that it happens when we are returning true earlier from HTMLCanvasElement::CallerCanRead because mWriteOnly is false here

bool HTMLCanvasElement::CallerCanRead(JSContext* aCx) {
  if (!mWriteOnly) {
    return true;
  }

  ...
}

Behind this intermittent failure there seems to be a legit bug to fix, removing the early return on !mWriteOnly was enough to make the test to pass consistently for me locally, but I would also like to fully see how it does only fail intermittently instead of consistently.

There are 26 total failures in the last 7 days on linux1804-64-qr opt, macosx1014-64 debug and opt.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=318903713&repo=autoland&lineNumber=2426

[task 2020-10-17T02:43:13.647Z] 02:43:13 INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html
[task 2020-10-17T02:48:35.594Z] 02:48:35 INFO - TEST-INFO | started process screentopng
[task 2020-10-17T02:48:35.745Z] 02:48:35 INFO - TEST-INFO | screentopng: exit 0
[task 2020-10-17T02:48:35.745Z] 02:48:35 INFO - Buffered messages logged at 02:43:13
[task 2020-10-17T02:48:35.745Z] 02:48:35 INFO - add_task | Entering test test_drawWindow
[task 2020-10-17T02:48:35.746Z] 02:48:35 INFO - Extension loaded
[task 2020-10-17T02:48:35.747Z] 02:48:35 INFO - Extension loaded
[task 2020-10-17T02:48:35.747Z] 02:48:35 INFO - Buffered messages logged at 02:43:14
[task 2020-10-17T02:48:35.747Z] 02:48:35 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | drawWindow() call was successful: #ff9 == rgb(255,255,153)
[task 2020-10-17T02:48:35.747Z] 02:48:35 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | drawWindow() method not awailable without permission
[task 2020-10-17T02:48:35.748Z] 02:48:35 INFO - add_task | Leaving test test_drawWindow
[task 2020-10-17T02:48:35.748Z] 02:48:35 INFO - add_task | Entering test test_tainted_canvas
[task 2020-10-17T02:48:35.748Z] 02:48:35 INFO - Extension loaded
[task 2020-10-17T02:48:35.749Z] 02:48:35 INFO - Extension loaded
[task 2020-10-17T02:48:35.749Z] 02:48:35 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | toDataURL() call was successful.
[task 2020-10-17T02:48:35.750Z] 02:48:35 INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | getImageData() returned the correct colour (transparent).
[task 2020-10-17T02:48:35.750Z] 02:48:35 INFO - Buffered messages finished
[task 2020-10-17T02:48:35.750Z] 02:48:35 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | Test timed out.
[task 2020-10-17T02:48:35.750Z] 02:48:35 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-10-17T02:48:35.750Z] 02:48:35 INFO - reportError@SimpleTest/TestRunner.js:143:22
[task 2020-10-17T02:48:35.751Z] 02:48:35 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:165:18
[task 2020-10-17T02:48:36.606Z] 02:48:36 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-10-17T02:48:36.607Z] 02:48:36 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | Extension left running at test shutdown
[task 2020-10-17T02:48:36.608Z] 02:48:36 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-10-17T02:48:36.609Z] 02:48:36 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:117:18
[task 2020-10-17T02:48:36.610Z] 02:48:36 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1614:13
[task 2020-10-17T02:48:36.611Z] 02:48:36 INFO - SimpleTest.finish@SimpleTest/SimpleTest.js:1628:3
[task 2020-10-17T02:48:36.612Z] 02:48:36 INFO - killTest@SimpleTest/TestRunner.js:152:22
[task 2020-10-17T02:48:36.648Z] 02:48:36 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-10-17T02:48:36.648Z] 02:48:36 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | message queue is empty - got "["success"]", expected "[]"
[task 2020-10-17T02:48:36.648Z] 02:48:36 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:500:14
[task 2020-10-17T02:48:36.649Z] 02:48:36 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:32:18
[task 2020-10-17T02:48:36.649Z] 02:48:36 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1614:13
[task 2020-10-17T02:48:36.650Z] 02:48:36 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-10-17T02:48:36.650Z] 02:48:36 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | no tasks awaiting on messages - got "["error"]", expected "[]"
[task 2020-10-17T02:48:36.650Z] 02:48:36 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:500:14
[task 2020-10-17T02:48:36.650Z] 02:48:36 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:36:18
[task 2020-10-17T02:48:36.650Z] 02:48:36 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1614:13
[task 2020-10-17T02:48:36.651Z] 02:48:36 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-10-17T02:48:36.651Z] 02:48:36 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | Extension left running at test shutdown
[task 2020-10-17T02:48:36.651Z] 02:48:36 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-10-17T02:48:36.651Z] 02:48:36 INFO - ExtensionTestUtils.loadExtension/<@SimpleTest/ExtensionTestUtils.js:117:18
[task 2020-10-17T02:48:36.651Z] 02:48:36 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1614:13
[task 2020-10-17T02:48:36.651Z] 02:48:36 INFO - executeCleanupFunction@SimpleTest/SimpleTest.js:1624:7
[task 2020-10-17T02:48:36.672Z] 02:48:36 INFO - GECKO(1794) | MEMORY STAT | vsize 2541MB | residentFast 140MB | heapAllocated 12MB
[task 2020-10-17T02:48:38.680Z] 02:48:38 INFO - TEST-OK | toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html | took 325035ms
[task 2020-10-17T02:48:41.688Z] 02:48:41 INFO - Error: Unable to restore focus, expect failures and timeouts.
[task 2020-10-17T02:48:41.690Z] 02:48:41 ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html logged result after SimpleTest.finish(): Extension left running at test shutdown
[task 2020-10-17T02:48:41.691Z] 02:48:41 ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html logged result after SimpleTest.finish(): message queue is empty
[task 2020-10-17T02:48:41.691Z] 02:48:41 ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html logged result after SimpleTest.finish(): no tasks awaiting on messages
[task 2020-10-17T02:48:41.691Z] 02:48:41 ERROR - TEST-UNEXPECTED-FAIL | /tests/toolkit/components/extensions/test/mochitest/test_ext_contentscript_canvas.html logged result after SimpleTest.finish(): Extension left running at test shutdown

Luca are there any updates here?

Flags: needinfo?(tomica)
Flags: needinfo?(lgreco)
Whiteboard: [retriggered] → [retriggered][stockwell needswork:owner]
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.