Closed Bug 1585098 Opened 5 years ago Closed 5 years ago

Perma fission browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js | Counts one instance of the shield being shown - Got undefined, expected 1

Categories

(Firefox :: Protections UI, defect, P5)

Product:
Firefox
Component:
Protections UI
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, regression)

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=269087968&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/Tjk6Q7-QQh-bdPaKHszXXg/runs/0/artifacts/public/logs/live_backing.log

task 2019-09-30T17:02:56.050Z] 17:02:56 INFO - TEST-START | browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js
[task 2019-09-30T17:02:56.134Z] 17:02:56 INFO - GECKO(4346) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-09-30T17:02:56.250Z] 17:02:56 INFO - GECKO(4346) | ++DOCSHELL 0x7f68d50f4800 == 1 [pid = 4478] [id = {9e4859f1-a4fb-44c5-a086-f590dd4d18aa}]
[task 2019-09-30T17:02:56.251Z] 17:02:56 INFO - GECKO(4346) | ++DOMWINDOW == 3 (0x7f68d40232e0) [pid = 4478] [serial = 3] [outer = (nil)]
[task 2019-09-30T17:02:56.251Z] 17:02:56 INFO - GECKO(4346) | ++DOMWINDOW == 4 (0x7f68d4072000) [pid = 4478] [serial = 4] [outer = 0x7f68d40232e0]
[task 2019-09-30T17:02:56.331Z] 17:02:56 INFO - GECKO(4346) | ++DOMWINDOW == 5 (0x7f68d5175800) [pid = 4478] [serial = 5] [outer = 0x7f68d40232e0]
[task 2019-09-30T17:02:56.369Z] 17:02:56 INFO - GECKO(4346) | ++DOCSHELL 0x7f5f474b6800 == 1 [pid = 4729] [id = {88835de3-f3ab-44c2-9d9c-8d3d4be9691a}]
[task 2019-09-30T17:02:56.450Z] 17:02:56 INFO - GECKO(4346) | ++DOMWINDOW == 1 (0x7f5f484c1f20) [pid = 4729] [serial = 1] [outer = (nil)]
[task 2019-09-30T17:02:56.457Z] 17:02:56 INFO - GECKO(4346) | ++DOMWINDOW == 2 (0x7f5f47437c00) [pid = 4729] [serial = 2] [outer = 0x7f5f484c1f20]
[task 2019-09-30T17:02:56.735Z] 17:02:56 INFO - GECKO(4346) | --DOMWINDOW == 3 (0x7f123ffcf000) [pid = 4419] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2019-09-30T17:02:56.735Z] 17:02:56 INFO - GECKO(4346) | --DOMWINDOW == 2 (0x7f123f6e6400) [pid = 4419] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2019-09-30T17:02:56.916Z] 17:02:56 INFO - GECKO(4346) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpBboNWD.mozrunner/runtests_leaks_tab_pid4754.log
[task 2019-09-30T17:02:57.053Z] 17:02:57 INFO - GECKO(4346) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-09-30T17:02:57.150Z] 17:02:57 INFO - GECKO(4346) | --DOMWINDOW == 2 (0x7fb20ab2fc00) [pid = 4507] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-09-30T17:02:57.166Z] 17:02:57 INFO - GECKO(4346) | ++DOCSHELL 0x7f23360b7000 == 1 [pid = 4754] [id = {4ece0af2-8603-4e7f-86be-78436777c473}]
[task 2019-09-30T17:02:57.222Z] 17:02:57 INFO - GECKO(4346) | --DOCSHELL 0x7fb20abaf800 == 0 [pid = 4507] [id = {e17a4cc4-a741-4c5f-a7cd-c08e9a62c66e}] [url = about:privatebrowsing]
[task 2019-09-30T17:02:57.222Z] 17:02:57 INFO - GECKO(4346) | --DOMWINDOW == 1 (0x7fb20ab23d40) [pid = 4507] [serial = 1] [outer = (nil)] [url = about:privatebrowsing]
[task 2019-09-30T17:02:57.223Z] 17:02:57 INFO - GECKO(4346) | ++DOMWINDOW == 1 (0x7f23370ddf20) [pid = 4754] [serial = 1] [outer = (nil)]
[task 2019-09-30T17:02:57.223Z] 17:02:57 INFO - GECKO(4346) | ++DOMWINDOW == 2 (0x7f2336036000) [pid = 4754] [serial = 2] [outer = 0x7f23370ddf20]
[task 2019-09-30T17:02:57.433Z] 17:02:57 INFO - GECKO(4346) | ++DOMWINDOW == 3 (0x7f233700c000) [pid = 4754] [serial = 3] [outer = 0x7f23370ddf20]
[task 2019-09-30T17:02:57.473Z] 17:02:57 INFO - GECKO(4346) | --DOMWINDOW == 6 (0x7f6db59bc000) [pid = 4396] [serial = 12] [outer = (nil)] [url = moz-extension://a84242b1-69fb-424b-83ca-12043f562b79/_generated_background_page.html]
[task 2019-09-30T17:02:57.671Z] 17:02:57 INFO - GECKO(4346) | ++DOMWINDOW == 4 (0x7f2337005000) [pid = 4754] [serial = 4] [outer = 0x7f23370ddf20]
[task 2019-09-30T17:02:57.795Z] 17:02:57 INFO - GECKO(4346) | ++DOCSHELL 0x7f2336a1f000 == 2 [pid = 4754] [id = {cd1ee03a-3c8e-4e9e-a948-c3d94c272cba}]
[task 2019-09-30T17:02:57.797Z] 17:02:57 INFO - GECKO(4346) | ++DOMWINDOW == 5 (0x7f23370de2e0) [pid = 4754] [serial = 5] [outer = (nil)]
[task 2019-09-30T17:02:57.817Z] 17:02:57 INFO - GECKO(4346) | [Child 4754, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 41
[task 2019-09-30T17:02:57.819Z] 17:02:57 INFO - GECKO(4346) | [Child 4754, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 41
[task 2019-09-30T17:02:57.839Z] 17:02:57 INFO - GECKO(4346) | [Child 4754, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-09-30T17:02:57.908Z] 17:02:57 INFO - GECKO(4346) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpBboNWD.mozrunner/runtests_leaks_tab_pid4780.log
[task 2019-09-30T17:02:58.070Z] 17:02:58 INFO - GECKO(4346) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-09-30T17:02:58.218Z] 17:02:58 INFO - GECKO(4346) | ++DOCSHELL 0x7fc67c9b7000 == 1 [pid = 4780] [id = {3229c3be-8b4f-49c4-b5d2-ccd1e1a1fd96}]
[task 2019-09-30T17:02:58.259Z] 17:02:58 INFO - GECKO(4346) | ++DOMWINDOW == 1 (0x7fc67d8dbf20) [pid = 4780] [serial = 1] [outer = (nil)]
[task 2019-09-30T17:02:58.260Z] 17:02:58 INFO - GECKO(4346) | ++DOMWINDOW == 2 (0x7fc67c936c00) [pid = 4780] [serial = 2] [outer = 0x7fc67d8dbf20]
[task 2019-09-30T17:02:58.321Z] 17:02:58 INFO - GECKO(4346) | JavaScript error: resource:///actors/PageStyleChild.jsm, line 35: SecurityError: Permission denied to access property "document" on cross-origin object
[task 2019-09-30T17:02:58.329Z] 17:02:58 INFO - TEST-INFO | started process screentopng
[task 2019-09-30T17:02:58.874Z] 17:02:58 INFO - TEST-INFO | screentopng: exit 0
[task 2019-09-30T17:02:58.874Z] 17:02:58 INFO - Buffered messages logged at 17:02:56
[task 2019-09-30T17:02:58.874Z] 17:02:58 INFO - Entering test bound setup
[task 2019-09-30T17:02:58.874Z] 17:02:58 INFO - TEST-PASS | browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js | TP is attached to the browser window -
[task 2019-09-30T17:02:58.875Z] 17:02:58 INFO - TEST-PASS | browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js | TP is not enabled -
[task 2019-09-30T17:02:58.875Z] 17:02:58 INFO - TEST-PASS | browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js | TP was not enabled on start up -
[task 2019-09-30T17:02:58.875Z] 17:02:58 INFO - TEST-PASS | browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js | no CB exceptions at startup -
[task 2019-09-30T17:02:58.875Z] 17:02:58 INFO - Leaving test bound setup
[task 2019-09-30T17:02:58.875Z] 17:02:58 INFO - Entering test bound testShieldHistogram
[task 2019-09-30T17:02:58.875Z] 17:02:58 INFO - Wait tab event: load
[task 2019-09-30T17:02:58.875Z] 17:02:58 INFO - Buffered messages logged at 17:02:57
[task 2019-09-30T17:02:58.875Z] 17:02:58 INFO - Tab event received: load
[task 2019-09-30T17:02:58.876Z] 17:02:58 INFO - TEST-PASS | browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js | Page loads without tracking -
[task 2019-09-30T17:02:58.876Z] 17:02:58 INFO - Wait tab event: load
[task 2019-09-30T17:02:58.876Z] 17:02:58 INFO - Buffered messages logged at 17:02:58
[task 2019-09-30T17:02:58.880Z] 17:02:58 INFO - Console message: [JavaScript Warning: "The resource at “http://trackertest.org/” was blocked because content blocking is enabled." {file: "about:blank" line: 0}]
[task 2019-09-30T17:02:58.880Z] 17:02:58 INFO - Tab event received: load
[task 2019-09-30T17:02:58.880Z] 17:02:58 INFO - TEST-PASS | browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js | Adds one more page load -
[task 2019-09-30T17:02:58.880Z] 17:02:58 INFO - Buffered messages finished
[task 2019-09-30T17:02:58.881Z] 17:02:58 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js | Counts one instance of the shield being shown - Got undefined, expected 1
[task 2019-09-30T17:02:58.881Z] 17:02:58 INFO - Stack trace:
[task 2019-09-30T17:02:58.881Z] 17:02:58 INFO - chrome://mochikit/content/browser-test.js:test_is:1595
[task 2019-09-30T17:02:58.881Z] 17:02:58 INFO - chrome://mochitests/content/browser/browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js:testShieldHistogram:62
[task 2019-09-30T17:02:58.881Z] 17:02:58 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1350
[task 2019-09-30T17:02:58.881Z] 17:02:58 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1385
[task 2019-09-30T17:02:58.882Z] 17:02:58 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1213
[task 2019-09-30T17:02:58.882Z] 17:02:58 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-09-30T17:02:58.882Z] 17:02:58 INFO - Disable TP for the page (which reloads the page)
[task 2019-09-30T17:02:58.884Z] 17:02:58 INFO - Wait tab event: load
[task 2019-09-30T17:02:58.885Z] 17:02:58 INFO - Console message: [JavaScript Error: "SecurityError: Permission denied to access property "document" on cross-origin object" {file: "resource:///actors/PageStyleChild.jsm" line: 35}]
[task 2019-09-30T17:02:58.885Z] 17:02:58 INFO - GECKO(4346) | --DOCSHELL 0x7f62ebdb4800 == 0 [pid = 4608] [id = {d77e8e17-e3f6-420b-91e0-656c38a6624b}] [url = about:blank]
[task 2019-09-30T17:02:58.885Z] 17:02:58 INFO - GECKO(4346) | ++DOMWINDOW == 6 (0x7f23370b9800) [pid = 4754] [serial = 6] [outer = 0x7f23370ddf20]
[task 2019-09-30T17:02:58.886Z] 17:02:58 INFO - GECKO(4346) | ++DOCSHELL 0x7f2337029800 == 3 [pid = 4754] [id = {8789315d-8937-4aeb-913c-82784da12d2f}]
[task 2019-09-30T17:02:58.886Z] 17:02:58 INFO - GECKO(4346) | ++DOMWINDOW == 7 (0x7f23370de4c0) [pid = 4754] [serial = 7] [outer = (nil)]
[task 2019-09-30T17:02:58.886Z] 17:02:58 INFO - GECKO(4346) | [Child 4754, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 41
[task 2019-09-30T17:02:58.887Z] 17:02:58 INFO - GECKO(4346) | [Child 4754, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 41
[task 2019-09-30T17:02:58.889Z] 17:02:58 INFO - GECKO(4346) | [Child 4754, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-09-30T17:02:58.965Z] 17:02:58 INFO - GECKO(4346) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpBboNWD.mozrunner/runtests_leaks_tab_pid4803.log
[task 2019-09-30T17:02:59.087Z] 17:02:59 INFO - GECKO(4346) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-09-30T17:02:59.217Z] 17:02:59 INFO - GECKO(4346) | ++DOCSHELL 0x7fbf85fb6000 == 1 [pid = 4803] [id = {895ef89f-960f-4f29-afaa-8887486e21de}]
[task 2019-09-30T17:02:59.257Z] 17:02:59 INFO - GECKO(4346) | ++DOMWINDOW == 1 (0x7fbf86f99f20) [pid = 4803] [serial = 1] [outer = (nil)]
[task 2019-09-30T17:02:59.261Z] 17:02:59 INFO - GECKO(4346) | ++DOMWINDOW == 2 (0x7fbf85f3bc00) [pid = 4803] [serial = 2] [outer = 0x7fbf86f99f20]
[task 2019-09-30T17:02:59.303Z] 17:02:59 INFO - GECKO(4346) | ++DOMWINDOW == 3 (0x7fbf86f08c00) [pid = 4803] [serial = 3] [outer = 0x7fbf86f99f20]
[task 2019-09-30T17:02:59.406Z] 17:02:59 INFO - GECKO(4346) | --DOMWINDOW == 3 (0x7f8217a33800) [pid = 4565] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-09-30T17:02:59.414Z] 17:02:59 INFO - GECKO(4346) | --DOMWINDOW == 2 (0x7f82183b0000) [pid = 4565] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2019-09-30T17:02:59.416Z] 17:02:59 INFO - GECKO(4346) | --DOCSHELL 0x7fc67c9b7000 == 0 [pid = 4780] [id = {3229c3be-8b4f-49c4-b5d2-ccd1e1a1fd96}] [url = about:blank]
[task 2019-09-30T17:02:59.416Z] 17:02:59 INFO - Tab event received: load
[task 2019-09-30T17:02:59.419Z] 17:02:59 INFO - TEST-PASS | browser/base/content/test/trackingUI/browser_trackingUI_telemetry.js | Adds one more page load -

All failures are on fission. Failures started when https://hg.mozilla.org/mozilla-central/rev/7a87452a5f79 removed the skip-if for this test; bug 1582531 will re-skip.

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.