Closed Bug 1638146 Opened 4 years ago Closed 4 years ago

Intermittent browser/modules/test/browser/browser_UsageTelemetry.js | TAB_COUNT - Navigated in existing tab - {"1":0,"2":1,"3":1,"4":0} deepEqual {"1":0,"2":1,"3":2,"4":0} - JS frame :: chrome://mochitests/content/browser/browser/modules/test/browser/

Categories

(Core :: Performance, defect, P5)

Product:
Core
Component:
Performance
Type:
defect

Tracking

()

Status:
RESOLVED INVALID

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=302331471&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/DYbq_NDcTa2CunFyJpvguw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/DYbq_NDcTa2CunFyJpvguw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

 INFO - TEST-PASS | browser/modules/test/browser/browser_UsageTelemetry.js | LOADED_TAB_COUNT - Restored pending tab - {"1":0,"2":2,"3":1,"4":0} deepEqual {"1":0,"2":2,"3":1,"4":0} - 
[task 2020-05-14T20:01:05.311Z] 20:01:05     INFO - Buffered messages finished
[task 2020-05-14T20:01:05.312Z] 20:01:05     INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_UsageTelemetry.js | TAB_COUNT - Navigated in existing tab - {"1":0,"2":1,"3":1,"4":0} deepEqual {"1":0,"2":1,"3":2,"4":0} - JS frame :: chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js :: checkTabCountHistogram :: line 277
[task 2020-05-14T20:01:05.312Z] 20:01:05     INFO - Stack trace:
[task 2020-05-14T20:01:05.312Z] 20:01:05     INFO - chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js:checkTabCountHistogram:277
[task 2020-05-14T20:01:05.313Z] 20:01:05     INFO - chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js:test_loadedTabsHistogram:510
[task 2020-05-14T20:01:05.313Z] 20:01:05     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1064
[task 2020-05-14T20:01:05.313Z] 20:01:05     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1104
[task 2020-05-14T20:01:05.313Z] 20:01:05     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:927
[task 2020-05-14T20:01:05.313Z] 20:01:05     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-05-14T20:01:05.314Z] 20:01:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-14T20:01:05.315Z] 20:01:05     INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_UsageTelemetry.js | LOADED_TAB_COUNT - Navigated in existing tab - {"1":0,"2":2,"3":1,"4":0} deepEqual {"1":0,"2":2,"3":2,"4":0} - JS frame :: chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js :: checkTabCountHistogram :: line 277
[task 2020-05-14T20:01:05.315Z] 20:01:05     INFO - Stack trace:
[task 2020-05-14T20:01:05.315Z] 20:01:05     INFO - chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js:checkTabCountHistogram:277
[task 2020-05-14T20:01:05.316Z] 20:01:05     INFO - chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js:test_loadedTabsHistogram:516
[task 2020-05-14T20:01:05.316Z] 20:01:05     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1064
[task 2020-05-14T20:01:05.316Z] 20:01:05     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1104
[task 2020-05-14T20:01:05.316Z] 20:01:05     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:927
[task 2020-05-14T20:01:05.316Z] 20:01:05     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-05-14T20:01:05.319Z] 20:01:05     INFO - GECKO(5173) | [Parent 5173: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fc298e83000 == 12 [pid = 5173] [id = {4fff1483-342c-4f97-9b34-eceac19a32a9}]
[task 2020-05-14T20:01:05.319Z] 20:01:05     INFO - GECKO(5173) | [Parent 5173: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 29 (0x7fc29bed2bc0) [pid = 5173] [serial = 31] [outer = (nil)]
[task 2020-05-14T20:01:05.319Z] 20:01:05     INFO - GECKO(5173) | [Parent 5173: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 30 (0x7fc298ebb400) [pid = 5173] [serial = 32] [outer = 0x7fc29bed2bc0]
[task 2020-05-14T20:01:05.319Z] 20:01:05     INFO - GECKO(5173) | [Child 5526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7ff501124c00) [pid = 5526] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-05-14T20:01:05.319Z] 20:01:05     INFO - GECKO(5173) | [Child 5526: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7ff501127c00 == 0 [pid = 5526] [id = {8c7c333f-e632-4345-a107-cafd074b9369}] [url = about:blank]
[task 2020-05-14T20:01:05.319Z] 20:01:05     INFO - GECKO(5173) | [Child 5526: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7ff51ca93350) [pid = 5526] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2020-05-14T20:01:05.319Z] 20:01:05     INFO - GECKO(5173) | [Parent 5173, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4265
[task 2020-05-14T20:01:05.476Z] 20:01:05     INFO - GECKO(5173) | [Parent 5173: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fc29e0a5800 == 13 [pid = 5173] [id = {09bd65b4-edfd-4490-b99b-31b69d59a3f0}]
[task 2020-05-14T20:01:05.476Z] 20:01:05     INFO - GECKO(5173) | [Parent 5173: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 31 (0x7fc29e42dfb0) [pid = 5173] [serial = 33] [outer = (nil)]
[task 2020-05-14T20:01:05.573Z] 20:01:05     INFO - GECKO(5173) | [Child 5474: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f89b4b8a800 == 2 [pid = 5474] [id = {8194c798-6416-48bc-a52f-a7f0b5024f7d}]
[task 2020-05-14T20:01:05.573Z] 20:01:05     INFO - GECKO(5173) | [Child 5474: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x7f89ba092260) [pid = 5474] [serial = 10] [outer = (nil)]
[task 2020-05-14T20:01:05.573Z] 20:01:05     INFO - GECKO(5173) | [Child 5474: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0x7f89b4b8dc00) [pid = 5474] [serial = 11] [outer = 0x7f89ba092260]
[task 2020-05-14T20:01:05.669Z] 20:01:05     INFO - GECKO(5173) | [Child 5289: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7f7d43094000) [pid = 5289] [serial = 10] [outer = (nil)] [url = about:blank]
[task 2020-05-14T20:01:05.671Z] 20:01:05     INFO - GECKO(5173) | [Child 5289: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7f7d43090400) [pid = 5289] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2020-05-14T20:01:05.687Z] 20:01:05     INFO - GECKO(5173) | [Parent 5173: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 32 (0x7fc2971bfc00) [pid = 5173] [serial = 34] [outer = 0x7fc29e42dfb0]
[task 2020-05-14T20:01:05.688Z] 20:01:05     INFO - GECKO(5173) | [Child 5289: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f7d4273f400 == 1 [pid = 5289] [id = {210ad067-63d5-4ebd-91cc-5e875435f7fb}] [url = about:blank]
[task 2020-05-14T20:01:05.706Z] 20:01:05     INFO - GECKO(5173) | [Child 5448: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (0x7f5dc046f000) [pid = 5448] [serial = 18] [outer = 0x7f5dc0458430]
[task 2020-05-14T20:01:05.975Z] 20:01:05     INFO - GECKO(5173) | [Child 5474: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0x7f89b4be3000) [pid = 5474] [serial = 12] [outer = 0x7f89ba092260]
[task 2020-05-14T20:01:06.283Z] 20:01:06     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-14T20:01:06.284Z] 20:01:06     INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_UsageTelemetry.js | TAB_COUNT - Opened new window - {"1":0,"2":1,"3":1,"4":1,"5":0} deepEqual {"1":0,"2":1,"3":2,"4":1,"5":0} - JS frame :: chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js :: checkTabCountHistogram :: line 277
[task 2020-05-14T20:01:06.284Z] 20:01:06     INFO - Stack trace:
[task 2020-05-14T20:01:06.285Z] 20:01:06     INFO - chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js:checkTabCountHistogram:277
[task 2020-05-14T20:01:06.286Z] 20:01:06     INFO - chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js:test_loadedTabsHistogram:526
[task 2020-05-14T20:01:06.287Z] 20:01:06     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1064
[task 2020-05-14T20:01:06.287Z] 20:01:06     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1104
[task 2020-05-14T20:01:06.288Z] 20:01:06     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:927
[task 2020-05-14T20:01:06.288Z] 20:01:06     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-05-14T20:01:06.289Z] 20:01:06     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-14T20:01:06.289Z] 20:01:06     INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_UsageTelemetry.js | LOADED_TAB_COUNT - Opened new window - {"1":0,"2":2,"3":2,"4":0} deepEqual {"1":0,"2":2,"3":2,"4":1,"5":0} - JS frame :: chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js :: checkTabCountHistogram :: line 277
[task 2020-05-14T20:01:06.290Z] 20:01:06     INFO - Stack trace:
[task 2020-05-14T20:01:06.290Z] 20:01:06     INFO - chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js:checkTabCountHistogram:277
[task 2020-05-14T20:01:06.291Z] 20:01:06     INFO - chrome://mochitests/content/browser/browser/modules/test/browser/browser_UsageTelemetry.js:test_loadedTabsHistogram:532
[task 2020-05-14T20:01:06.291Z] 20:01:06     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1064
[task 2020-05-14T20:01:06.292Z] 20:01:06     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1104
[task 2020-05-14T20:01:06.292Z] 20:01:06     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:927
[task 2020-05-14T20:01:06.293Z] 20:01:06     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-05-14T20:01:06.310Z] 20:01:06     INFO - GECKO(5173) | [Parent 5173: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fc2971c2800 == 14 [pid = 5173] [id = {1391c3d8-32c2-4952-bb90-45fe0f7642f5}]

Fixed by backout:
https://hg.mozilla.org/integration/autoland/rev/4be8fc22b0cf000403dc40c1cd72c363f135533c

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INVALID
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.