1683455 - Intermittent toolkit/components/extensions/test/mochitest/test_ext_identity.html | Test timed out.

Status: RESOLVED INCOMPLETE

(WebExtensions :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-12-18T22:54:43.282Z] 22:54:43     INFO - TEST-START | toolkit/components/extensions/test/mochitest/test_ext_identity.html
[task 2020-12-18T22:54:43.400Z] 22:54:43     INFO - GECKO(5668) | Console message: [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_identity.html" line: 0}]
[task 2020-12-18T22:54:44.306Z] 22:54:44     INFO - GECKO(5668) | Console message: [JavaScript Error: "XML Parsing Error: syntax error
[task 2020-12-18T22:54:44.306Z] 22:54:44     INFO - GECKO(5668) | Location: https://example.com/tests/toolkit/components/extensions/test/mochitest/redirect_auto.sjs?redirect_uri=https%3A%2F%2F35b64b676900f491c00e7f618d43f7040e88422e.example.com%2Fidentity_cb&no_redirect=1
[task 2020-12-18T22:54:44.307Z] 22:54:44     INFO - GECKO(5668) | Line Number 1, Column 1:" {file: "https://example.com/tests/toolkit/components/extensions/test/mochitest/redirect_auto.sjs?redirect_uri=https%3A%2F%2F35b64b676900f491c00e7f618d43f7040e88422e.example.com%2Fidentity_cb&no_redirect=1" line: 1 column: 1 source: "ok"}]
[task 2020-12-18T22:54:44.899Z] 22:54:44     INFO - GECKO(5668) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "https://example.com/tests/toolkit/components/extensions/test/mochitest/oauth.html?redirect_uri=https%3A%2F%2F35b64b676900f491c00e7f618d43f7040e88422e.example.com%2Fidentity_cb" line: 0}]
[task 2020-12-18T22:54:44.935Z] 22:54:44     INFO - GECKO(5668) | JavaScript error: , line 0: AbortError: Actor 'Conduits' destroyed before query 'RunListener' was resolved
[task 2020-12-18T22:54:44.935Z] 22:54:44     INFO - GECKO(5668) | Console message: [JavaScript Error: "AbortError: Actor 'Conduits' destroyed before query 'RunListener' was resolved"]
[task 2020-12-18T22:54:44.971Z] 22:54:44     INFO - GECKO(5668) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-12-18T22:54:45.654Z] 22:54:45     INFO - GECKO(5668) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "https://example.com/tests/toolkit/components/extensions/test/mochitest/oauth.html?post=303&server_uri=redirect_auto.sjs&redirect_uri=https%3A%2F%2F35b64b676900f491c00e7f618d43f7040e88422e.example.com%2Fidentity_cb" line: 0}]
[task 2020-12-18T22:54:45.738Z] 22:54:45     INFO - GECKO(5668) | Console message: [JavaScript Warning: "A form was submitted in the windows-1252 encoding which cannot encode all Unicode characters, so user input may get corrupted. To avoid this problem, the page should be changed so that the form is submitted in the UTF-8 encoding either by changing the encoding of the page itself to UTF-8 or by specifying accept-charset=utf-8 on the form element." {file: "https://example.com/tests/toolkit/components/extensions/test/mochitest/oauth.html?post=303&server_uri=redirect_auto.sjs&redirect_uri=https%3A%2F%2F35b64b676900f491c00e7f618d43f7040e88422e.example.com%2Fidentity_cb" line: 13}]
[task 2020-12-18T22:54:46.118Z] 22:54:46     INFO - GECKO(5668) | [CodeCoverage] Setting handlers for process 5932.
[task 2020-12-18T22:54:47.368Z] 22:54:47     INFO - GECKO(5668) | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "https://example.com/tests/toolkit/components/extensions/test/mochitest/oauth.html?redirect_uri=http%3A%2F%2F127.0.0.1%2Fmozoauth2%2F35b64b676900f491c00e7f618d43f7040e88422e" line: 0}]
[task 2020-12-18T22:54:47.550Z] 22:54:47     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5668.
[task 2020-12-18T22:54:47.788Z] 22:54:47     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T22:54:49.230Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T22:54:49.231Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5736.
[task 2020-12-18T22:54:49.232Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5818.
[task 2020-12-18T22:54:49.233Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5898.
[task 2020-12-18T22:54:49.233Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5740.
[task 2020-12-18T22:54:49.233Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5932.
[task 2020-12-18T22:54:49.475Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T22:54:49.516Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T22:54:49.737Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T22:54:49.819Z] 22:54:49     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T22:54:50.048Z] 22:54:50     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T22:54:50.088Z] 22:54:50     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T22:54:50.310Z] 22:54:50     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T22:54:50.372Z] 22:54:50     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T22:54:50.593Z] 22:54:50     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T22:54:50.608Z] 22:54:50     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T22:54:50.610Z] 22:54:50     INFO - GECKO(5668) | JavaScript error: resource://testing-common/PerTestCoverageUtils.jsm, line 76: NS_ERROR_FILE_DIR_NOT_EMPTY: Component returned failure code: 0x80520014 (NS_ERROR_FILE_DIR_NOT_EMPTY) [nsIFile.moveTo]
[task 2020-12-18T23:00:05.099Z] 23:00:05     INFO - TEST-INFO | started process screentopng
[task 2020-12-18T23:00:05.244Z] 23:00:05     INFO - TEST-INFO | screentopng: exit 0
[task 2020-12-18T23:00:05.244Z] 23:00:05     INFO - Buffered messages logged at 22:54:43
[task 2020-12-18T23:00:05.244Z] 23:00:05     INFO - add_task | Entering test setup
[task 2020-12-18T23:00:05.244Z] 23:00:05     INFO - add_task | Leaving test setup
[task 2020-12-18T23:00:05.244Z] 23:00:05     INFO - add_task | Entering test test_noPermission
[task 2020-12-18T23:00:05.244Z] 23:00:05     INFO - Extension loaded
[task 2020-12-18T23:00:05.244Z] 23:00:05     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_identity.html | No identity api without permission - Expected: undefined, Actual: undefined 
[task 2020-12-18T23:00:05.244Z] 23:00:05     INFO - add_task | Leaving test test_noPermission
[task 2020-12-18T23:00:05.245Z] 23:00:05     INFO - add_task | Entering test test_getRedirectURL
[task 2020-12-18T23:00:05.245Z] 23:00:05     INFO - Extension loaded

[task 2020-12-18T23:00:05.264Z] 23:00:05     INFO - TEST-PASS | toolkit/components/extensions/test/mochitest/test_ext_identity.html | Expected redirect url to be loopback address 
[task 2020-12-18T23:00:05.264Z] 23:00:05     INFO - add_task | Leaving test test_loopbackRedirectURI
[task 2020-12-18T23:00:05.265Z] 23:00:05     INFO - Buffered messages finished
[task 2020-12-18T23:00:05.265Z] 23:00:05     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_identity.html | Test timed out. 
[task 2020-12-18T23:00:05.265Z] 23:00:05     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-12-18T23:00:05.265Z] 23:00:05     INFO -     reportError@SimpleTest/TestRunner.js:143:22
[task 2020-12-18T23:00:05.265Z] 23:00:05     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:165:18
[task 2020-12-18T23:00:06.095Z] 23:00:06    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2020-12-18T23:00:06.111Z] 23:00:06     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5668.
[task 2020-12-18T23:00:06.333Z] 23:00:06     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T23:00:06.911Z] 23:00:06     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T23:00:06.912Z] 23:00:06     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5740.
[task 2020-12-18T23:00:06.912Z] 23:00:06     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5818.
[task 2020-12-18T23:00:06.912Z] 23:00:06     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5932.
[task 2020-12-18T23:00:06.912Z] 23:00:06     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5898.
[task 2020-12-18T23:00:06.912Z] 23:00:06     INFO - GECKO(5668) | [CodeCoverage] Requested flush for 5736.
[task 2020-12-18T23:00:07.133Z] 23:00:07     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T23:00:07.189Z] 23:00:07     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T23:00:07.411Z] 23:00:07     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T23:00:07.495Z] 23:00:07     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T23:00:07.719Z] 23:00:07     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T23:00:07.735Z] 23:00:07     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T23:00:07.971Z] 23:00:07     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T23:00:08.007Z] 23:00:08     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T23:00:08.231Z] 23:00:08     INFO - GECKO(5668) | [CodeCoverage] flush completed.
[task 2020-12-18T23:00:08.273Z] 23:00:08     INFO - GECKO(5668) | [CodeCoverage] JS flush completed.
[task 2020-12-18T23:00:08.274Z] 23:00:08     INFO - GECKO(5668) | JavaScript error: resource://testing-common/PerTestCoverageUtils.jsm, line 76: NS_ERROR_FILE_DIR_NOT_EMPTY: Component returned failure code: 0x80520014 (NS_ERROR_FILE_DIR_NOT_EMPTY) [nsIFile.moveTo]
[task 2020-12-18T23:00:35.097Z] 23:00:35     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-12-18T23:00:35.097Z] 23:00:35     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/mochitest/test_ext_identity.html | Test timed out. 
[task 2020-12-18T23:00:35.097Z] 23:00:35     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-12-18T23:00:35.097Z] 23:00:35     INFO -     reportError@SimpleTest/TestRunner.js:143:22
[task 2020-12-18T23:00:35.097Z] 23:00:35     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:165:18
[task 2020-12-18T23:00:36.102Z] 23:00:36    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!```

Comment 1

[Intermittent Failures Robot]

1 failures in 3421 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1683455&startday=2020-12-14&endday=2020-12-20&tree=all

Comment 2

[BugBot [:suhaib / :marco]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.