Open Bug 1870751 Opened 1 year ago Updated 6 months ago

Intermittent TV browser/components/protections/test/browser/browser_protections_telemetry.js | Uncaught exception in test bound checkTelemetryLoadEventForEntrypoint - recorded telemetry for showing the report contains default 'direct' entrypoint - timed o

Categories

(Firefox :: Protections UI, defect, P5)

Product:
Firefox
Component:
Protections UI
Type:
defect

Tracking

()

Tracking Flags:
Tracking Status
firefox-esr115 --- unaffected
firefox121 --- unaffected
firefox122 --- unaffected
firefox123 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, regression, test-verify-fail)

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=440582913&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/GK4AzfVsSGOB18Ifkj2DBA/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/GK4AzfVsSGOB18Ifkj2DBA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2023-12-18T21:52:08.540Z] 21:52:08     INFO - TEST-PASS | browser/components/protections/test/browser/browser_protections_telemetry.js | undefined assertion name - 
[task 2023-12-18T21:52:08.540Z] 21:52:08     INFO - Leaving test bound test_save_telemetry
[task 2023-12-18T21:52:08.541Z] 21:52:08     INFO - Entering test bound checkTelemetryLoadEventForEntrypoint
[task 2023-12-18T21:52:08.541Z] 21:52:08     INFO - Buffered messages logged at 21:51:58
[task 2023-12-18T21:52:08.541Z] 21:52:08     INFO - Typo in 'entrypoint' should not be recorded
[task 2023-12-18T21:52:08.541Z] 21:52:08     INFO - Buffered messages finished
[task 2023-12-18T21:52:08.542Z] 21:52:08     INFO - TEST-UNEXPECTED-FAIL | browser/components/protections/test/browser/browser_protections_telemetry.js | Uncaught exception in test bound checkTelemetryLoadEventForEntrypoint - recorded telemetry for showing the report contains default 'direct' entrypoint - timed out after 50 tries.
[task 2023-12-18T21:52:08.543Z] 21:52:08     INFO - Leaving test bound checkTelemetryLoadEventForEntrypoint
[task 2023-12-18T21:52:08.543Z] 21:52:08     INFO - TEST-SKIP | browser/components/protections/test/browser/browser_protections_telemetry.js | bound checkTelemetryClickEventsVPN 
[task 2023-12-18T21:52:08.544Z] 21:52:08  WARNING - Skipping test bound checkTelemetryClickEventsVPN
[task 2023-12-18T21:52:08.545Z] 21:52:08     INFO - TEST-SKIP | browser/components/protections/test/browser/browser_protections_telemetry.js | bound checkTelemetryEventsVPNBanner 
[task 2023-12-18T21:52:08.545Z] 21:52:08  WARNING - Skipping test bound checkTelemetryEventsVPNBanner
[task 2023-12-18T21:52:08.545Z] 21:52:08     INFO - GECKO(2315) | MEMORY STAT | vsize 8019MB | residentFast 367MB | heapAllocated 133MB
[task 2023-12-18T21:52:08.546Z] 21:52:08     INFO - TEST-OK | browser/components/protections/test/browser/browser_protections_telemetry.js | took 23505ms
[task 2023-12-18T21:52:08.546Z] 21:52:08     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-12-18T21:52:08.547Z] 21:52:08     INFO - TEST-UNEXPECTED-FAIL | browser/components/protections/test/browser/browser_protections_telemetry.js | Found an unexpected tab at the end of test run: about:protections?entryPoint=newPage - 
[task 2023-12-18T21:52:08.547Z] 21:52:08     INFO - GECKO(2315) | [Child 2321: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 10ff8b800 == 1 [pid = 2321] [id = 1]
[task 2023-12-18T21:52:08.548Z] 21:52:08     INFO - GECKO(2315) | [Child 2321: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (10b3443e0) [pid = 2321] [serial = 3] [outer = 0]
[task 2023-12-18T21:52:08.548Z] 21:52:08     INFO - GECKO(2315) | [Child 2321: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (10ff8bc00) [pid = 2321] [serial = 4] [outer = 10b3443e0]
[task 2023-12-18T21:52:08.549Z] 21:52:08     INFO - checking window state
[task 2023-12-18T21:52:08.549Z] 21:52:08     INFO - GECKO(2315) | [Parent 2315: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (136563000) [pid = 2315] [serial = 20] [outer = 139dfe4c0]
[task 2023-12-18T21:52:08.550Z] 21:52:08     INFO - GECKO(2315) | [WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2023-12-18T21:52:08.563Z] 21:52:08     INFO - GECKO(2315) | [Child 2318: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 10e289000 == 0 [pid = 2318] [id = 1] [url = about:blank]
[task 2023-12-18T21:52:08.637Z] 21:52:08     INFO - GECKO(2315) | [Child 2323: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 11dd21800 == 1 [pid = 2323] [id = 13] [url = about:protections?entryPoint=newPage]
[task 2023-12-18T21:52:08.638Z] 21:52:08     INFO - GECKO(2315) | [Child 2323: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 11c388c00 == 0 [pid = 2323] [id = 0] [url = about:newtab]
[task 2023-12-18T21:52:08.641Z] 21:52:08     INFO - GECKO(2315) | [Child 2323, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/base/GlobalTeardownObserver.cpp:51
[task 2023-12-18T21:52:09.231Z] 21:52:09     INFO - GECKO(2315) | [Child 2322, Main Thread] WARNING: IPC message 'PContent::Msg_AccumulateChildKeyedHistograms' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2023-12-18T21:52:09.233Z] 21:52:09     INFO - GECKO(2315) | [Child 2322, Main Thread] WARNING: '!ipcActor->SendAccumulateChildKeyedHistograms(keyedHistogramsToSend)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:282
[task 2023-12-18T21:52:09.235Z] 21:52:09     INFO - GECKO(2315) | [Child 2322, Main Thread] WARNING: IPC message 'PContent::Msg_RecordDiscardedData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2023-12-18T21:52:09.235Z] 21:52:09     INFO - GECKO(2315) | [Child 2322, Main Thread] WARNING: '!ipcActor->SendRecordDiscardedData(discardedData)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:297
[task 2023-12-18T21:52:09.271Z] 21:52:09     INFO - GECKO(2315) | [Child 2318: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (10e289400) [pid = 2318] [serial = 6] [outer = 0] [url = about:blank]
[task 2023-12-18T21:52:09.271Z] 21:52:09     INFO - GECKO(2315) | [Child 2318: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (10c2443e0) [pid = 2318] [serial = 5] [outer = 0] [url = about:blank]
[task 2023-12-18T21:52:09.390Z] 21:52:09     INFO - GECKO(2315) | [Child 2323: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (11e5c7000) [pid = 2323] [serial = 53] [outer = 0] [url = about:protections?entryPoint=newPage]
[task 2023-12-18T21:52:09.391Z] 21:52:09     INFO - GECKO(2315) | [Child 2323: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (1139d6020) [pid = 2323] [serial = 51] [outer = 0] [url = about:protections?entryPoint=newPage]
[task 2023-12-18T21:52:09.391Z] 21:52:09     INFO - GECKO(2315) | [Child 2323: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (1115443e0) [pid = 2323] [serial = 1] [outer = 0] [url = about:newtab]
[task 2023-12-18T21:52:09.394Z] 21:52:09     INFO - GECKO(2315) | [Child 2323: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (11c38f400) [pid = 2323] [serial = 3] [outer = 0] [url = about:newtab]
[task 2023-12-18T21:52:10.205Z] 21:52:10     INFO - GECKO(2315) | [Parent 2315: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (13a090400) [pid = 2315] [serial = 9] [outer = 0] [url = about:blank]
[task 2023-12-18T21:52:10.371Z] 21:52:10     INFO - GECKO(2315) | Completed ShutdownLeaks collections in process 2321
[task 2023-12-18T21:52:10.458Z] 21:52:10     INFO - GECKO(2315) | Completed ShutdownLeaks collections in process 2318
[task 2023-12-18T21:52:10.572Z] 21:52:10     INFO - GECKO(2315) | Completed ShutdownLeaks collections in process 2317
[task 2023-12-18T21:52:10.603Z] 21:52:10     INFO - GECKO(2315) | Completed ShutdownLeaks collections in process 2323
[task 2023-12-18T21:52:10.603Z] 21:52:10     INFO - GECKO(2315) | [Parent 2315: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (117610400) [pid = 2315] [serial = 21] [outer = 139dfe4c0]
[task 2023-12-18T21:52:12.789Z] 21:52:12     INFO - GECKO(2315) | [Parent 2315: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (136563000) [pid = 2315] [serial = 20] [outer = 0] [url = about:blank]
[task 2023-12-18T21:52:13.183Z] 21:52:13     INFO - GECKO(2315) | Completed ShutdownLeaks collections in process 2315
[task 2023-12-18T21:52:13.184Z] 21:52:13     INFO - TEST-START | Shutdown
[task 2023-12-18T21:52:13.184Z] 21:52:13     INFO - Browser Chrome Test Summary
[task 2023-12-18T21:52:13.184Z] 21:52:13     INFO - Passed:  52
[task 2023-12-18T21:52:13.185Z] 21:52:13     INFO - Failed:  2
[task 2023-12-18T21:52:13.185Z] 21:52:13     INFO - Todo:    0
[task 2023-12-18T21:52:13.186Z] 21:52:13     INFO - Mode:    e10s
[task 2023-12-18T21:52:13.186Z] 21:52:13     INFO - *** End BrowserChrome Test Results ***
See Also: → 1870752

:halemu, since you are the author of the regressor, bug 1864079, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(halemu)

Thanks for filing this. We'll look into it.

Flags: needinfo?(halemu)
You need to log in before you can comment on or make changes to this bug.