Open Bug 1776004 Opened 2 years ago Updated 11 hours ago

Intermittent dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | single tracking bug

Categories

(Core :: DOM: Service Workers, defect, P3)

Product:
Core
Component:
DOM: Service Workers
Type:
defect

Tracking

()

Status:
ASSIGNED

People

(Reporter: jmaher, Assigned: asuth)

References

Details

(Keywords: intermittent-failure, intermittent-testcase, leave-open, Whiteboard: [stockwell unknown])

Attachments

(1 file, 1 obsolete file)

Bug 1776004 - Intermittent fix: Report QM usage, allow for incremental vacuum. r=#dom-workers!
48 bytes, text/x-phabricator-request
Details | Review
No description provided.

Additional information about this bug failures and frequency patterns can be found by running: ./mach test-info failure-report --bug 1776004

Severity: -- → S3

There have been 33 total failures in the last 7 days, recent [failure log](link to recent failure log).
Affected platforms are:

  • macosx1015-64-qr
  • windows10-32-2004-qr
  • windows10-64-2004-asan-qr
  • windows10-64-2004-qr
  • windows10-64-2004-shippable-qr
[task 2022-12-03T22:54:24.482Z] 22:54:24     INFO - TEST-PASS | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | registration should be unregistered - 
[task 2022-12-03T22:54:24.483Z] 22:54:24     INFO - Buffered messages finished
[task 2022-12-03T22:54:24.484Z] 22:54:24     INFO - TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | origin usage should be mitigated - 
[task 2022-12-03T22:54:24.484Z] 22:54:24     INFO - Stack trace:
[task 2022-12-03T22:54:24.484Z] 22:54:24     INFO - chrome://mochikit/content/browser-test.js:test_ok:1449
[task 2022-12-03T22:54:24.484Z] 22:54:24     INFO - chrome://mochitests/content/browser/dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js:do_fault_injection_test:210
[task 2022-12-03T22:54:24.485Z] 22:54:24     INFO - ### testing: error: NS_ERROR_INTERCEPTION_FAILED (2152398948) consumeQuotaOrigin: https://test1.example.org
Flags: needinfo?(jmarshall)

There have been 39 total failures in the last 7 days, recent failure log.
Affected platforms are:

  • macosx1015-64-qr
  • macosx1015-64-shippable-qr
  • windows10-32-2004-qr
  • windows10-64-2004-asan-qr
  • windows10-64-2004-qr
[task 2023-02-23T22:46:23.688Z] 22:46:23     INFO - TEST-PASS | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | registration should be unregistered - 
[task 2023-02-23T22:46:23.689Z] 22:46:23     INFO - Buffered messages finished
[task 2023-02-23T22:46:23.689Z] 22:46:23     INFO - TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | origin usage should be mitigated - 
[task 2023-02-23T22:46:23.689Z] 22:46:23     INFO - Stack trace:
[task 2023-02-23T22:46:23.690Z] 22:46:23     INFO - chrome://mochikit/content/browser-test.js:test_ok:1465
[task 2023-02-23T22:46:23.690Z] 22:46:23     INFO - chrome://mochitests/content/browser/dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js:do_fault_injection_test:210
[task 2023-02-23T22:46:23.690Z] 22:46:23     INFO - ### testing: error: NS_ERROR_DOM_ABORT_ERR (2152923156) consumeQuotaOrigin: https://test2.example.org
[task 2023-02-23T22:46:23.690Z] 22:46:23     INFO - Clearing storage on domain example.org (from origin https://test1.example.org)
[task 2023-02-23T22:46:23.691Z] 22:46:23     INFO - Installing ServiceWorker sw_respondwith_serviceworker.js at network_with_utils.html on origin https://test1.example.org

There have been 43 total failures in the last 7 days.
There are:

  • 2 failures on Linux 18.04 x64 WebRender opt
  • 1 failure on Linux 18.04 x64 WebRender Shippable opt
  • 4 failures on OS X 10.15 WebRender debug
  • 4 failures on OS X 10.15 WebRender opt
  • 1 failure on OS X 10.15 WebRender Shippable opt
  • 8 failures on Windows 10 x86 2004 WebRender debug
  • 2 failures on Windows 10 x64 2004 asan WebRender opt
  • 1 failure on Windows 10 x64 2004 CCov WebRender opt
  • 7 failures on Windows 10 x64 2004 WebRender debug
  • 2 failures on Windows 10 x64 2004 asan WebRender opt
  • 1 failure on Windows 11 x86 22H2 WebRender debug
  • 1 failures on Windows 11 x64 22H2 asan WebRender opt
  • 4 failures on Windows 11 x64 22H2 CCov WebRender opt
  • 5 failures on Windows 11 x64 22H2 WebRender debug
  • 1 failure on Windows 11 x64 22H2 WebRender opt

Recent failure log.

[task 2023-03-03T23:15:11.035Z] 23:15:11     INFO - TEST-PASS | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | registration should be unregistered - 
[task 2023-03-03T23:15:11.035Z] 23:15:11     INFO - Buffered messages finished
[task 2023-03-03T23:15:11.036Z] 23:15:11     INFO - TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | origin usage should be mitigated - 
[task 2023-03-03T23:15:11.036Z] 23:15:11     INFO - Stack trace:
[task 2023-03-03T23:15:11.036Z] 23:15:11     INFO - chrome://mochikit/content/browser-test.js:test_ok:1482
[task 2023-03-03T23:15:11.036Z] 23:15:11     INFO - chrome://mochitests/content/browser/dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js:do_fault_injection_test:210
[task 2023-03-03T23:15:11.037Z] 23:15:11     INFO - ### testing: error: NS_ERROR_DOM_ABORT_ERR (2152923156) consumeQuotaOrigin: https://test2.example.org
[task 2023-03-03T23:15:11.037Z] 23:15:11     INFO - Clearing storage on domain example.org (from origin https://test1.example.org)
[task 2023-03-03T23:15:11.038Z] 23:15:11     INFO - Installing ServiceWorker sw_respondwith_serviceworker.js at network_with_utils.html on origin https://test1.example.org
[task 2023-03-03T23:15:11.038Z] 23:15:11     INFO - GECKO(3820) | [Child 960: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 290a2b3c000 == 1 [pid = 960] [id = 0]
[task 2023-03-03T23:15:11.039Z] 23:15:11     INFO - GECKO(3820) | [Child 960: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (2909dc80f90) [pid = 960] [serial = 1] [outer = 0]
[task 2023-03-03T23:15:11.039Z] 23:15:11     INFO - GECKO(3820) | [Child 960: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (290a2b40400) [pid = 960] [serial = 2] [outer = 2909dc80f90]
[task 2023-03-03T23:15:11.040Z] 23:15:11     INFO - GECKO(3820) | [Child 2148: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 16deb536000 == 0 [pid = 2148] [id = 0] [url = https://test1.example.org/browser/dom/serviceworkers/test/network_with_utils.html?err=NS_ERROR_INTERCEPTION_FAILED&fault=3]
[task 2023-03-03T23:15:11.041Z] 23:15:11     INFO - GECKO(3820) | [Child 2148: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (16de6480f90) [pid = 2148] [serial = 1] [outer = 0] [url = https://test1.example.org/browser/dom/serviceworkers/test/network_with_utils.html?err=NS_ERROR_INTERCEPTION_FAILED&fault=3]
[task 2023-03-03T23:15:11.041Z] 23:15:11     INFO - GECKO(3820) | [Child 2148: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (16deb53a400) [pid = 2148] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-03-03T23:15:11.042Z] 23:15:11     INFO - GECKO(3820) | [Child 2148: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (16deb53d000) [pid = 2148] [serial = 3] [outer = 0] [url = https://test1.example.org/browser/dom/serviceworkers/test/network_with_utils.html?err=NS_ERROR_INTERCEPTION_FAILED&fault=3]
[task 2023-03-03T23:15:11.042Z] 23:15:11     INFO - GECKO(3820) | [Child 2148, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-03T23:15:11.044Z] 23:15:11     INFO - GECKO(3820) | [Child 2148, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-03T23:15:11.044Z] 23:15:11     INFO - GECKO(3820) | [Child 2148, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-03T23:15:11.044Z] 23:15:11     INFO - GECKO(3820) | [Child 960: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (290a2b43000) [pid = 960] [serial = 3] [outer = 2909dc80f90]
[task 2023-03-03T23:15:11.172Z] 23:15:11     INFO - GECKO(3820) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167787990896748\AppData\Local\Temp\tmpr9y_hbns.mozrunner\runtests_leaks_tab_pid6008.log
[task 2023-03-03T23:15:11.175Z] 23:15:11     INFO - GECKO(3820) | [6008, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-03-03T23:15:11.187Z] 23:15:11     INFO - GECKO(3820) | [Child 6008, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.217Z] 23:15:11     INFO - GECKO(3820) | [Child 6008, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.218Z] 23:15:11     INFO - GECKO(3820) | [Child 6008, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.230Z] 23:15:11     INFO - GECKO(3820) | [Child 6008, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.304Z] 23:15:11     INFO - GECKO(3820) | [Parent 7432, DOMCacheThread] WARNING: QM_TRY failure (WARNING): '"ToResult(file->Remove( false))" failed with resultCode 0x80520012, resultName NS_ERROR_FILE_NOT_FOUND', file dom/cache/FileUtils.cpp:774
[task 2023-03-03T23:15:11.315Z] 23:15:11     INFO - GECKO(3820) | [Parent 7432, DOMCacheThread] WARNING: QM_TRY failure (WARNING): '"ToResult(file->Remove( false))" failed with resultCode 0x80520012, resultName NS_ERROR_FILE_NOT_FOUND', file dom/cache/FileUtils.cpp:774
[task 2023-03-03T23:15:11.336Z] 23:15:11     INFO - GECKO(3820) | [Child 6740, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.575Z] 23:15:11     INFO - ServiceWorker installed
[task 2023-03-03T23:15:11.585Z] 23:15:11     INFO - GECKO(3820) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167787990896748\AppData\Local\Temp\tmpr9y_hbns.mozrunner\runtests_leaks_tab_pid1292.log
[task 2023-03-03T23:15:11.586Z] 23:15:11     INFO - GECKO(3820) | [1292, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-03-03T23:15:11.587Z] 23:15:11     INFO - Consuming storage on origin https://test2.example.org
[task 2023-03-03T23:15:11.592Z] 23:15:11     INFO - GECKO(3820) | [Child 7080: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 1741ca34000 == 1 [pid = 7080] [id = 0]
[task 2023-03-03T23:15:11.593Z] 23:15:11     INFO - GECKO(3820) | [Child 7080: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (17417980f90) [pid = 7080] [serial = 1] [outer = 0]
[task 2023-03-03T23:15:11.594Z] 23:15:11     INFO - GECKO(3820) | [Child 7080: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (1741ca38400) [pid = 7080] [serial = 2] [outer = 17417980f90]
[task 2023-03-03T23:15:11.599Z] 23:15:11     INFO - GECKO(3820) | [Child 1292, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.626Z] 23:15:11     INFO - GECKO(3820) | [Child 1292, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.630Z] 23:15:11     INFO - GECKO(3820) | [Child 1292, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.631Z] 23:15:11     INFO - GECKO(3820) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2023-03-03T23:15:11.632Z] 23:15:11     INFO - GECKO(3820) | [Child 1292, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:430
[task 2023-03-03T23:15:11.642Z] 23:15:11     INFO - GECKO(3820) | [Child 960, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-03-03T23:15:11.648Z] 23:15:11     INFO - GECKO(3820) | [Child 1292, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.675Z] 23:15:11     INFO - GECKO(3820) | [Child 960: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 290a2b3c000 == 0 [pid = 960] [id = 0] [url = https://test1.example.org/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2023-03-03T23:15:11.693Z] 23:15:11     INFO - GECKO(3820) | [Child 7080: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (1741ca3b000) [pid = 7080] [serial = 3] [outer = 17417980f90]
[task 2023-03-03T23:15:11.705Z] 23:15:11     INFO - GECKO(3820) | [Child 960: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (2909dc80f90) [pid = 960] [serial = 1] [outer = 0] [url = https://test1.example.org/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2023-03-03T23:15:11.709Z] 23:15:11     INFO - GECKO(3820) | [Child 960: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (290a2b40400) [pid = 960] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-03-03T23:15:11.710Z] 23:15:11     INFO - GECKO(3820) | [Child 960: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (290a2b43000) [pid = 960] [serial = 3] [outer = 0] [url = https://test1.example.org/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2023-03-03T23:15:11.712Z] 23:15:11     INFO - GECKO(3820) | [Child 960, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-03T23:15:11.715Z] 23:15:11     INFO - GECKO(3820) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167787990896748\AppData\Local\Temp\tmpr9y_hbns.mozrunner\runtests_leaks_tab_pid8992.log
[task 2023-03-03T23:15:11.715Z] 23:15:11     INFO - GECKO(3820) | [8992, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-03-03T23:15:11.716Z] 23:15:11     INFO - GECKO(3820) | [Child 960, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-03T23:15:11.725Z] 23:15:11     INFO - GECKO(3820) | [Child 960, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-03T23:15:11.728Z] 23:15:11     INFO - GECKO(3820) | [Child 8992, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.771Z] 23:15:11     INFO - GECKO(3820) | [Child 8992, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.772Z] 23:15:11     INFO - GECKO(3820) | [Child 8992, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.783Z] 23:15:11     INFO - GECKO(3820) | [Child 8992, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-03T23:15:11.975Z] 23:15:11     INFO - GECKO(3820) | [Parent 7432, DOMCacheThread] WARNING: QM_TRY failure (WARNING): '"ToResult(file->Remove( false))" failed with resultCode 0x80520012, resultName NS_ERROR_FILE_NOT_FOUND', file dom/cache/FileUtils.cpp:774
[task 2023-03-03T23:15:11.976Z] 23:15:11     INFO - GECKO(3820) | [Parent 7432, DOMCacheThread] WARNING: QM_TRY failure (WARNING): '"ToResult(file->Remove( false))" failed with resultCode 0x80520012, resultName NS_ERROR_FILE_NOT_FOUND', file dom/cache/FileUtils.cpp:774
[task 2023-03-03T23:15:12.425Z] 23:15:12     INFO - ## Checking normal operation.
[task 2023-03-03T23:15:12.434Z] 23:15:12     INFO - Navigating to https://test1.example.org/browser/dom/serviceworkers/test/network_with_utils.html?err=NS_ERROR_DOM_ABORT_ERR&fault=0
[task 2023-03-03T23:15:12.441Z] 23:15:12     INFO - GECKO(3820) | [Child 6008: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 24e25a36000 == 1 [pid = 6008] [id = 0]
[task 2023-03-03T23:15:12.442Z] 23:15:12     INFO - GECKO(3820) | [Child 6008: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (24e20c80f90) [pid = 6008] [serial = 1] [outer = 0]
[task 2023-03-03T23:15:12.443Z] 23:15:12     INFO - GECKO(3820) | [Child 6008: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (24e25a3a400) [pid = 6008] [serial = 2] [outer = 24e20c80f90]
[task 2023-03-03T23:15:12.456Z] 23:15:12     INFO - GECKO(3820) | Waiting for browser load
[task 2023-03-03T23:15:12.460Z] 23:15:12     INFO - GECKO(3820) | Waiting for browser state change
[task 2023-03-03T23:15:12.494Z] 23:15:12     INFO - GECKO(3820) | Saw state f0001 and status 0
[task 2023-03-03T23:15:12.523Z] 23:15:12     INFO - GECKO(3820) | [Child 6008: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (24e25a3d000) [pid = 6008] [serial = 3] [outer = 24e20c80f90]
[task 2023-03-03T23:15:12.547Z] 23:15:12     INFO - GECKO(3820) | [Child 7080: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1741ca34000 == 0 [pid = 7080] [id = 0] [url = https://test2.example.org/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2023-03-03T23:15:12.559Z] 23:15:12     INFO - GECKO(3820) | [Child 7080: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (17417980f90) [pid = 7080] [serial = 1] [outer = 0] [url = https://test2.example.org/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2023-03-03T23:15:12.567Z] 23:15:12     INFO - GECKO(3820) | [Child 7080: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (1741ca38400) [pid = 7080] [serial = 2] [outer = 0] [url = about:blank]
[task 2023-03-03T23:15:12.568Z] 23:15:12     INFO - GECKO(3820) | [Child 7080: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (1741ca3b000) [pid = 7080] [serial = 3] [outer = 0] [url = https://test2.example.org/browser/dom/serviceworkers/test/empty_with_utils.html]
[task 2023-03-03T23:15:12.569Z] 23:15:12     INFO - GECKO(3820) | [Child 7080, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-03T23:15:12.569Z] 23:15:12     INFO - GECKO(3820) | [Child 7080, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-03T23:15:12.578Z] 23:15:12     INFO - GECKO(3820) | [Child 7080, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-03T23:15:12.604Z] 23:15:12     INFO - GECKO(3820) | Saw state c0010 and status 0
[task 2023-03-03T23:15:12.619Z] 23:15:12     INFO - GECKO(3820) | Browser got expected state change https://test1.example.org/browser/dom/serviceworkers/test/network_with_utils.html?err=NS_ERROR_DOM_ABORT_ERR&fault=0
[task 2023-03-03T23:15:12.751Z] 23:15:12     INFO -  Tab opened, querying body content.
[task 2023-03-03T23:15:12.769Z] 23:15:12     INFO - TEST-PASS | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | navigation without injected fault originates from ServiceWorker -

Hello Olli! As the owner of this component, can you help us assign the bug to someone?

Thank you!

Flags: needinfo?(smaug)

Adding to LWS DOM's todo list

Flags: needinfo?(smaug)
Flags: needinfo?(jmarshall)

Update

There have been 45 total failures within the last 7 days:

  • 9 failures on Windows 11 x86 22H2 WebRender debug/opt
  • 1 failure on Windows 11 x86 22H2 WebRender Shippable opt
  • 2 failures on Windows 11 x64 22H2 asan WebRender opt
  • 26 failures on Windows 11 x64 22H2 WebRender debug/opt
  • 7 failures on Windows 11 x64 22H2 WebRender Shippable opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=446790056&repo=mozilla-central&lineNumber=100858

[task 2024-02-10T11:17:40.345Z] 11:17:40     INFO - TEST-PASS | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | registration should be unregistered - 
[task 2024-02-10T11:17:40.345Z] 11:17:40     INFO - Buffered messages finished
[task 2024-02-10T11:17:40.346Z] 11:17:40     INFO - TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | origin usage should be mitigated - 
[task 2024-02-10T11:17:40.346Z] 11:17:40     INFO - Stack trace:
[task 2024-02-10T11:17:40.347Z] 11:17:40     INFO - chrome://mochikit/content/browser-test.js:test_ok:1592
[task 2024-02-10T11:17:40.347Z] 11:17:40     INFO - chrome://mochitests/content/browser/dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js:do_fault_injection_test:210
[task 2024-02-10T11:17:40.347Z] 11:17:40     INFO - chrome://mochitests/content/browser/dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js:test_navigation_fetch_fault_handling:257
[task 2024-02-10T11:17:40.347Z] 11:17:40     INFO - chrome://mochikit/content/browser-test.js:handleTask:1139
[task 2024-02-10T11:17:40.347Z] 11:17:40     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1211
[task 2024-02-10T11:17:40.347Z] 11:17:40     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1353
[task 2024-02-10T11:17:40.347Z] 11:17:40     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1128
[task 2024-02-10T11:17:40.347Z] 11:17:40     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1058
[task 2024-02-10T11:17:40.348Z] 11:17:40     INFO - ### testing: error: NS_ERROR_INTERCEPTION_FAILED (2152398948) consumeQuotaOrigin: https://test1.example.org

Hi Olli, are you still looking into this?
Thank you.

Flags: needinfo?(smaug)
Whiteboard: [stockwell unknown][stockwell unknown][stockwell unknown][stockwell unknown][stockwell needswork:owner] → [stockwell needswork:owner]
Flags: needinfo?(smaug) → needinfo?(bugmail)
Attachment #9384023 - Attachment is obsolete: true
Whiteboard: [stockwell unknown] → [stockwell needswork:owner]

Update

There have been 30 total failures within the last 7 days:

  • 6 failures on Windows 11 x64 22H2 WebRender Shippable opt
  • 17 failures on Windows 11 x64 22H2 WebRender opt/ debug
  • 2 failures on Windows 11 x64 22H2 CCov WebRender opt
  • 1 failure on Windows 11 x64 22H2 asan WebRender opt
  • 1 failure on Windows 11 x86 22H2 WebRender Shippable opt
  • 3 failures on Windows 11 x86 22H2 WebRender debug/ opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=452966908&repo=autoland&lineNumber=9174

The errors are TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/browser_navigation_fetch_fault_handling.js | origin usage should be mitigated. Absent knowing the actual usage, I have to guess somewhat, but so I have a fix I will put up which is:

  • Do log the usage reported to us by QM.
  • Increase the threshold to 32k * 3 which accounts for the limitations of incremental vacuum.
Assignee: nobody → bugmail
Status: NEW → ASSIGNED
Flags: needinfo?(bugmail)

This attempts to address intermittents where the QM usage is not as low
as we expect. The patch:

  • Reports the QM usage via info().
  • Adjusts the minimum origin usage to allow for the Cache API storage
    to be empty by way of having had its contents cleared and the
    incremental vacuum heuristics having found that there weren't enough
    free pages to force an incremental vacuum.
    • Because we are using 15 of the 16 pages when fully vacuumed and the
      incremental vacuum heuristics only kick in when we have at least 8
      free pages, it's definitely possible for an empty Cache DB to be
      3 * 32 KiB in size.

Note that this is not guaranteed to fix the problem; it's possible
there is an actual ordering problem that is causing us to try and get
the quota usage before it has been cleared, but in that case we will
still fail because we ensure that we have multiple megabytes of storage
used. And in that case, the QM usage being reported will let us move
to the next stage of investigation with some idea of what's going on.

Pushed by bugmail@asutherland.org: https://hg.mozilla.org/integration/autoland/rev/e617e2086e98 Intermittent fix: Report QM usage, allow for incremental vacuum. r=edenchuang

adding leave-open because I'm not sure this will actually fix the problem and I'd like any new intermittents to come here.

Keywords: leave-open

Various logs say:

  • m-c 1: [task 2024-06-12T12:19:07.515Z] 12:19:07 INFO - QM says usage of https://test1.example.org is 127368
  • m-c 2: [task 2024-06-12T10:35:08.785Z] 10:35:08 INFO - QM says usage of https://test1.example.org is 127368
  • Same thing for at least 5 autoland logs.

So that's 31 4k pages and a few extra bytes which is interesting. This might be a good reason to implement the granular quota usage report, potentially with even more data available to chrome callers, since it's possible there's some transient aspects to this that other mechanisms like exporting the origin directory might not be able to meaningfully capture.

You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: