Closed Bug 1617417 Opened 7 months ago Closed 3 months ago

Intermittent toolkit/components/antitracking/test/browser/browser_addonHostPermissionIgnoredInTP.js | Expected script nodes found - null == 3 -

Categories

(Core :: Privacy: Anti-Tracking, defect, P3)

defect

Tracking

()

RESOLVED FIXED
mozilla79
Tracking Status
firefox79 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: xeonchen)

Details

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

Attachments

(1 file)

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


[task 2020-02-22T22:14:01.493Z] 22:14:01 INFO - TEST-START | toolkit/components/antitracking/test/browser/browser_addonHostPermissionIgnoredInTP.js
[task 2020-02-22T22:14:04.289Z] 22:14:04 INFO - Buffered messages finished
[task 2020-02-22T22:14:04.289Z] 22:14:04 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/antitracking/test/browser/browser_addonHostPermissionIgnoredInTP.js | Expected script nodes found - null == 3 -
[task 2020-02-22T22:14:04.290Z] 22:14:04 INFO - Stack trace:
[task 2020-02-22T22:14:04.290Z] 22:14:04 INFO - resource://testing-common/content-task.js line 110 > eval:null:8
[task 2020-02-22T22:14:04.291Z] 22:14:04 INFO - Removing the tab
[task 2020-02-22T22:14:04.291Z] 22:14:04 INFO - Leaving test bound
[task 2020-02-22T22:14:04.291Z] 22:14:04 INFO - GECKO(1222) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2020-02-22T22:14:04.292Z] 22:14:04 INFO - GECKO(1222) | MEMORY STAT | vsize 2863MB | residentFast 355MB | heapAllocated 122MB
[task 2020-02-22T22:14:04.292Z] 22:14:04 INFO - TEST-OK | toolkit/components/antitracking/test/browser/browser_addonHostPermissionIgnoredInTP.js | took 2705ms
[task 2020-02-22T22:14:04.293Z] 22:14:04 INFO - GECKO(1222) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f2c5a2bd000 == 1 [pid = 1542] [id = {416346c2-ef4d-4a75-ab42-a9abd9aae739}] [url = https://tracking.example.com/browser/toolkit/components/antitracking/test/browser/container2.html]
[task 2020-02-22T22:14:04.298Z] 22:14:04 INFO - GECKO(1222) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f2c5a2ca800 == 0 [pid = 1542] [id = {b2a57da2-a5f7-4bce-bfee-796edf1cc837}] [url = https://tracking.example.com/browser/toolkit/components/antitracking/test/browser/embedder2.html]
[task 2020-02-22T22:14:04.299Z] 22:14:04 INFO - GECKO(1222) | [Child 1518: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f5b60bf0000 == 1 [pid = 1518] [id = {84609488-4212-4bcd-8b52-1ea5f8762d71}]
[task 2020-02-22T22:14:04.299Z] 22:14:04 INFO - GECKO(1222) | [Child 1518: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f5b7b993520) [pid = 1518] [serial = 1] [outer = (nil)]
[task 2020-02-22T22:14:04.299Z] 22:14:04 INFO - GECKO(1222) | [Child 1518, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 847
[task 2020-02-22T22:14:04.300Z] 22:14:04 INFO - GECKO(1222) | [Child 1518: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f5b61382000) [pid = 1518] [serial = 2] [outer = 0x7f5b7b993520]
[task 2020-02-22T22:14:04.315Z] 22:14:04 INFO - GECKO(1222) | [Child 1282: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (0x7f6944095090) [pid = 1282] [serial = 22] [outer = (nil)] [url = ]
[task 2020-02-22T22:14:04.356Z] 22:14:04 INFO - GECKO(1222) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7f2c75093520) [pid = 1542] [serial = 1] [outer = (nil)] [url = https://tracking.example.com/browser/toolkit/components/antitracking/test/browser/container2.html]
[task 2020-02-22T22:14:04.356Z] 22:14:04 INFO - GECKO(1222) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7f2c75093350) [pid = 1542] [serial = 4] [outer = (nil)] [url = https://tracking.example.com/browser/toolkit/components/antitracking/test/browser/embedder2.html]
[task 2020-02-22T22:14:04.356Z] 22:14:04 INFO - GECKO(1222) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f2c5aa72800) [pid = 1542] [serial = 5] [outer = (nil)] [url = https://tracking.example.com/browser/toolkit/components/antitracking/test/browser/embedder2.html]
[task 2020-02-22T22:14:04.359Z] 22:14:04 INFO - GECKO(1222) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f2c5a11f000) [pid = 1542] [serial = 3] [outer = (nil)] [url = https://tracking.example.com/browser/toolkit/components/antitracking/test/browser/container2.html]
[task 2020-02-22T22:14:04.360Z] 22:14:04 INFO - GECKO(1222) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f2c5aa64000) [pid = 1542] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-02-22T22:14:04.409Z] 22:14:04 INFO - GECKO(1222) | [Child 1393: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fc6c0519800 == 1 [pid = 1393] [id = {28230e56-9825-4d94-b6c5-f2c084ccf0c7}] [url = about:blank]
[task 2020-02-22T22:14:04.410Z] 22:14:04 INFO - GECKO(1222) | [Child 1542, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362

baku would you mind to take a look?

Severity: normal → S3
Flags: needinfo?(amarchesini)
Priority: P5 → P3

In the past week there were 46 failures on:
-linux1804-64 debug.

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

[task 2020-06-13T22:26:48.616Z] 22:26:48 INFO - TEST-START | toolkit/components/antitracking/test/browser/browser_addonHostPermissionIgnoredInTP.js
[task 2020-06-13T22:26:48.937Z] 22:26:48 INFO - GECKO(1253) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpnmW49s.mozrunner/runtests_leaks_tab_pid1617.log
[task 2020-06-13T22:26:48.937Z] 22:26:48 INFO - GECKO(1253) | [1617, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 244
[task 2020-06-13T22:26:49.236Z] 22:26:49 INFO - GECKO(1253) | [Parent 1253, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp, line 1086
[task 2020-06-13T22:26:49.237Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fcf56c55c00 == 6 [pid = 1430] [id = {e3a4ddd3-02d2-41b5-ae7e-413fa944a078}]
[task 2020-06-13T22:26:49.238Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (0x7fcf6ca93350) [pid = 1430] [serial = 16] [outer = (nil)]
[task 2020-06-13T22:26:49.238Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (0x7fcf56c6e000) [pid = 1430] [serial = 17] [outer = 0x7fcf6ca93350]
[task 2020-06-13T22:26:49.272Z] 22:26:49 INFO - GECKO(1253) | [Child 1430, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp, line 2656
[task 2020-06-13T22:26:49.293Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 18 (0x7fcf56ca4800) [pid = 1430] [serial = 18] [outer = 0x7fcf6ca93350]
[task 2020-06-13T22:26:49.409Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fcf56c72c00 == 7 [pid = 1430] [id = {532823a1-ba44-40e9-9cbd-9f430189353d}]
[task 2020-06-13T22:26:49.410Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (0x7fcf6ca93e30) [pid = 1430] [serial = 19] [outer = (nil)]
[task 2020-06-13T22:26:49.413Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (0x7fcf56ca8800) [pid = 1430] [serial = 20] [outer = 0x7fcf6ca93e30]
[task 2020-06-13T22:26:49.449Z] 22:26:49 INFO - GECKO(1253) | [Parent 1253, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp, line 1607
[task 2020-06-13T22:26:49.478Z] 22:26:49 INFO - GECKO(1253) | [Child 1414: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f1cc0ecd000 == 0 [pid = 1414] [id = {43f073be-f62f-4036-8f9c-7f65d4d867e1}] [url = about:blank]
[task 2020-06-13T22:26:49.600Z] 22:26:49 INFO - GECKO(1253) | [Child 1617, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1629
[task 2020-06-13T22:26:49.661Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (0x7fcf4e767800) [pid = 1430] [serial = 21] [outer = 0x7fcf6ca93e30]
[task 2020-06-13T22:26:49.779Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fcf4e768800 == 8 [pid = 1430] [id = {dd89cfc3-762b-4a37-bd09-322f017264fd}]
[task 2020-06-13T22:26:49.780Z] 22:26:49 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 22 (0x7fcf4e783090) [pid = 1430] [serial = 22] [outer = (nil)]
[task 2020-06-13T22:26:49.902Z] 22:26:49 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fbbd222fc00 == 1 [pid = 1542] [id = {dd89cfc3-762b-4a37-bd09-322f017264fd}]
[task 2020-06-13T22:26:49.905Z] 22:26:49 INFO - GECKO(1253) | [Child 1430, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsLoadGroup.cpp, line 595
[task 2020-06-13T22:26:49.953Z] 22:26:49 INFO - GECKO(1253) | [Child 1430, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/checkouts/gecko/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2020-06-13T22:26:49.975Z] 22:26:49 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7fbbe8193520) [pid = 1542] [serial = 1] [outer = (nil)]
[task 2020-06-13T22:26:49.982Z] 22:26:49 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7fbbccab1000) [pid = 1542] [serial = 2] [outer = 0x7fbbe8193520]
[task 2020-06-13T22:26:50.058Z] 22:26:50 INFO - GECKO(1253) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpnmW49s.mozrunner/runtests_leaks_tab_pid1641.log
[task 2020-06-13T22:26:50.059Z] 22:26:50 INFO - GECKO(1253) | [1641, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 244
[task 2020-06-13T22:26:50.100Z] 22:26:50 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fbbccaba800) [pid = 1542] [serial = 3] [outer = 0x7fbbe8193520]
[task 2020-06-13T22:26:50.122Z] 22:26:50 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fbbd2157400 == 2 [pid = 1542] [id = {a4d95653-cb3e-4156-b5ba-dae833a9da33}]
[task 2020-06-13T22:26:50.122Z] 22:26:50 INFO - GECKO(1253) | [Child 1542, Main Thread] WARNING: '!topWindow', file /builds/worker/checkouts/gecko/dom/html/HTMLIFrameElement.cpp, line 256
[task 2020-06-13T22:26:50.123Z] 22:26:50 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7fbbe81936f0) [pid = 1542] [serial = 4] [outer = (nil)]
[task 2020-06-13T22:26:50.250Z] 22:26:50 INFO - GECKO(1253) | [Child 1542: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7fbbd215f800) [pid = 1542] [serial = 5] [outer = 0x7fbbe81936f0]
[task 2020-06-13T22:26:50.771Z] 22:26:50 INFO - GECKO(1253) | [Child 1641, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp, line 1629
[task 2020-06-13T22:26:50.815Z] 22:26:50 INFO - GECKO(1253) | [Child 1430: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (0x7fcf4e783090) [pid = 1430] [serial = 22] [outer = (nil)] [url = ]
[task 2020-06-13T22:26:50.844Z] 22:26:50 INFO - TEST-INFO | started process screentopng
[task 2020-06-13T22:26:51.066Z] 22:26:51 INFO - TEST-INFO | screentopng: exit 0
[task 2020-06-13T22:26:51.066Z] 22:26:51 INFO - Buffered messages logged at 22:26:48
[task 2020-06-13T22:26:51.066Z] 22:26:51 INFO - Entering test bound
[task 2020-06-13T22:26:51.067Z] 22:26:51 INFO - Starting test
[task 2020-06-13T22:26:51.068Z] 22:26:51 INFO - Buffered messages logged at 22:26:49
[task 2020-06-13T22:26:51.068Z] 22:26:51 INFO - Extension loaded
[task 2020-06-13T22:26:51.068Z] 22:26:51 INFO - Creating a new tab
[task 2020-06-13T22:26:51.072Z] 22:26:51 INFO - 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: "moz-extension://4810efef-a671-4e08-bdff-e53e1f414130/page.html" line: 0}]
[task 2020-06-13T22:26:51.072Z] 22:26:51 INFO - Buffered messages logged at 22:26:50
[task 2020-06-13T22:26:51.074Z] 22:26:51 INFO - 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: "https://tracking.example.com/browser/toolkit/components/antitracking/test/browser/embedder2.html" line: 0}]
[task 2020-06-13T22:26:51.074Z] 22:26:51 INFO - Verify the number of script nodes found
[task 2020-06-13T22:26:51.075Z] 22:26:51 INFO - Buffered messages finished
[task 2020-06-13T22:26:51.075Z] 22:26:51 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/antitracking/test/browser/browser_addonHostPermissionIgnoredInTP.js | Expected script nodes found - null == 3 -

Steven, could you take a look?

Flags: needinfo?(senglehardt)
Whiteboard: [stockwell needswork:owner]
Assignee: nobody → xeonchen
Flags: needinfo?(senglehardt)
Flags: needinfo?(amarchesini)
Pushed by xeonchen@gmail.com:
https://hg.mozilla.org/integration/autoland/rev/73a8750f8858
use waitForCondition instead of setTimeout; r=dimi
Status: NEW → RESOLVED
Closed: 3 months ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla79
You need to log in before you can comment on or make changes to this bug.