1832358 - Add `app.normandy.first_run` to ignorePrefs.json, since it gets toggled in the background while tests are running

Status: RESOLVED FIXED

(Testing :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-05-10T16:12:14.737Z] 16:12:14     INFO - TEST-OK | dom/security/test/csp/test_ignore_xfo.html | took 1520ms
[task 2023-05-10T16:12:14.827Z] 16:12:14    ERROR - TEST-UNEXPECTED-FAIL | /tests/dom/security/test/csp/test_ignore_xfo.html | changed preference: app.normandy.first_run
[task 2023-05-10T16:12:14.835Z] 16:12:14     INFO - TEST-START | Shutdown
[task 2023-05-10T16:12:14.835Z] 16:12:14     INFO - Passed:  6
[task 2023-05-10T16:12:14.836Z] 16:12:14     INFO - Failed:  0
[task 2023-05-10T16:12:14.837Z] 16:12:14     INFO - Todo:    0
[task 2023-05-10T16:12:14.837Z] 16:12:14     INFO - Mode:    e10s
[task 2023-05-10T16:12:14.838Z] 16:12:14     INFO - Slowest: 1522ms - /tests/dom/security/test/csp/test_ignore_xfo.html
[task 2023-05-10T16:12:14.838Z] 16:12:14     INFO - SimpleTest FINISHED
[task 2023-05-10T16:12:14.839Z] 16:12:14     INFO - TEST-INFO | Ran 1 Loops
[task 2023-05-10T16:12:14.840Z] 16:12:14     INFO - SimpleTest FINISHED
[task 2023-05-10T16:12:14.874Z] 16:12:14     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-10T16:12:14.882Z] 16:12:14     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-10T16:12:14.886Z] 16:12:14     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-10T16:12:14.889Z] 16:12:14     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-10T16:12:14.900Z] 16:12:14     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-10T16:12:14.905Z] 16:12:14     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-10T16:12:14.915Z] 16:12:14     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-10T16:12:14.924Z] 16:12:14     INFO - GECKO(9008) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 575: NotFoundError: No such JSWindowActor 'SpecialPowers'
[task 2023-05-10T16:12:15.250Z] 16:12:15     INFO - GECKO(9008) | 1683735135259	Marionette	TRACE	Received observer notification quit-application
[task 2023-05-10T16:12:15.261Z] 16:12:15     INFO - GECKO(9008) | 1683735135259	Marionette	INFO	Stopped listening on port 2828
[task 2023-05-10T16:12:15.261Z] 16:12:15     INFO - GECKO(9008) | 1683735135260	Marionette	DEBUG	Marionette stopped listening
[task 2023-05-10T16:12:15.297Z] 16:12:15     INFO - GECKO(9008) | [GPU 3496, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-05-10T16:12:15.328Z] 16:12:15     INFO - GECKO(9008) | [Parent 1996, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-10T16:12:15.331Z] 16:12:15     INFO - GECKO(9008) | [Parent 1996, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-05-10T16:12:15.392Z] 16:12:15     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-10T16:12:15.404Z] 16:12:15     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-10T16:12:15.413Z] 16:12:15     INFO - GECKO(9008) | [Child 4716, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-10T16:12:15.425Z] 16:12:15     INFO - GECKO(9008) | [Child 9484, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-10T16:12:15.442Z] 16:12:15     INFO - GECKO(9008) | [Child 9484, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-10T16:12:15.451Z] 16:12:15     INFO - GECKO(9008) | [Child 9484, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-10T16:12:15.463Z] 16:12:15     INFO - GECKO(9008) | [Child 7008, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-10T16:12:15.470Z] 16:12:15     INFO - GECKO(9008) | [Child 7008, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-10T16:12:15.479Z] 16:12:15     INFO - GECKO(9008) | [Child 7008, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-10T16:12:15.939Z] 16:12:15     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:15.941Z] 16:12:15     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:15.942Z] 16:12:15     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:15.943Z] 16:12:15     INFO - GECKO(9008) | [Parent 1996, 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:742
[task 2023-05-10T16:12:15.944Z] 16:12:15     INFO - GECKO(9008) | [Parent 1996, 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:754
[task 2023-05-10T16:12:16.081Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:16.083Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:16.084Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:16.085Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:16.222Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:16.224Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:16.225Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:16.225Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:16.363Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:16.365Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:16.366Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:16.367Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:16.505Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:16.507Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:16.507Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:16.508Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:16.646Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:16.649Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:16.650Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:16.651Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:16.788Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:16.789Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:16.790Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:16.792Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:16.929Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:16.931Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:16.932Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:16.932Z] 16:12:16     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:17.070Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:17.073Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:17.074Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:17.074Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:17.212Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:17.214Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:17.215Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:17.216Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:17.353Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:17.355Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:17.356Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:17.357Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:17.495Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:17.497Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:17.498Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:17.499Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:17.637Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:975
[task 2023-05-10T16:12:17.637Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:665
[task 2023-05-10T16:12:17.639Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:718
[task 2023-05-10T16:12:17.640Z] 16:12:17     INFO - GECKO(9008) | [Parent 1996, 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:779
[task 2023-05-10T16:12:19.832Z] 16:12:19     INFO - GECKO(9008) | [Parent 1996, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2023-05-10T16:12:20.491Z] 16:12:20     INFO - GECKO(9008) | console.error: "DEPRECATION WARNING: Calling FileUtils.getDir(..., ..., true) causes main thread I/O and should be avoided especially during startup/shutdown\nYou may find more details about this deprecation at: https://bugzilla.mozilla.org/show_bug.cgi?id=921157\nresource://gre/modules/FileUtils.sys.mjs 62 FileUtils_getDir\nresource://gre/modules/UpdateService.sys.mjs 1056 getUpdateDirCreate\nresource://gre/modules/UpdateService.sys.mjs 1095 getUpdateFile\nresource://gre/modules/UpdateService.sys.mjs 4449 UM__loadXMLFileIntoArray\nresource://gre/modules/UpdateService.sys.mjs 4282 UpdateManager\nresource://gre/modules/XPCOMUtils.sys.mjs 137 defineLazyServiceGetter/<\nresource://gre/modules/UpdateService.sys.mjs 3381 get _pingSuffix\nresource://gre/modules/UpdateService.sys.mjs 3414 AUS__checkForBackgroundUpdates\nresource://gre/modules/UpdateService.sys.mjs 3369 AUS_notify\nresource://gre/modules/UpdateTimerManager.sys.mjs 205 TM_notify/</<\n"
[task 2023-05-10T16:12:20.757Z] 16:12:20     INFO - GECKO(9008) | [Child 3112, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-10T16:12:20.770Z] 16:12:20     INFO - GECKO(9008) | [Child 3040, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-10T16:12:20.771Z] 16:12:20     INFO - GECKO(9008) | [Child 3112, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-10T16:12:20.772Z] 16:12:20     INFO - GECKO(9008) | [Child 3040, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-10T16:12:20.773Z] 16:12:20     INFO - GECKO(9008) | [Child 10156, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-10T16:12:20.774Z] 16:12:20     INFO - GECKO(9008) | [Child 10156, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-10T16:12:20.777Z] 16:12:20     INFO - GECKO(9008) | [Child 3112, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-10T16:12:20.777Z] 16:12:20     INFO - GECKO(9008) | [Child 3040, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-10T16:12:20.779Z] 16:12:20     INFO - GECKO(9008) | [Child 10156, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-10T16:12:22.231Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.233Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.233Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.234Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.235Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.235Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.236Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.236Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.237Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: idb: '0.015000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.238Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: idb: '0.015000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.239Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: idb: '0.015000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.239Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: idb: '0.015000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.240Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: idb: '0.015000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.240Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: idb: '0.015000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.241Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: idb: '0.015000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.498Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: quota manager shutdown step: '0.281000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.507Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: quota manager shutdown step: '0.281000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.507Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: idb: '0.281000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.508Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: cache: '0.281000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.508Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: sdb: '0.281000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.509Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: fs: '0.281000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.510Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: ls: '0.281000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.510Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: quota manager shutdown step: '0.281000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.511Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: quota manager shutdown step: '0.281000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.511Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, IPDL Background] WARNING: quota manager shutdown step: '0.281000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3383
[task 2023-05-10T16:12:22.512Z] 16:12:22     INFO - GECKO(9008) | [Parent 1996, 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-05-10T16:12:22.827Z] 16:12:22     INFO - GECKO(9008) | [Utility 4728, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-05-10T16:12:22.845Z] 16:12:22     INFO - GECKO(9008) | [GPU 3496, 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-05-10T16:12:22.845Z] 16:12:22     INFO - GECKO(9008) | [GPU 3496, Main Thread] WARNING: IPC message 'PGPU::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-05-10T16:12:22.847Z] 16:12:22     INFO - GECKO(9008) | [Utility 4728, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-10T16:12:22.858Z] 16:12:22     INFO - GECKO(9008) | Destroying context 00000210508C6000 surface 0000021050846930 on display 0000021050884300
[task 2023-05-10T16:12:22.873Z] 16:12:22     INFO - GECKO(9008) | [GPU 3496, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-10T16:12:23.187Z] 16:12:23     INFO - GECKO(9008) | [Parent 1996, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2023-05-10T16:12:23.313Z] 16:12:23     INFO - GECKO(9008) | [Parent 1996, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-05-10T16:12:23.328Z] 16:12:23     INFO - GECKO(9008) | [Parent 1996, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-05-10T16:12:23.376Z] 16:12:23     INFO - GECKO(9008) | [Parent 1996, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4688
[task 2023-05-10T16:12:23.407Z] 16:12:23     INFO - TEST-INFO | Main app process: exit 0
[task 2023-05-10T16:12:23.409Z] 16:12:23     INFO - runtests.py | Application ran for: 0:00:34.223135
[task 2023-05-10T16:12:23.409Z] 16:12:23     INFO - zombiecheck | Reading PID log: C:\Users\task_168372855320048\AppData\Local\Temp\tmpny68cylwpidlog
[task 2023-05-10T16:12:23.410Z] 16:12:23     INFO - ==> process 1996 launched child process 3496 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.0.1108392582\1014680556" -parentBuildID 20230510152225 -prefsHandle 2176 -prefMapHandle 2156 -prefsLen 21920 -prefMapSize 252348 -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {3b360553-1ab2-4694-827b-b6617f897ddf} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 2256 15b9a298890 gpu)
[task 2023-05-10T16:12:23.411Z] 16:12:23     INFO - ==> process 1996 launched child process 7180 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.1.1819019091\1435565689" -childID 1 -isForBrowser -prefsHandle 2868 -prefMapHandle 2860 -prefsLen 21748 -prefMapSize 252348 -jsInitHandle 1436 -jsInitLen 293324 -parentBuildID 20230510152225 -win32kLockedDown -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {c2a0955a-7b06-482b-bb8b-488154f31817} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 2876 15bac3be890 tab)
[task 2023-05-10T16:12:23.412Z] 16:12:23     INFO - ==> process 1996 launched child process 4716 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.2.383583864\1628084592" -childID 2 -isForBrowser -prefsHandle 3276 -prefMapHandle 3268 -prefsLen 23282 -prefMapSize 252348 -jsInitHandle 1436 -jsInitLen 293324 -parentBuildID 20230510152225 -win32kLockedDown -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {a235c010-2f65-4196-b672-51bf6015d6d1} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 3292 15bac3bca90 tab)
[task 2023-05-10T16:12:23.413Z] 16:12:23     INFO - ==> process 1996 launched child process 4644 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.3.830736905\1077663968" -childID 3 -isForBrowser -prefsHandle 1612 -prefMapHandle 1608 -prefsLen 22982 -prefMapSize 252348 -jsInitHandle 1436 -jsInitLen 293324 -parentBuildID 20230510152225 -win32kLockedDown -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {83ad5631-1717-4baa-8fae-4962e228fb98} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 3548 15baeb3fb90 tab)
[task 2023-05-10T16:12:23.414Z] 16:12:23     INFO - ==> process 1996 launched child process 7008 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.4.1935535244\1809356701" -childID 4 -isForBrowser -prefsHandle 3936 -prefMapHandle 3932 -prefsLen 23115 -prefMapSize 252348 -jsInitHandle 1436 -jsInitLen 293324 -parentBuildID 20230510152225 -win32kLockedDown -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {21d66e33-f746-45ce-8360-47aa4b5fc186} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 3940 15bae966090 tab)
[task 2023-05-10T16:12:23.414Z] 16:12:23     INFO - ==> process 1996 launched child process 3112 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.5.2015617285\1680312065" -childID 5 -isForBrowser -prefsHandle 4100 -prefMapHandle 4112 -prefsLen 23189 -prefMapSize 252348 -jsInitHandle 1436 -jsInitLen 293324 -parentBuildID 20230510152225 -win32kLockedDown -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {509318df-5e7d-418e-b3a4-bfca73fe6408} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 4040 15bae965190 tab)
[task 2023-05-10T16:12:23.416Z] 16:12:23     INFO - ==> process 1996 launched child process 3040 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.6.674148177\1459927424" -childID 6 -isForBrowser -prefsHandle 4304 -prefMapHandle 4308 -prefsLen 23291 -prefMapSize 252348 -jsInitHandle 1436 -jsInitLen 293324 -parentBuildID 20230510152225 -win32kLockedDown -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {2c92d297-ce01-4bea-96e7-a8098ac433e8} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 4292 15bb14a4290 tab)
[task 2023-05-10T16:12:23.417Z] 16:12:23     INFO - ==> process 1996 launched child process 4728 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.7.156169098\516667002" -parentBuildID 20230510152225 -sandboxingKind 0 -prefsHandle 4100 -prefMapHandle 4400 -prefsLen 30591 -prefMapSize 252348 -win32kLockedDown -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {c0bb91a8-9c36-4018-a2dc-f20ed0d2a0a5} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 4564 15bb174cf90 utility)
[task 2023-05-10T16:12:23.418Z] 16:12:23     INFO - ==> process 1996 launched child process 9484 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.8.1844771600\1952124159" -childID 7 -isForBrowser -prefsHandle 4708 -prefMapHandle 4704 -prefsLen 30591 -prefMapSize 252348 -jsInitHandle 1436 -jsInitLen 293324 -parentBuildID 20230510152225 -win32kLockedDown -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {001da406-59c0-4ad4-9a62-2e89fd0c05f1} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 4700 15bb2eb2590 tab)
[task 2023-05-10T16:12:23.419Z] 16:12:23     INFO - ==> process 1996 launched child process 10156 ("Z:\task_168372855320048\build\application\firefox\firefox.exe" -contentproc --channel="1996.9.764644978\110555019" -childID 8 -isForBrowser -prefsHandle 3640 -prefMapHandle 5332 -prefsLen 28954 -prefMapSize 252348 -jsInitHandle 1436 -jsInitLen 293324 -parentBuildID 20230510152225 -win32kLockedDown -appDir "Z:\task_168372855320048\build\application\firefox\browser" - {3c35fbba-51b2-4c5f-aafd-213e970e425e} 1996 "\\.\pipe\gecko-crash-server-pipe.1996" 3544 15b9a244b90 tab)
[task 2023-05-10T16:12:23.419Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 7008
[task 2023-05-10T16:12:23.420Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 3040
[task 2023-05-10T16:12:23.420Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 4644
[task 2023-05-10T16:12:23.421Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 3496
[task 2023-05-10T16:12:23.421Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 3112
[task 2023-05-10T16:12:23.421Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 7180
[task 2023-05-10T16:12:23.422Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 4716
[task 2023-05-10T16:12:23.423Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 9484
[task 2023-05-10T16:12:23.423Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 10156
[task 2023-05-10T16:12:23.424Z] 16:12:23     INFO - zombiecheck | Checking for orphan process with PID: 4728
[task 2023-05-10T16:12:23.424Z] 16:12:23     INFO - Stopping web server
[task 2023-05-10T16:12:23.424Z] 16:12:23     INFO - Server shut down.
[task 2023-05-10T16:12:23.438Z] 16:12:23     INFO - Web server killed.
[task 2023-05-10T16:12:23.441Z] 16:12:23     INFO - Stopping web socket server
[task 2023-05-10T16:12:23.470Z] 16:12:23     INFO - Stopping ssltunnel
[task 2023-05-10T16:12:23.501Z] 16:12:23     INFO - leakcheck | Processing log file C:\Users\task_168372855320048\AppData\Local\Temp\tmp36yujbx9.mozrunner\runtests_leaks.log
[task 2023-05-10T16:12:23.503Z] 16:12:23     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2023-05-10T16:12:23.503Z] 16:12:23     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 1000 bytes
[task 2023-05-10T16:12:23.504Z] 16:12:23     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2023-05-10T16:12:23.504Z] 16:12:23     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2023-05-10T16:12:23.504Z] 16:12:23     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2023-05-10T16:12:23.505Z] 16:12:23     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2023-05-10T16:12:23.505Z] 16:12:23     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2023-05-10T16:12:23.505Z] 16:12:23     INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2023-05-10T16:12:23.506Z] 16:12:23     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2023-05-10T16:12:23.507Z] 16:12:23     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2023-05-10T16:12:23.507Z] 16:12:23     INFO - leakcheck | Processing leak log file C:\Users\task_168372855320048\AppData\Local\Temp\tmp36yujbx9.mozrunner\runtests_leaks.log
[task 2023-05-10T16:12:23.509Z] 16:12:23     INFO - TEST-PASS | leakcheck | default no leaks detected!
<...>
[task 2023-05-10T16:12:38.852Z] 16:12:38     INFO - GECKO(3844) | 1683735158851	Marionette	TRACE	[2] MarionetteCommands actor destroyed for window id 4
[task 2023-05-10T16:12:38.855Z] 16:12:38     INFO - GECKO(3844) | 1683735158855	Marionette	DEBUG	2 <- [1,8,null,{"value":null}]
[task 2023-05-10T16:12:38.878Z] 16:12:38     INFO - runtests.py | Waiting for browser...
[task 2023-05-10T16:12:38.890Z] 16:12:38     INFO - GECKO(3844) | 1683735158892	Marionette	DEBUG	Closed connection 2
[task 2023-05-10T16:12:38.984Z] 16:12:38     INFO - GECKO(3844) | [Parent 3948, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/ThirdPartyUtil.cpp:421
[task 2023-05-10T16:12:38.993Z] 16:12:38     INFO - GECKO(3844) | [Parent 3948, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1235
[task 2023-05-10T16:12:39.046Z] 16:12:39     INFO - GECKO(3844) | [Parent 3948, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2023-05-10T16:12:39.235Z] 16:12:39     INFO - GECKO(3844) | [Parent 3948, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1155
[task 2023-05-10T16:12:39.298Z] 16:12:39     INFO - SimpleTest START
[task 2023-05-10T16:12:39.303Z] 16:12:39     INFO - Dumping test context:
[task 2023-05-10T16:12:39.303Z] 16:12:39     INFO -   fission.autostart=true
[task 2023-05-10T16:12:39.312Z] 16:12:39     INFO - TEST-START | dom/security/test/csp/test_ignore_xfo.html

Comment 1

[BugBot [:suhaib / :marco/ :calixte]]

:mjurgens, since you are the author of the regressor, bug 1809888, could you take a look?

For more information, please visit BugBot documentation.

Comment 2

[Malte Jürgens [:maltejur]]

There are two other prior failures which seem related, looking further into this tomorrow.

Comment 5

[Malte Jürgens [:maltejur]]

I am pretty sure that this has nothing to do with my changes, but is rather caused by Bug 1783248, which recently created a check for changed prefs. The timing would fit there. A possible fix would be adding app.normandy.first_run to ignorePrefs.json, similar to Bug 1829062.

Comment 6

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1783248

Comment 7

[Daniel Holbert [:dholbert]]

(In reply to Malte Jürgens from comment #5)

I am pretty sure that this has nothing to do with my changes, but is rather caused by Bug 1783248, which recently created a check for changed prefs. The timing would fit there. A possible fix would be adding app.normandy.first_run to ignorePrefs.json, similar to Bug 1829062.

Makes sense!

That list is appropriate for prefs that might get toggled in the background, while tests happen to be running, completely independently from the tests themselves.

That seems to be the case for this pref -- it looks like it starts out true, and then gets set to false here:
https://searchfox.org/mozilla-central/rev/32c74afbb24dce4b5dd6b33be71197e615631d71/toolkit/components/normandy/lib/RecipeRunner.sys.mjs#132-136

Comment 8

[Daniel Holbert [:dholbert]]

(Reclassifying and removing the specific test name from the bug title, given this explanation.)

Also retitling the bug, since if the bug happens to end up having "real" intermittent test failures, it'd be better to spawn a new testname | single tracking bug bug for those, rather than lumping them in here.

Comment 9

[Daniel Holbert [:dholbert]]

Looks like the patch here is stuck in phab limbo (which may be a broader issue with phabricator/bugzilla integration being temporarily stuck/broken). I filed https://bugzilla.mozilla.org/show_bug.cgi?id=1832392 on that.

Comment 10

[Malte Jürgens [:maltejur]]

Attachment: Bug 1832358: Add `app.normandy.first_run` to `ignorePrefs.json` r?dholbert

Comment 11

[Malte Jürgens [:maltejur]]

Attachment: Bug 1832358: Add `app.normandy.first_run` to `ignorePrefs.json` r?dholbert

Comment 12

[Daniel Holbert [:dholbert]]

Reviewed and triggered lando to get this landed. Thanks for the patch, and sorry for the temporary phabricator limbo headaches. :)

Comment 13

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/09d2a4afc6ef
Add `app.normandy.first_run` to `ignorePrefs.json` r=dholbert DONTBUILD

Comment 14

[Cristian Tuns]

https://hg.mozilla.org/mozilla-central/rev/09d2a4afc6ef

Comment 15

[BugBot [:suhaib / :marco/ :calixte]]

The patch landed in nightly and beta is affected.
:mjurgens, is this bug important enough to require an uplift?

• If yes, please nominate the patch for beta approval.
• If no, please set status-firefox114 to wontfix.

For more information, please visit BugBot documentation.

Comment 16

[Intermittent Failures Robot]

1 failures in 4077 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832358&startday=2023-05-08&endday=2023-05-14&tree=all