1920682 - Intermittent /css/css-contain/content-visibility/content-visibility-023.html | single tracking bug

Status: RESOLVED DUPLICATE of bug 1920681

(Core :: Layout, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2024-09-24T10:26:47.414Z] 10:26:47     INFO - TEST-START | /css/css-contain/content-visibility/content-visibility-023.html
[task 2024-09-24T10:26:47.417Z] 10:26:47     INFO - PID 4828 | 1727173607416	Marionette	INFO	Testing http://web-platform.test:8000/css/css-contain/content-visibility/content-visibility-023.html == http://web-platform.test:8000/css/css-contain/content-visibility/content-visibility-023-ref.html
[task 2024-09-24T10:28:18.977Z] 10:28:18     INFO - Got timeout in harness
[task 2024-09-24T10:28:18.977Z] 10:28:18     INFO - PID 4828 | [Parent 8612, WindowsVsyncThread] WARNING: DwmComposition dynamically disabled, falling back to software timers: file /builds/worker/checkouts/gecko/gfx/thebes/gfxWindowsPlatform.cpp:1494
[task 2024-09-24T10:28:19.006Z] 10:28:19     INFO - TEST-UNEXPECTED-TIMEOUT | /css/css-contain/content-visibility/content-visibility-023.html | TestRunner hit external timeout (this may indicate a hang)
[task 2024-09-24T10:28:19.006Z] 10:28:19     INFO - TEST-INFO took 91580ms
[task 2024-09-24T10:28:20.723Z] 10:28:20     INFO - PID 4828 | [Parent 8612, Main Thread] WARNING: WebProgress Ignored: BrowsingContext is null or discarded: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3161
[task 2024-09-24T10:28:21.492Z] 10:28:21     INFO - PID 4828 | 1727173701491	Marionette	INFO	Stopped listening on port 53133
[task 2024-09-24T10:28:21.734Z] 10:28:21     INFO - PID 4828 | [Parent 8612, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-09-24T10:28:21.736Z] 10:28:21     INFO - PID 4828 | [Parent 8612, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-09-24T10:28:21.737Z] 10:28:21     INFO - PID 4828 | [Parent 8612, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-09-24T10:28:21.999Z] 10:28:21     INFO - Browser exited with return code 572
[task 2024-09-24T10:28:22.000Z] 10:28:21  WARNING - Firefox didn't exit cleanly, not processing leak logs
[task 2024-09-24T10:28:22.001Z] 10:28:22  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.ReftestTest /css/css-contain/content-visibility/content-visibility-023.html>, (<wptrunner.wpttest.ReftestResult TIMEOUT>, []))
[task 2024-09-24T10:28:22.001Z] 10:28:22     INFO - Closing logging queue
[task 2024-09-24T10:28:22.001Z] 10:28:22     INFO - queue closed
[task 2024-09-24T10:28:22.103Z] 10:28:22     INFO - Application command: D:\task_172717237461154\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_172717237461154\AppData\Local\Temp\tmpish4nijs
[task 2024-09-24T10:28:22.112Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540.log
[task 2024-09-24T10:28:22.113Z] 10:28:22     INFO - PID 6940 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-09-24T10:28:22.114Z] 10:28:22     INFO - PID 6940 | [Parent 1360, Main Thread] WARNING: '!mColorFilterWatcher', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:69
[task 2024-09-24T10:28:22.117Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540_gpu_pid8248.log
[task 2024-09-24T10:28:22.117Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540_socket_pid4080.log
[task 2024-09-24T10:28:22.118Z] 10:28:22     INFO - PID 6940 | 1727173599358	Marionette	INFO	Marionette enabled
[task 2024-09-24T10:28:22.119Z] 10:28:22     INFO - PID 6940 | 1727173599604	Marionette	INFO	Listening on port 53145
[task 2024-09-24T10:28:22.120Z] 10:28:22     INFO - PID 6940 | [Parent 1360, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1215
[task 2024-09-24T10:28:22.121Z] 10:28:22     INFO - PID 6940 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-09-24T10:28:22.122Z] 10:28:22     INFO - PID 6940 | Initializing context 279cd5cd000 surface 279cd569350 on display 279cd59f100
[task 2024-09-24T10:28:22.123Z] 10:28:22     INFO - PID 6940 | [GPU 8248, Renderer] WARNING: Failed to make GL context current!: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:479
[task 2024-09-24T10:28:22.124Z] 10:28:22     INFO - PID 6940 | EGL Error: 0x3009
[task 2024-09-24T10:28:22.124Z] 10:28:22     INFO - PID 6940 | [GPU 8248, Renderer] WARNING: GLContext::InitWithPrefix failed!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp:341
[task 2024-09-24T10:28:22.125Z] 10:28:22     INFO - PID 6940 | Destroying context 279cd5cd000 surface 279cd569350 on display 279cd59f100
[task 2024-09-24T10:28:22.126Z] 10:28:22     INFO - PID 6940 | [GPU 8248, Renderer] WARNING: Failed to create GLContext from PBuffer: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:1199
[task 2024-09-24T10:28:22.127Z] 10:28:22     INFO - PID 6940 | Initializing context 279cd5cd000 surface 279cd56ae50 on display 279cd59f100
[task 2024-09-24T10:28:22.127Z] 10:28:22     INFO - PID 6940 | GL_VENDOR: Google Inc. (NVIDIA)
[task 2024-09-24T10:28:22.128Z] 10:28:22     INFO - PID 6940 | mVendor: NVIDIA
[task 2024-09-24T10:28:22.128Z] 10:28:22     INFO - PID 6940 | GL_RENDERER: ANGLE (NVIDIA, NVIDIA Tesla M60 Direct3D11 vs_5_0 ps_5_0, D3D11-31.0.15.3815)
[task 2024-09-24T10:28:22.129Z] 10:28:22     INFO - PID 6940 | mRenderer: Unknown
[task 2024-09-24T10:28:22.130Z] 10:28:22     INFO - PID 6940 | mIsMesa: 0
[task 2024-09-24T10:28:22.130Z] 10:28:22     INFO - PID 6940 | [Parent 1360, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1888
[task 2024-09-24T10:28:22.131Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540_tab_pid6304.log
[task 2024-09-24T10:28:22.132Z] 10:28:22     INFO - PID 6940 | [GPU 8248, BackgroundThreadPool #1] WARNING: MFTEnumEx returned no IMFActivate instances: file /builds/worker/checkouts/gecko/dom/media/platforms/wmf/MFTDecoder.cpp:81
[task 2024-09-24T10:28:22.132Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2024-09-24T10:28:22.133Z] 10:28:22     INFO - PID 6940 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-09-24T10:28:22.134Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540_tab_pid1464.log
[task 2024-09-24T10:28:22.135Z] 10:28:22     INFO - PID 6940 | [Parent 1360, Main Thread] WARNING: Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out!: '!nsContentUtils::IsChromeDoc(window->GetExtantDoc())', file /builds/worker/checkouts/gecko/dom/events/EventListenerManager.cpp:461
[task 2024-09-24T10:28:22.135Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-24T10:28:22.136Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-24T10:28:22.137Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-24T10:28:22.138Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2024-09-24T10:28:22.139Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540_tab_pid6984.log
[task 2024-09-24T10:28:22.139Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-24T10:28:22.140Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-24T10:28:22.140Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-24T10:28:22.142Z] 10:28:22     INFO - PID 6940 | [Parent 1360, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1657
[task 2024-09-24T10:28:22.142Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-24T10:28:22.142Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-24T10:28:22.144Z] 10:28:22     INFO - PID 6940 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-24T10:28:22.144Z] 10:28:22     INFO - PID 6940 | [Parent 1360, IndexedDB IO #2] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:1191
[task 2024-09-24T10:28:22.145Z] 10:28:22     INFO - PID 6940 | [Parent 1360, IndexedDB IO #2] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:684
[task 2024-09-24T10:28:22.146Z] 10:28:22     INFO - PID 6940 | [Parent 1360, IndexedDB IO #2] WARNING: QM_TRY failure (ERROR): '::mozilla::ToResultInvoke< nsCOMPtr<mozIStorageConnection>>( ::std::mem_fn( &::mozilla::detail::DerefedType<decltype(aStorageService)>::OpenDatabaseWithFileURL), (aStorageService), &aFileURL, telemetryFilename, mozIStorageService::CONNECTION_INTERRUPTIBLE) failed with resultCode 0x80630001, resultName NS_ERROR_STORAGE_BUSY', file dom/indexedDB/ActorsParent.cpp:734
[task 2024-09-24T10:28:22.146Z] 10:28:22     INFO - PID 6940 | [Parent 1360, IndexedDB IO #2] WARNING: QM_TRY failure (WARNING): '"OpenDatabase(aStorageService, aFileURL, aTelemetryId) .map([](auto connection) -> ConnectionType { return Some(std::move(connection)); })" failed with resultCode 0x80630001, resultName NS_ERROR_STORAGE_BUSY', file dom/indexedDB/ActorsParent.cpp:758
[task 2024-09-24T10:28:22.148Z] 10:28:22     INFO - PID 6940 | [Parent 1360, IndexedDB IO #2] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '3870112724rsegmnoittet-es.sqlite', retrying for up to 10 seconds: file /builds/worker/checkouts/gecko/dom/indexedDB/ActorsParent.cpp:770
[task 2024-09-24T10:28:22.148Z] 10:28:22     INFO - PID 6940 | [Parent 1360, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1215
[task 2024-09-24T10:28:22.149Z] 10:28:22     INFO - PID 6940 | [Parent 1360, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2024-09-24T10:28:22.150Z] 10:28:22     INFO - PID 6940 | [Parent 1360, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2024-09-24T10:28:22.151Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540_tab_pid1168.log
[task 2024-09-24T10:28:22.151Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540_tab_pid4312.log
[task 2024-09-24T10:28:22.152Z] 10:28:22     INFO - PID 6940 | [Parent 1360, BgIOThreadPool #3] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1358
[task 2024-09-24T10:28:22.153Z] 10:28:22     INFO - PID 6940 | [Parent 1360, BgIOThreadPool #3] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1358
[task 2024-09-24T10:28:22.153Z] 10:28:22     INFO - PID 6940 | [WARN  glean_core::error_recording] gfx.adapter.primary.driver_files: Value length 811 exceeds maximum of 255
[task 2024-09-24T10:28:22.154Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540_utility_pid9768.log
[task 2024-09-24T10:28:22.155Z] 10:28:22     INFO - PID 6940 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_172717237461154\AppData\Local\Temp\tmplethjkzj\runtests_leaks_7540_tab_pid8644.log
[task 2024-09-24T10:28:22.155Z] 10:28:22     INFO - PID 6940 | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T10:28:22.155Z] 10:28:22     INFO - PID 6940 | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Amo: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T10:28:22.156Z] 10:28:22     INFO - PID 6940 | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T10:28:22.157Z] 10:28:22     INFO - PID 6940 | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Amp: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T10:28:22.158Z] 10:28:22     INFO - PID 6940 | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T10:28:22.159Z] 10:28:22     INFO - PID 6940 | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Wikipedia: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T10:28:22.160Z] 10:28:22     INFO - PID 6940 | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T10:28:22.161Z] 10:28:22     INFO - PID 6940 | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Mdn: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T10:28:22.162Z] 10:28:22     INFO - PID 6940 | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-09-24T10:28:22.162Z] 10:28:22     INFO - PID 6940 | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Yelp: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-09-24T10:28:22.162Z] 10:28:22     INFO - Starting runner
[task 2024-09-24T10:28:23.575Z] 10:28:23     INFO - TEST-START | /css/css-contain/content-visibility/content-visibility-024.html