Open
Bug 1912639
Opened 2 months ago
Updated 2 months ago
Intermittent TV toolkit/components/aboutinference/tests/browser/browser_aboutinference.js | single tracking bug
Categories
(Core :: Machine Learning, defect, P5)
Core
Machine Learning
Tracking
()
NEW
| Tracking | Status | |
|---|---|---|
| firefox-esr115 | --- | unaffected |
| firefox-esr128 | --- | unaffected |
| firefox129 | --- | unaffected |
| firefox130 | --- | affected |
| firefox131 | --- | affected |
People
(Reporter: intermittent-bug-filer, Unassigned, NeedInfo)
References
(Regression)
Details
(Keywords: intermittent-failure, regression, test-verify-fail)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=470025134&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fvblNa4ZRKutB6vQO5lQwA/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/fvblNa4ZRKutB6vQO5lQwA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2024-08-11T07:06:58.350Z] 07:06:58 INFO - TEST-PASS | toolkit/components/aboutinference/tests/browser/browser_aboutinference.js | "\n browser.ml.enable is set to False ! Toggle it to activate local inference.\n " includes "browser.ml.enable is set to False !" -
[task 2024-08-11T07:06:58.351Z] 07:06:58 INFO - Leaving test bound test_about_inference_disabled
[task 2024-08-11T07:06:58.352Z] 07:06:58 INFO - Entering test bound test_about_inference_process
[task 2024-08-11T07:06:58.353Z] 07:06:58 INFO - Buffered messages logged at 07:04:00
[task 2024-08-11T07:06:58.357Z] 07:06:58 INFO - Building the egnine process
[task 2024-08-11T07:06:58.357Z] 07:06:58 INFO - Resolving 1 mocked downloads for "test-translation-wasm-0"
[task 2024-08-11T07:06:58.358Z] 07:06:58 INFO - Buffered messages logged at 07:05:27
[task 2024-08-11T07:06:58.360Z] 07:06:58 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2024-08-11T07:06:58.361Z] 07:06:58 INFO - Buffered messages finished
[task 2024-08-11T07:06:58.362Z] 07:06:58 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/aboutinference/tests/browser/browser_aboutinference.js | Test timed out -
[task 2024-08-11T07:06:58.363Z] 07:06:58 INFO - GECKO(1101) | Completed ShutdownLeaks collections in process 1101
[task 2024-08-11T07:06:58.365Z] 07:06:58 INFO - TEST-START | Shutdown
[task 2024-08-11T07:06:58.366Z] 07:06:58 INFO - Browser Chrome Test Summary
[task 2024-08-11T07:06:58.367Z] 07:06:58 INFO - Passed: 11
[task 2024-08-11T07:06:58.368Z] 07:06:58 INFO - Failed: 1
[task 2024-08-11T07:06:58.370Z] 07:06:58 INFO - Todo: 0
[task 2024-08-11T07:06:58.371Z] 07:06:58 INFO - Mode: e10s
[task 2024-08-11T07:06:58.372Z] 07:06:58 INFO - *** End BrowserChrome Test Results ***
[task 2024-08-11T07:06:58.374Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ff245357c00 == 4 [pid = 1210] [id = 4] [url = moz-extension://fd57fbcd-33cf-42f4-85cb-12933f71ffaf/_generated_background_page.html]
[task 2024-08-11T07:06:58.377Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ff24534ec00 == 3 [pid = 1210] [id = 2] [url = moz-extension://0fb48c04-b87e-4d31-988b-92b247c58ee2/_generated_background_page.html]
[task 2024-08-11T07:06:58.378Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ff24534f800 == 2 [pid = 1210] [id = 1] [url = moz-extension://d0907082-03df-41d0-a9b3-bb8e97b60f7f/_generated_background_page.html]
[task 2024-08-11T07:06:58.380Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ff24534b800 == 1 [pid = 1210] [id = 0] [url = moz-extension://5a8c6d7d-da52-48f6-8b31-f2bc2d8428e6/_generated_background_page.html]
[task 2024-08-11T07:06:58.381Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPC I/O Parent] WARNING: [1.1]: GetUserData call for port '30467B20724E475F.3D66CA2485D49A72' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:424
[task 2024-08-11T07:06:58.384Z] 07:06:58 INFO - GECKO(1101) | 1723360017843 Marionette TRACE Received observer notification quit-application
[task 2024-08-11T07:06:58.386Z] 07:06:58 INFO - GECKO(1101) | 1723360017848 Marionette INFO Stopped listening on port 2828
[task 2024-08-11T07:06:58.389Z] 07:06:58 INFO - GECKO(1101) | 1723360017854 Marionette DEBUG Marionette stopped listening
[task 2024-08-11T07:06:58.392Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ff244387800 == 0 [pid = 1210] [id = 5] [url = moz-extension://c239fe84-6776-41d1-83bf-2fe96b90c95c/_generated_background_page.html]
[task 2024-08-11T07:06:58.393Z] 07:06:58 INFO - GECKO(1101) | [Child 1369, Main Thread] WARNING: NS_ENSURE_TRUE(winInner) failed: file /builds/worker/checkouts/gecko/widget/nsBaseDragService.cpp:1256
[task 2024-08-11T07:06:58.394Z] 07:06:58 INFO - GECKO(1101) | [Child 1369, Main Thread] WARNING: NS_ENSURE_TRUE(widget) failed: file /builds/worker/checkouts/gecko/widget/nsDragServiceProxy.cpp:150
[task 2024-08-11T07:06:58.396Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPC I/O Parent] WARNING: [1.1]: GetUserData call for port '725B9BAF9D721496.D88111CDD305BEB8' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:424
[task 2024-08-11T07:06:58.397Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f724a333800 == 5 [pid = 1101] [id = 4] [url = chrome://mochikit/content/browser-harness.xhtml]
[task 2024-08-11T07:06:58.398Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f7254f15800 == 4 [pid = 1101] [id = 2] [url = about:blank]
[task 2024-08-11T07:06:58.399Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f725c414c00 == 3 [pid = 1101] [id = 1] [url = chrome://extensions/content/dummy.xhtml]
[task 2024-08-11T07:06:58.400Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-08-11T07:06:58.403Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-08-11T07:06:58.404Z] 07:06:58 INFO - GECKO(1101) | [Child 1243: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ff2446b4c00 == 0 [pid = 1243] [id = 7] [url = about:blank]
[task 2024-08-11T07:06:58.405Z] 07:06:58 INFO - GECKO(1101) | [Child 1243: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7ff2446b8800) [pid = 1243] [serial = 24] [outer = 0] [url = about:blank]
[task 2024-08-11T07:06:58.410Z] 07:06:58 INFO - GECKO(1101) | [Child 1243: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7ff2446d4800) [pid = 1243] [serial = 23] [outer = 0] [url = about:blank]
[task 2024-08-11T07:06:58.411Z] 07:06:58 INFO - GECKO(1101) | [Child 1369: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ff24576e800 == 0 [pid = 1369] [id = 0] [url = about:newtab]
[task 2024-08-11T07:06:58.412Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (7ff244d52600) [pid = 1210] [serial = 9] [outer = 0] [url = moz-extension://fd57fbcd-33cf-42f4-85cb-12933f71ffaf/_generated_background_page.html]
[task 2024-08-11T07:06:58.413Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (7ff244d54200) [pid = 1210] [serial = 5] [outer = 0] [url = moz-extension://0fb48c04-b87e-4d31-988b-92b247c58ee2/_generated_background_page.html]
[task 2024-08-11T07:06:58.415Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (7ff244d53c00) [pid = 1210] [serial = 3] [outer = 0] [url = moz-extension://d0907082-03df-41d0-a9b3-bb8e97b60f7f/_generated_background_page.html]
[task 2024-08-11T07:06:58.416Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (7ff26388de00) [pid = 1210] [serial = 1] [outer = 0] [url = moz-extension://5a8c6d7d-da52-48f6-8b31-f2bc2d8428e6/_generated_background_page.html]
[task 2024-08-11T07:06:58.417Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (7ff243a39400) [pid = 1210] [serial = 16] [outer = 0] [url = moz-extension://c239fe84-6776-41d1-83bf-2fe96b90c95c/_generated_background_page.html]
[task 2024-08-11T07:06:58.418Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (7ff244387c00) [pid = 1210] [serial = 18] [outer = 0] [url = moz-extension://c239fe84-6776-41d1-83bf-2fe96b90c95c/_generated_background_page.html]
[task 2024-08-11T07:06:58.419Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7ff24437b800) [pid = 1210] [serial = 15] [outer = 0] [url = moz-extension://fd57fbcd-33cf-42f4-85cb-12933f71ffaf/_generated_background_page.html]
[task 2024-08-11T07:06:58.423Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7ff24437ec00) [pid = 1210] [serial = 13] [outer = 0] [url = moz-extension://0fb48c04-b87e-4d31-988b-92b247c58ee2/_generated_background_page.html]
[task 2024-08-11T07:06:58.424Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7ff245356400) [pid = 1210] [serial = 12] [outer = 0] [url = moz-extension://d0907082-03df-41d0-a9b3-bb8e97b60f7f/_generated_background_page.html]
[task 2024-08-11T07:06:58.425Z] 07:06:58 INFO - GECKO(1101) | [Child 1210: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7ff245353c00) [pid = 1210] [serial = 11] [outer = 0] [url = moz-extension://5a8c6d7d-da52-48f6-8b31-f2bc2d8428e6/_generated_background_page.html]
[task 2024-08-11T07:06:58.429Z] 07:06:58 INFO - GECKO(1101) | [Child 1369: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7ff26388f200) [pid = 1369] [serial = 1] [outer = 0] [url = about:newtab]
[task 2024-08-11T07:06:58.448Z] 07:06:58 INFO - GECKO(1101) | [Child 1369: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7ff245772000) [pid = 1369] [serial = 3] [outer = 0] [url = about:newtab]
[task 2024-08-11T07:06:58.656Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f7253181800 == 2 [pid = 1101] [id = 3] [url = resource://gre-resources/hiddenWindow.html]
[task 2024-08-11T07:06:58.660Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f725c40f000 == 1 [pid = 1101] [id = 0] [url = chrome://browser/content/browser.xhtml]
[task 2024-08-11T07:06:58.668Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: BlockShutdown: Init failed: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtectionStorage.cpp:302
[task 2024-08-11T07:06:58.700Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: IPC message 'PBrowser::Msg_Destroy' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:557
[task 2024-08-11T07:06:58.705Z] 07:06:58 INFO - GECKO(1101) | [Child 1339, Main Thread] WARNING: IPC message 'PContent::Msg_InsertNewFocusActionId' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:557
[task 2024-08-11T07:06:58.707Z] 07:06:58 INFO - GECKO(1101) | [Child 1339, Main Thread] WARNING: IPC message 'PWindowGlobal::Msg_UpdateDocumentHasLoaded' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:557
[task 2024-08-11T07:06:58.707Z] 07:06:58 INFO - GECKO(1101) | [Child 1339, Main Thread] WARNING: IPC message 'PContent::Msg_RemoveDynEntriesFromActiveSessionHistoryEntry' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:557
[task 2024-08-11T07:06:58.708Z] 07:06:58 INFO - GECKO(1101) | [Child 1339, Main Thread] WARNING: IPC message 'PContent::Msg_SynchronizeLayoutHistoryState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:557
[task 2024-08-11T07:06:58.709Z] 07:06:58 INFO - GECKO(1101) | [Child 1339, Main Thread] WARNING: IPC message 'PContent::Msg_DiscardBrowsingContext' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:557
[task 2024-08-11T07:06:58.718Z] 07:06:58 INFO - GECKO(1101) | [Child 1339: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ff245769400 == 0 [pid = 1339] [id = 0] [url = chrome://global/content/ml/MLEngine.html]
[task 2024-08-11T07:06:58.753Z] 07:06:58 INFO - GECKO(1101) | [Child 1339: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7ff244d5fa00) [pid = 1339] [serial = 1] [outer = 0] [url = chrome://global/content/ml/MLEngine.html]
[task 2024-08-11T07:06:58.755Z] 07:06:58 INFO - GECKO(1101) | [Child 1339: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7ff245768c00) [pid = 1339] [serial = 3] [outer = 0] [url = chrome://global/content/ml/MLEngine.html]
[task 2024-08-11T07:06:58.861Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.862Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.862Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.862Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.862Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.863Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.864Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.865Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.865Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.865Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.917Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: idb: '0.056000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.918Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: idb: '0.056000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.919Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: idb: '0.056000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.920Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, QuotaManager IO] WARNING: quota manager shutdown step: '0.056000s: ShutdownStorageOp::DoDirectoryWork -> ShutdownStorageInternal.', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.921Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: quota manager shutdown step: '0.056000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.922Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: quota manager shutdown step: '0.056000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.922Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: idb: '0.056000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.923Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: cache: '0.056000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.924Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: sdb: '0.056000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.924Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: fs: '0.056000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.925Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: ls: '0.056000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.926Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: quota manager shutdown step: '0.056000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.926Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: quota manager shutdown step: '0.056000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:58.927Z] 07:06:58 INFO - GECKO(1101) | [Parent 1101, IPDL Background] WARNING: quota manager shutdown step: '0.056000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2221
[task 2024-08-11T07:06:59.057Z] 07:06:59 INFO - GECKO(1101) | [Utility 1315, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:557
[task 2024-08-11T07:06:59.104Z] 07:06:59 INFO - GECKO(1101) | Destroying context 7f726dab85f0 surface 0 on display 7f726d0b1900
[task 2024-08-11T07:06:59.137Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f7249966400 == 0 [pid = 1101] [id = 11] [url = chrome://global/content/win.xhtml]
[task 2024-08-11T07:06:59.665Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2024-08-11T07:06:59.786Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (7f72565d9c00) [pid = 1101] [serial = 5] [outer = 0] [url = chrome://extensions/content/dummy.xhtml]
[task 2024-08-11T07:06:59.786Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f725c40b400) [pid = 1101] [serial = 2] [outer = 0] [url = about:blank]
[task 2024-08-11T07:06:59.790Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (7f72422e4400) [pid = 1101] [serial = 35] [outer = 0] [url = chrome://global/content/win.xhtml]
[task 2024-08-11T07:06:59.791Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (7f724a333c00) [pid = 1101] [serial = 12] [outer = 0] [url = about:blank]
[task 2024-08-11T07:06:59.793Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (7f726a24ea00) [pid = 1101] [serial = 1] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2024-08-11T07:06:59.794Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (7f7248654200) [pid = 1101] [serial = 11] [outer = 0] [url = chrome://mochikit/content/browser-harness.xhtml]
[task 2024-08-11T07:06:59.796Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (7f7257055e00) [pid = 1101] [serial = 3] [outer = 0] [url = chrome://extensions/content/dummy.xhtml]
[task 2024-08-11T07:06:59.798Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (7f7254f18800) [pid = 1101] [serial = 7] [outer = 0] [url = about:blank]
[task 2024-08-11T07:06:59.798Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f7254f2fc00) [pid = 1101] [serial = 6] [outer = 0] [url = about:blank]
[task 2024-08-11T07:06:59.799Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f724d16fa00) [pid = 1101] [serial = 8] [outer = 0] [url = resource://gre-resources/hiddenWindow.html]
[task 2024-08-11T07:06:59.800Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f72449db400) [pid = 1101] [serial = 32] [outer = 0] [url = chrome://global/content/win.xhtml]
[task 2024-08-11T07:06:59.802Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f724b637400) [pid = 1101] [serial = 10] [outer = 0] [url = resource://gre-resources/hiddenWindow.html]
[task 2024-08-11T07:06:59.822Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2024-08-11T07:06:59.822Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2024-08-11T07:06:59.822Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2024-08-11T07:06:59.823Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2024-08-11T07:06:59.824Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2024-08-11T07:06:59.824Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2024-08-11T07:06:59.825Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2024-08-11T07:06:59.825Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2024-08-11T07:06:59.826Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2024-08-11T07:06:59.826Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2024-08-11T07:06:59.827Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2024-08-11T07:06:59.827Z] 07:06:59 INFO - GECKO(1101) | [Parent 1101, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - TEST-INFO | Main app process: exit 0
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - TEST-INFO | Confirming we saw 34 DOCSHELL created and 34 destroyed log strings.
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - TEST-INFO | Confirming we saw 100 DOMWINDOW created and 100 destroyed log strings.
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - runtests.py | Application ran for: 0:03:17.656507
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - zombiecheck | Reading PID log: /tmp/tmpe0up1ypnpidlog
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - ==> process 1101 launched child process 1166
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - ==> process 1166 launched child process 1210
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - ==> process 1166 launched child process 1243
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - ==> process 1166 launched child process 1315
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - ==> process 1166 launched child process 1322
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - ==> process 1166 launched child process 1324
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - ==> process 1166 launched child process 1339
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - ==> process 1166 launched child process 1369
[task 2024-08-11T07:06:59.905Z] 07:06:59 INFO - ==> process 1166 launched child process 1427
[task 2024-08-11T07:06:59.906Z] 07:06:59 INFO - ==> process 1166 launched child process 1455
[task 2024-08-11T07:06:59.906Z] 07:06:59 INFO - ==> process 1166 launched child process 1490
[task 2024-08-11T07:06:59.908Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1315
[task 2024-08-11T07:06:59.908Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1322
[task 2024-08-11T07:06:59.908Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1324
[task 2024-08-11T07:06:59.908Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1166
[task 2024-08-11T07:06:59.908Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1455
[task 2024-08-11T07:06:59.908Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1339
[task 2024-08-11T07:06:59.908Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1490
[task 2024-08-11T07:06:59.909Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1427
[task 2024-08-11T07:06:59.909Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1369
[task 2024-08-11T07:06:59.910Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1210
[task 2024-08-11T07:06:59.910Z] 07:06:59 INFO - zombiecheck | Checking for orphan process with PID: 1243
[task 2024-08-11T07:06:59.911Z] 07:06:59 INFO - Stopping web server
[task 2024-08-11T07:06:59.917Z] 07:06:59 INFO - Server shut down.
[task 2024-08-11T07:06:59.918Z] 07:06:59 INFO - Web server killed.
[task 2024-08-11T07:06:59.918Z] 07:06:59 INFO - Stopping web socket server
[task 2024-08-11T07:06:59.920Z] 07:06:59 INFO - Stopping ssltunnel
[task 2024-08-11T07:06:59.921Z] 07:06:59 INFO - Stopping gst for v4l2loopback
[task 2024-08-11T07:06:59.922Z] 07:06:59 INFO - leakcheck | Processing log file /tmp/tmpn7hk3o8s.mozrunner/runtests_leaks.log for scope toolkit/components/aboutinference/tests/browser/browser.toml
[task 2024-08-11T07:06:59.923Z] 07:06:59 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2024-08-11T07:06:59.923Z] 07:06:59 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 0 bytes
[task 2024-08-11T07:06:59.924Z] 07:06:59 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2024-08-11T07:06:59.924Z] 07:06:59 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2024-08-11T07:06:59.925Z] 07:06:59 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2024-08-11T07:06:59.925Z] 07:06:59 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2024-08-11T07:06:59.926Z] 07:06:59 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2024-08-11T07:06:59.926Z] 07:06:59 INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2024-08-11T07:06:59.927Z] 07:06:59 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2024-08-11T07:06:59.927Z] 07:06:59 INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2024-08-11T07:06:59.928Z] 07:06:59 INFO - leakcheck | Processing leak log file /tmp/tmpn7hk3o8s.mozrunner/runtests_leaks_tab_pid1339.log
[task 2024-08-11T07:06:59.930Z] 07:06:59 INFO - TEST-PASS | leakcheck | tab no leaks detected!
|
||
Comment 1•2 months ago
|
||
:Mardak, since you are the author of the regressor, bug 1912518, could you take a look?
For more information, please visit BugBot documentation.
Flags: needinfo?(edilee)
|
||
Comment 2•2 months ago
|
||
Not actually regressed by bug 1912518 as this verify fails even without that patch. Seems like something isn't getting cleaned up to allow running the same test multiple times.
The first run of test_about_inference_process with runInference: true passes:
0:10.88 INFO Entering test bound test_about_inference_process
0:10.88 GECKO(10905) console.log: ML:EngineParent: "Mocking remote settings in MLEngineParent."
0:10.88 INFO Building the egnine process
0:10.88 GECKO(10905) 1723364170697 RemoteAgent TRACE Received observer notification domwindowopened
0:10.90 GECKO(10905) console.debug: ML:EngineParent: "Creating a new engine"
0:10.90 GECKO(10905) console.log: ML:EngineParent: "MLEngine constructor, adding engine" "default-engine"
0:10.90 GECKO(10905) console.log: ML:EngineParent: "Instances" ({})
0:10.90 INFO Resolving 1 mocked downloads for "test-translation-wasm-0"
0:10.90 GECKO(10905) console.debug: ML:EngineParent: "Using runtime ort-wasm-simd.wasm@1.0" ({id:"dd19f032-f181-45b3-9e93-658fcce227ec", name:"ort-wasm-simd.wasm", version:"1.0", last_modified:1723364170694, schema:1723364170694})
0:10.90 GECKO(10905) console.log: Download requested: test-translation-wasm-0 ort-wasm-simd.wasm
0:10.90 GECKO(10905) console.log: Handling download: test-translation-wasm-0
0:10.91 GECKO(10905) console.debug: ML:EngineChild: "Inference engine options:" ({engineId:"default-engine", taskName:"moz-echo", timeoutMS:120000, modelHubRootUrl:"https://model-hub.mozilla.org/", modelHubUrlTemplate:"{model}/{revision}", modelId:null, modelRevision:null, tokenizerId:null, tokenizerRevision:null, processorId:null, processorRevision:null, logLevel:"Debug", runtimeFilename:"ort-wasm-simd.wasm"})
0:10.96 PASS The text get echoed exercising the whole flow. - "This gets echoed." == "This gets echoed." -
But the repeat run gets existing engine and times out:
0:12.49 INFO Entering test bound test_about_inference_process
0:12.49 GECKO(10905) console.log: ML:EngineParent: "Mocking remote settings in MLEngineParent."
0:12.49 INFO Building the egnine process
0:12.49 GECKO(10905) 1723364172308 RemoteAgent TRACE Received observer notification domwindowopened
0:12.51 GECKO(10905) console.debug: ML:EngineParent: "Returning existing engine" "default-engine"
0:12.51 INFO Resolving 1 mocked downloads for "test-translation-wasm-0"
I'll try to bisect to the actual regressing bug
|
|
||
Comment 3•2 months ago
|
||
Bug 1906093 added "Returning existing engine" https://searchfox.org/mozilla-central/rev/03258de701dbcde998cfb07f75dce2b7d8fdbe20/toolkit/components/ml/actors/MLEngineParent.sys.mjs#139-143
tarek, I'm guessing something needs to clean up this engine when doing runInference so that the subsequent runs go through engine creation instead of getting existing? https://searchfox.org/mozilla-central/rev/03258de701dbcde998cfb07f75dce2b7d8fdbe20/toolkit/components/aboutinference/tests/browser/head.js#32-33
Flags: needinfo?(tziade)
Regressed by: 1906093
|
|
||
Comment 4•2 months ago
|
||
Set release status flags based on info from the regressing bug 1906093
status-firefox129:
--- → unaffected
status-firefox130:
--- → affected
status-firefox131:
--- → affected
status-firefox-esr115:
--- → unaffected
status-firefox-esr128:
--- → unaffected
| Comment hidden (Intermittent Failures Robot) |
You need to log in
before you can comment on or make changes to this bug.
Description
•