Closed Bug 2066286 Opened 13 days ago Closed 13 days ago

Perma TV browser/components/aiwindow/models/tests/browser/browser_monitor_errors.js | test_timed_out_run_records_timeout_code - Uncaught exception in test - [MockEngineManager]

Categories

(Core :: Machine Learning: General, defect, P5)

Product:
Core
Component:
Machine Learning: General
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
156 Branch
Tracking Flags:
Tracking Status
firefox-esr140 --- unaffected
firefox-esr153 --- unaffected
firefox154 --- unaffected
firefox155 --- unaffected
firefox156 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

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

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

[task 2026-08-24T21:20:32.485+00:00] 21:20:32     INFO - TEST-INFO | screencapture: exit 0
[task 2026-08-24T21:20:32.486+00:00] 21:20:32     INFO - Buffered messages logged at 21:20:23
[task 2026-08-24T21:20:32.486+00:00] 21:20:32     INFO - Entering test test_timed_out_run_records_timeout_code
[task 2026-08-24T21:20:32.486+00:00] 21:20:32     INFO - Buffered messages finished
[task 2026-08-24T21:20:32.486+00:00] 21:20:32     INFO - TEST-UNEXPECTED-FAIL | browser/components/aiwindow/models/tests/browser/browser_monitor_errors.js | test_timed_out_run_records_timeout_code - Uncaught exception in test - [MockEngineManager] Failed to find a request for the engine with purpose "monitor" - timed out after 50 tries.
[task 2026-08-24T21:20:32.486+00:00] 21:20:32     INFO - Leaving test test_timed_out_run_records_timeout_code
[task 2026-08-24T21:20:32.486+00:00] 21:20:32     INFO - Entering test test_canceled_run_records_canceled_code
[task 2026-08-24T21:20:32.487+00:00] 21:20:32     INFO - GECKO(1995) | [Parent 1995: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (15d093600) [pid = 1995] [serial = 12] [outer = 0] [url = about:blank]
[task 2026-08-24T21:20:32.487+00:00] 21:20:32     INFO - GECKO(1995) | console.warn: "User model \"\" not found for major version 11 for feature 'chat', using generic"
[task 2026-08-24T21:20:32.487+00:00] 21:20:32     INFO - GECKO(1995) | [Parent 1995: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 15de0e800 == 6 [pid = 1995] [id = 6]
[task 2026-08-24T21:20:32.487+00:00] 21:20:32     INFO - GECKO(1995) | [Parent 1995: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (15e6d1000) [pid = 1995] [serial = 13] [outer = 0]
[task 2026-08-24T21:20:32.487+00:00] 21:20:32     INFO - GECKO(1995) | [Parent 1995: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (15d093b00) [pid = 1995] [serial = 14] [outer = 15e6d1000]
[task 2026-08-24T21:20:32.497+00:00] 21:20:32     INFO - GECKO(1995) | [Child 2041: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 10d384c00 == 1 [pid = 2041] [id = 2]
[task 2026-08-24T21:20:32.498+00:00] 21:20:32     INFO - GECKO(1995) | [Child 2041: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (103343a00) [pid = 2041] [serial = 7] [outer = 0]
[task 2026-08-24T21:20:32.498+00:00] 21:20:32     INFO - GECKO(1995) | [Child 2041: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (102633f00) [pid = 2041] [serial = 8] [outer = 103343a00]
[task 2026-08-24T21:20:32.933+00:00] 21:20:32     INFO - GECKO(1995) | [Child 2050: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 103b10c00 == 1 [pid = 2050] [id = 0]
[task 2026-08-24T21:20:32.933+00:00] 21:20:32     INFO - GECKO(1995) | [Child 2050: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (10311fd00) [pid = 2050] [serial = 1] [outer = 0]
[task 2026-08-24T21:20:32.933+00:00] 21:20:32     INFO - GECKO(1995) | [Child 2050: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (103135300) [pid = 2050] [serial = 2] [outer = 10311fd00]
[task 2026-08-24T21:20:33.004+00:00] 21:20:33     INFO - GECKO(1995) | [Parent 1995, sqldb:places.sqlite #3] WARNING: Suboptimal indexes for the SQL statement `WITH lambda (lambda) AS (   SELECT ln(2) / :halfLifeDays ), interactions AS (   SELECT     place_id,     created_at * 1000 AS visit_date   FROM     moz_places_metadata   WHERE     place_id = :pageId       AND (total_view_time >= :viewTimeSeconds * 1000         OR (total_view_time >= :viewTimeIfManyKeypressesSeconds * 1000           AND key_presses >= :manyKeypresses))   ORDER BY created_at DESC   LIMIT :numSampledVisits ), sampled_visits AS (   SELECT     vs.id,     vs.from_visit,     vs.place_id,     vs.visit_date,     vs.visit_type,     vs.source,     (       SELECT EXISTS (         SELECT 1         FROM interactions i         WHERE vs.visit_date BETWEEN
[task 2026-08-24T21:20:33.124+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2050: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (103136c00) [pid = 2050] [serial = 3] [outer = 10311fd00]
[task 2026-08-24T21:20:33.484+00:00] 21:20:33     INFO - GECKO(1995) | [Parent 1995: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 3768b6800 == 7 [pid = 1995] [id = 7]
[task 2026-08-24T21:20:33.485+00:00] 21:20:33     INFO - GECKO(1995) | [Parent 1995: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (13ad81c00) [pid = 1995] [serial = 15] [outer = 0]
[task 2026-08-24T21:20:33.490+00:00] 21:20:33     INFO - GECKO(1995) | [Parent 1995: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (364f89600) [pid = 1995] [serial = 16] [outer = 13ad81c00]
[task 2026-08-24T21:20:33.602+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2050, ProfilerChild] WARNING: Rejecting reply 'PProfiler::Reply_AwaitNextChunkManagerUpdate': resolver dropped without being called: file checkouts/gecko/ipc/glue/ProtocolUtils.cpp:861
[task 2026-08-24T21:20:33.602+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2050, ProfilerChild] WARNING: Not resolving response 'PProfiler::Reply_AwaitNextChunkManagerUpdate': actor is dead: file checkouts/gecko/ipc/glue/ProtocolUtils.cpp:833
[task 2026-08-24T21:20:33.633+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2050: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 103b10c00 == 0 [pid = 2050] [id = 0] [url = http://localhost:49835/test-page.html]
[task 2026-08-24T21:20:33.645+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2050: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (10311fd00) [pid = 2050] [serial = 1] [outer = 0] [url = http://localhost:49835/test-page.html]
[task 2026-08-24T21:20:33.647+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2050: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (103135300) [pid = 2050] [serial = 2] [outer = 0] [url = about:blank]
[task 2026-08-24T21:20:33.647+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2050: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (103136c00) [pid = 2050] [serial = 3] [outer = 0] [url = http://localhost:49835/test-page.html]
[task 2026-08-24T21:20:33.785+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2041: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 10d387800 == 2 [pid = 2041] [id = 3]
[task 2026-08-24T21:20:33.785+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2041: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (10d3c9000) [pid = 2041] [serial = 9] [outer = 0]
[task 2026-08-24T21:20:33.786+00:00] 21:20:33     INFO - GECKO(1995) | [Child 2041: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (102635d00) [pid = 2041] [serial = 10] [outer = 10d3c9000]
[task 2026-08-24T21:20:34.104+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 10b189000 == 1 [pid = 2062] [id = 0]
[task 2026-08-24T21:20:34.104+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (1004d8400) [pid = 2062] [serial = 1] [outer = 0]
[task 2026-08-24T21:20:34.104+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (100431700) [pid = 2062] [serial = 2] [outer = 1004d8400]
[task 2026-08-24T21:20:34.175+00:00] 21:20:34     INFO - GECKO(1995) | [Parent 1995, sqldb:places.sqlite #3] WARNING: Suboptimal indexes for the SQL statement `WITH lambda (lambda) AS (   SELECT ln(2) / :halfLifeDays ), interactions AS (   SELECT     place_id,     created_at * 1000 AS visit_date   FROM     moz_places_metadata   WHERE     place_id = :pageId       AND (total_view_time >= :viewTimeSeconds * 1000         OR (total_view_time >= :viewTimeIfManyKeypressesSeconds * 1000           AND key_presses >= :manyKeypresses))   ORDER BY created_at DESC   LIMIT :numSampledVisits ), sampled_visits AS (   SELECT     vs.id,     vs.from_visit,     vs.place_id,     vs.visit_date,     vs.visit_type,     vs.source,     (       SELECT EXISTS (         SELECT 1         FROM interactions i         WHERE vs.visit_date BETWEEN
[task 2026-08-24T21:20:34.292+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (100437100) [pid = 2062] [serial = 3] [outer = 1004d8400]
[task 2026-08-24T21:20:34.704+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062, ProfilerChild] WARNING: Rejecting reply 'PProfiler::Reply_AwaitNextChunkManagerUpdate': resolver dropped without being called: file checkouts/gecko/ipc/glue/ProtocolUtils.cpp:861
[task 2026-08-24T21:20:34.704+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062, ProfilerChild] WARNING: Not resolving response 'PProfiler::Reply_AwaitNextChunkManagerUpdate': actor is dead: file checkouts/gecko/ipc/glue/ProtocolUtils.cpp:833
[task 2026-08-24T21:20:34.732+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 10b189000 == 0 [pid = 2062] [id = 0] [url = http://localhost:49835/test-page.html]
[task 2026-08-24T21:20:34.743+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (1004d8400) [pid = 2062] [serial = 1] [outer = 0] [url = http://localhost:49835/test-page.html]
[task 2026-08-24T21:20:34.745+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (100431700) [pid = 2062] [serial = 2] [outer = 0] [url = about:blank]
[task 2026-08-24T21:20:34.745+00:00] 21:20:34     INFO - GECKO(1995) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (100437100) [pid = 2062] [serial = 3] [outer = 0] [url = http://localhost:49835/test-page.html]
[task 2026-08-24T21:20:34.789+00:00] 21:20:34     INFO - TEST-PASS | browser/components/aiwindow/models/tests/browser/browser_monitor_errors.js | test_canceled_run_records_canceled_code - The canceled run is an error - "error" == "error"
[task 2026-08-24T21:20:34.789+00:00] 21:20:34     INFO - GECKO(1995) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpq54cg13z.mozrunner/runtests_leaks_tab_pid2077.log

:again, since you are the author of the regressor, bug 2062352, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(again)

Solved by backout. https://hg.mozilla.org/integration/autoland/rev/33fcaa8c1438f97281deb69758e1787d95144501

Status: NEW → RESOLVED
Closed: 13 days ago
Flags: needinfo?(again)
Resolution: --- → FIXED
You need to log in before you can comment on or make changes to this bug.