Closed Bug 1810853 Opened 2 years ago Closed 2 years ago

Intermittent /css/selectors/media/media-loading-state.html | single tracking bug

Categories

(Core :: CSS Parsing and Computation, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=402664171&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RrJscarDTz6NeyNsUW0dDA/runs/0/artifacts/public/logs/live_backing.log


[task 2023-01-17T16:50:28.734Z] 16:50:28     INFO - TEST-START | /css/selectors/media/media-loading-state.html
[task 2023-01-17T16:50:28.787Z] 16:50:28     INFO - Setting pref dom.animations-api.compositing.enabled to true
[task 2023-01-17T16:50:28.814Z] 16:50:28     INFO - Setting pref layout.css.cascade-layers.enabled to true
[task 2023-01-17T16:50:28.850Z] 16:50:28     INFO - Setting pref gfx.font_rendering.fallback.async to false
[task 2023-01-17T16:50:28.902Z] 16:50:28     INFO - Setting pref layout.css.has-selector.enabled to true
[task 2023-01-17T16:50:28.957Z] 16:50:28     INFO - Setting pref layout.css.nth-child-of.enabled to true
[task 2023-01-17T16:50:28.990Z] 16:50:28     INFO - Setting pref dom.focus.fixup to true
[task 2023-01-17T16:50:29.334Z] 16:50:29     INFO - PID 3044 | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2023-01-17T16:50:29.388Z] 16:50:29     INFO - PID 3044 | [Child 4928, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_ERROR_INVALID_ARG) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/intl/l10n/L10nRegistry.cpp:384
[task 2023-01-17T16:50:29.429Z] 16:50:29     INFO - PID 3044 | [Child 4928, Main Thread] WARNING: Can't add a range if the end is older that the start.: file /builds/worker/checkouts/gecko/dom/html/TimeRanges.cpp:72
[task 2023-01-17T16:50:29.487Z] 16:50:29     INFO - PID 3044 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167396734783880\AppData\Local\Temp\tmpllvypbsn\runtests_leaks_6228_tab_pid8860.log
[task 2023-01-17T16:50:29.492Z] 16:50:29     INFO - PID 3044 | [8860, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2023-01-17T16:50:32.741Z] 16:50:32     INFO - PID 3044 | [Child 4928, MediaDecoderStateMachine #1] WARNING: Decoder=19c99df3700 state=DECODING_METADATA Decode metadata failed, shutting down decoder: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:372
[task 2023-01-17T16:50:32.749Z] 16:50:32     INFO - PID 3044 | [Child 4928, MediaDecoderStateMachine #1] WARNING: Decoder=19c99df3700 Decode error: NS_ERROR_DOM_MEDIA_METADATA_ERR (0x806e0006) - static MP4Metadata::ResultAndByteBuffer __cdecl mozilla::MP4Metadata::Metadata(mozilla::ByteStream *): Cannot parse metadata: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachineBase.cpp:164
[task 2023-01-17T16:50:32.751Z] 16:50:32     INFO - PID 3044 | JavaScript error: http://web-platform.test:8000/css/selectors/media/media-loading-state.html, line 46: AbortError: The fetching process for the media resource was aborted by the user agent at the user's request.
[task 2023-01-17T16:50:43.819Z] 16:50:43     INFO - PID 3044 | [Child 3764, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3384
[task 2023-01-17T16:50:43.837Z] 16:50:43     INFO - PID 3044 | [Child 3764, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-01-17T16:50:43.848Z] 16:50:43     INFO - PID 3044 | [Child 3764, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4663
[task 2023-01-17T16:50:44.158Z] 16:50:44     INFO - PID 3044 | [Child 4928, Main Thread] WARNING: IPC message 'PSessionStore::Msg_IncrementalSessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-01-17T16:50:44.163Z] 16:50:44     INFO - PID 3044 | [Child 4928, Main Thread] WARNING: IPC message 'PSessionStore::Msg_SessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-01-17T16:50:44.358Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:813
[task 2023-01-17T16:50:44.362Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:637
[task 2023-01-17T16:50:44.363Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] WARNING: QM_TRY failure (ERROR): '::mozilla::ToResultInvoke< nsCOMPtr<mozIStorageConnection>>( ::std::mem_fn( &::mozilla::detail::DerefedType<decltype(aStorageService)>::OpenDatabaseWithFileURL), (aStorageService), &aFileURL, telemetryFilename, mozIStorageService::CONNECTION_DEFAULT) failed with resultCode 0x80630001, resultName NS_ERROR_STORAGE_BUSY', file dom/indexedDB/ActorsParent.cpp:751
[task 2023-01-17T16:50:44.364Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] 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:775
[task 2023-01-17T16:50:44.365Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] 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:787
[task 2023-01-17T16:50:44.505Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:813
[task 2023-01-17T16:50:44.509Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:637
[task 2023-01-17T16:50:44.511Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] WARNING: QM_TRY failure (ERROR): '::mozilla::ToResultInvoke< nsCOMPtr<mozIStorageConnection>>( ::std::mem_fn( &::mozilla::detail::DerefedType<decltype(aStorageService)>::OpenDatabaseWithFileURL), (aStorageService), &aFileURL, telemetryFilename, mozIStorageService::CONNECTION_DEFAULT) failed with resultCode 0x80630001, resultName NS_ERROR_STORAGE_BUSY', file dom/indexedDB/ActorsParent.cpp:751
[task 2023-01-17T16:50:44.512Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] 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:812
[task 2023-01-17T16:50:44.659Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:813
[task 2023-01-17T16:50:44.663Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:637
[task 2023-01-17T16:50:44.665Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] WARNING: QM_TRY failure (ERROR): '::mozilla::ToResultInvoke< nsCOMPtr<mozIStorageConnection>>( ::std::mem_fn( &::mozilla::detail::DerefedType<decltype(aStorageService)>::OpenDatabaseWithFileURL), (aStorageService), &aFileURL, telemetryFilename, mozIStorageService::CONNECTION_DEFAULT) failed with resultCode 0x80630001, resultName NS_ERROR_STORAGE_BUSY', file dom/indexedDB/ActorsParent.cpp:751
[task 2023-01-17T16:50:44.666Z] 16:50:44     INFO - PID 3044 | [Parent 1708, QuotaManager IO] 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:812
[task 2023-01-17T16:53:29.404Z] 16:53:29     INFO - 
[task 2023-01-17T16:53:29.404Z] 16:53:29     INFO - TEST-FAIL | /css/selectors/media/media-loading-state.html | Test :pseudo-class syntax is supported without throwing a SyntaxError - assert_unreached: :buffering is not supported Reached unreachable code
[task 2023-01-17T16:53:29.404Z] 16:53:29     INFO - @http://web-platform.test:8000/css/selectors/media/media-loading-state.html:18:27
[task 2023-01-17T16:53:29.404Z] 16:53:29     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2591:25
[task 2023-01-17T16:53:29.404Z] 16:53:29     INFO - test@http://web-platform.test:8000/resources/testharness.js:628:30
[task 2023-01-17T16:53:29.408Z] 16:53:29     INFO - @http://web-platform.test:8000/css/selectors/media/media-loading-state.html:13:9
[task 2023-01-17T16:53:29.408Z] 16:53:29     INFO - TEST-UNEXPECTED-FAIL | /css/selectors/media/media-loading-state.html | Test :stalled pseudo-class - promise_test: Unhandled rejection with value: object "SyntaxError: Document.querySelector: 'video:stalled' is not a valid selector"
[task 2023-01-17T16:53:29.408Z] 16:53:29     INFO - TEST-INFO | expected TIMEOUT
[task 2023-01-17T16:53:29.409Z] 16:53:29     INFO - 
[task 2023-01-17T16:53:29.409Z] 16:53:29     INFO - TEST-UNEXPECTED-FAIL | /css/selectors/media/media-loading-state.html | Test :buffering pseudo-class - promise_test: Unhandled rejection with value: object "SyntaxError: Document.querySelector: 'video:buffering' is not a valid selector"
[task 2023-01-17T16:53:29.409Z] 16:53:29     INFO - TEST-INFO | expected NOTRUN
[task 2023-01-17T16:53:29.414Z] 16:53:29     INFO - TEST-UNEXPECTED-ERROR | /css/selectors/media/media-loading-state.html | Unhandled rejection: The fetching process for the media resource was aborted by the user agent at the user's request.
[task 2023-01-17T16:53:29.414Z] 16:53:29     INFO - @http://web-platform.test:8000/css/selectors/media/media-loading-state.html:46:80
[task 2023-01-17T16:53:29.414Z] 16:53:29     INFO - @http://web-platform.test:8000/css/selectors/media/media-loading-state.html:44:13
[task 2023-01-17T16:53:29.414Z] 16:53:29     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2591:25
[task 2023-01-17T16:53:29.415Z] 16:53:29     INFO - promise_test/tests.promise_tests</<@http://web-platform.test:8000/resources/testharness.js:733:36
[task 2023-01-17T16:53:29.415Z] 16:53:29     INFO - promise_test/tests.promise_tests<@http://web-platform.test:8000/resources/testharness.js:732:20
[task 2023-01-17T16:53:29.415Z] 16:53:29     INFO - TEST-INFO expected TIMEOUT | took 180679ms
[task 2023-01-17T16:53:29.418Z] 16:53:29     INFO - PID 3044 | 1673974409417	Marionette	INFO	Stopped listening on port 51000
[task 2023-01-17T16:53:29.462Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-01-17T16:53:29.474Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-01-17T16:53:29.481Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-01-17T16:53:29.484Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-01-17T16:53:29.490Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-01-17T16:53:29.491Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-01-17T16:53:29.502Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-01-17T16:53:29.585Z] 16:53:29     INFO - PID 3044 | [GPU 3352, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-01-17T16:53:29.600Z] 16:53:29     INFO - PID 3044 | [GPU 3352, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-01-17T16:53:29.622Z] 16:53:29     INFO - PID 3044 | [Parent 1708, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:204
[task 2023-01-17T16:53:29.635Z] 16:53:29     INFO - PID 3044 | [GPU 3352, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-01-17T16:53:29.722Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3384
[task 2023-01-17T16:53:29.729Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-01-17T16:53:29.740Z] 16:53:29     INFO - PID 3044 | [Child 8080, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4663
[task 2023-01-17T16:53:29.741Z] 16:53:29     INFO - PID 3044 | [Child 4928, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3384
[task 2023-01-17T16:53:29.745Z] 16:53:29     INFO - PID 3044 | [Child 4928, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-01-17T16:53:29.753Z] 16:53:29     INFO - PID 3044 | [Child 4928, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4663
[task 2023-01-17T16:53:29.838Z] 16:53:29     INFO - PID 3044 | [Child 6076, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3384
[task 2023-01-17T16:53:29.845Z] 16:53:29     INFO - PID 3044 | [Child 2452, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3384
[task 2023-01-17T16:53:29.846Z] 16:53:29     INFO - PID 3044 | [Child 8860, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3384
[task 2023-01-17T16:53:29.848Z] 16:53:29     INFO - PID 3044 | [Child 6076, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-01-17T16:53:29.849Z] 16:53:29     INFO - PID 3044 | [Child 2452, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-01-17T16:53:29.850Z] 16:53:29     INFO - PID 3044 | [Child 8860, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-01-17T16:53:29.855Z] 16:53:29     INFO - PID 3044 | [Child 2452, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4663
[task 2023-01-17T16:53:29.856Z] 16:53:29     INFO - PID 3044 | [Child 6076, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4663
[task 2023-01-17T16:53:29.857Z] 16:53:29     INFO - PID 3044 | [Child 8860, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4663
[task 2023-01-17T16:53:29.954Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.968Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.969Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.973Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.973Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.974Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.975Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.976Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.977Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.978Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.985Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: idb: '0.031000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.986Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: idb: '0.031000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:29.986Z] 16:53:29     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: idb: '0.031000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.014Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: quota manager shutdown step: '0.062000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.022Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: quota manager shutdown step: '0.062000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.023Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: idb: '0.062000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.024Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: cache: '0.062000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.024Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: sdb: '0.062000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.026Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: fs: '0.062000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.027Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: ls: '0.062000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.028Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: quota manager shutdown step: '0.062000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.028Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: quota manager shutdown step: '0.062000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.029Z] 16:53:30     INFO - PID 3044 | [Parent 1708, IPDL Background] WARNING: quota manager shutdown step: '0.062000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3298
[task 2023-01-17T16:53:30.089Z] 16:53:30     INFO - PID 3044 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-17T16:53:30.095Z] 16:53:30     INFO - PID 3044 |           Blocker:  Waiting for ping task
[task 2023-01-17T16:53:30.096Z] 16:53:30     INFO - PID 3044 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-01-17T16:53:30.097Z] 16:53:30     INFO - PID 3044 |           State: (none)
[task 2023-01-17T16:53:30.097Z] 16:53:30     INFO - PID 3044 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-17T16:53:30.098Z] 16:53:30     INFO - PID 3044 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-01-17T16:53:30.098Z] 16:53:30     INFO - PID 3044 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-01-17T16:53:30.099Z] 16:53:30     INFO - PID 3044 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-01-17T16:53:30.100Z] 16:53:30     INFO - PID 3044 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-17T16:53:30.101Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-17T16:53:30.102Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-17T16:53:30.102Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-17T16:53:30.103Z] 16:53:30     INFO - PID 3044 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-17T16:53:30.104Z] 16:53:30     INFO - PID 3044 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-17T16:53:30.105Z] 16:53:30     INFO - PID 3044 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-17T16:53:30.106Z] 16:53:30     INFO - PID 3044 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-17T16:53:30.107Z] 16:53:30     INFO - PID 3044 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-01-17T16:53:30.136Z] 16:53:30     INFO - PID 3044 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-17T16:53:30.140Z] 16:53:30     INFO - PID 3044 |           Blocker:  Waiting for ping task
[task 2023-01-17T16:53:30.141Z] 16:53:30     INFO - PID 3044 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-01-17T16:53:30.141Z] 16:53:30     INFO - PID 3044 |           State: (none)
[task 2023-01-17T16:53:30.142Z] 16:53:30     INFO - PID 3044 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-17T16:53:30.143Z] 16:53:30     INFO - PID 3044 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-01-17T16:53:30.143Z] 16:53:30     INFO - PID 3044 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-01-17T16:53:30.144Z] 16:53:30     INFO - PID 3044 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-01-17T16:53:30.145Z] 16:53:30     INFO - PID 3044 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-17T16:53:30.147Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-17T16:53:30.148Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-17T16:53:30.148Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-17T16:53:30.149Z] 16:53:30     INFO - PID 3044 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-17T16:53:30.150Z] 16:53:30     INFO - PID 3044 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-17T16:53:30.152Z] 16:53:30     INFO - PID 3044 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-17T16:53:30.154Z] 16:53:30     INFO - PID 3044 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-17T16:53:30.155Z] 16:53:30     INFO - PID 3044 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-01-17T16:53:30.156Z] 16:53:30     INFO - PID 3044 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-17T16:53:30.156Z] 16:53:30     INFO - PID 3044 |           Blocker:  TelemetryController: shutting down
[task 2023-01-17T16:53:30.157Z] 16:53:30     INFO - PID 3044 |           Phase: profile-before-change-telemetry
[task 2023-01-17T16:53:30.159Z] 16:53:30     INFO - PID 3044 |           State: Error getting state: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange" at addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-01-17T16:53:30.159Z] 16:53:30     INFO - PID 3044 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-01-17T16:53:30.160Z] 16:53:30     INFO - PID 3044 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-01-17T16:53:30.161Z] 16:53:30     INFO - PID 3044 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-17T16:53:30.162Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-17T16:53:30.162Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-17T16:53:30.163Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-17T16:53:30.164Z] 16:53:30     INFO - PID 3044 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-17T16:53:30.165Z] 16:53:30     INFO - PID 3044 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-17T16:53:30.166Z] 16:53:30     INFO - PID 3044 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-17T16:53:30.166Z] 16:53:30     INFO - PID 3044 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-17T16:53:30.167Z] 16:53:30     INFO - PID 3044 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-01-17T16:53:30.168Z] 16:53:30     INFO - PID 3044 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-17T16:53:30.169Z] 16:53:30     INFO - PID 3044 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-01-17T16:53:30.170Z] 16:53:30     INFO - PID 3044 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-01-17T16:53:30.171Z] 16:53:30     INFO - PID 3044 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-01-17T16:53:30.172Z] 16:53:30     INFO - PID 3044 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-17T16:53:30.172Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-17T16:53:30.173Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-17T16:53:30.174Z] 16:53:30     INFO - PID 3044 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-17T16:53:30.175Z] 16:53:30     INFO - PID 3044 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-17T16:53:30.178Z] 16:53:30     INFO - PID 3044 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-17T16:53:30.179Z] 16:53:30     INFO - PID 3044 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-17T16:53:30.180Z] 16:53:30     INFO - PID 3044 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-17T16:53:30.181Z] 16:53:30     INFO - PID 3044 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-01-17T16:53:30.182Z] 16:53:30     INFO - PID 3044 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-17T16:53:30.183Z] 16:53:30     INFO - PID 3044 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-17T16:53:30.185Z] 16:53:30     INFO - PID 3044 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-17T16:53:30.186Z] 16:53:30     INFO - PID 3044 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-17T16:53:30.187Z] 16:53:30     INFO - PID 3044 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-17T16:53:30.188Z] 16:53:30     INFO - PID 3044 | [GPU 3352, Main Thread] WARNING: IPC Connection Error: [Parent][PGPUParent] RunMessage(msgname=PGPU::Msg_ShutdownVR) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-01-17T16:53:30.188Z] 16:53:30     INFO - PID 3044 | [GPU 3352, Main Thread] WARNING: IPC message 'PGPU::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-01-17T16:53:30.190Z] 16:53:30     INFO - PID 3044 | Destroying context 000002268B7B2000 surface 0000022689C56EB0 on display 000002268B75F200
[task 2023-01-17T16:53:30.198Z] 16:53:30     INFO - PID 3044 | [Parent 1708, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:211
[task 2023-01-17T16:53:30.219Z] 16:53:30     INFO - PID 3044 | [GPU 3352, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4663
[task 2023-01-17T16:53:30.785Z] 16:53:30     INFO - PID 3044 | [Parent 1708, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3384
[task 2023-01-17T16:53:30.818Z] 16:53:30     INFO - PID 3044 | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-01-17T16:53:30.862Z] 16:53:30     INFO - PID 3044 | [Parent 1708, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4663
[task 2023-01-17T16:53:30.907Z] 16:53:30     INFO - Browser exited with return code 0
[task 2023-01-17T16:53:30.909Z] 16:53:30     INFO - PROCESS LEAKS C:\Users\task_167396734783880\AppData\Local\Temp\tmpllvypbsn\runtests_leaks_6228.log
[task 2023-01-17T16:53:30.910Z] 16:53:30     INFO - leakcheck | Processing log file C:\Users\task_167396734783880\AppData\Local\Temp\tmpllvypbsn\runtests_leaks_6228.log for scope /css/selectors/media
[task 2023-01-17T16:53:30.911Z] 16:53:30     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 3276800 bytes
[task 2023-01-17T16:53:30.911Z] 16:53:30     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 460800 bytes
[task 2023-01-17T16:53:30.912Z] 16:53:30     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2023-01-17T16:53:30.912Z] 16:53:30     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2023-01-17T16:53:30.913Z] 16:53:30     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2023-01-17T16:53:30.914Z] 16:53:30     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2023-01-17T16:53:30.915Z] 16:53:30     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2023-01-17T16:53:30.915Z] 16:53:30     INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2023-01-17T16:53:30.916Z] 16:53:30     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2023-01-17T16:53:30.916Z] 16:53:30     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2023-01-17T16:53:30.917Z] 16:53:30     INFO - leakcheck | Processing leak log file C:\Users\task_167396734783880\AppData\Local\Temp\tmpllvypbsn\runtests_leaks_6228.log
[task 2023-01-17T16:53:30.917Z] 16:53:30     INFO - TEST-PASS | leakcheck | default no leaks detected!
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.