[taskcluster 2025-01-29 16:01:45.373Z] Task ID: ed9kQ2QzSaGHwfZASEa3-Q
[taskcluster 2025-01-29 16:01:45.373Z] Worker ID: 2876284655722032730
[taskcluster 2025-01-29 16:01:45.373Z] Worker Group: us-central1-f
[taskcluster 2025-01-29 16:01:45.373Z] Worker Node Type: projects/474530013412/machineTypes/c2-standard-4
[taskcluster 2025-01-29 16:01:45.373Z] Worker Type: t-linux-large-noscratch-gcp
[taskcluster 2025-01-29 16:01:45.373Z] Public IP: 34.30.36.27
[taskcluster 2025-01-29 16:01:45.373Z] Hostname: gecko-t-t-linux-large-noscratch-gcp-hqncmqo3qbuitjrzorgona
[taskcluster 2025-01-29 16:01:45.373Z] using cache "ci-level-1-checkouts-hg58-v3-51c21ecd6de64212b936" -> /builds/worker/checkouts
[taskcluster 2025-01-29 16:01:45.373Z] using cache "ci-level-1-tooltool-cache-v3-51c21ecd6de64212b936" -> /builds/worker/tooltool-cache

[taskcluster 2025-01-29 16:01:46.883Z] Downloading artifact "public/image.tar.zst" from task ID: FFCX7yxdTIeO2mxvjgtxVA.
[taskcluster 2025-01-29 16:01:51.885Z] Download Progress: 7.52%
[taskcluster 2025-01-29 16:01:56.885Z] Download Progress: 21.77%
[taskcluster 2025-01-29 16:02:01.885Z] Download Progress: 35.86%
[taskcluster 2025-01-29 16:02:06.885Z] Download Progress: 51.71%
[taskcluster 2025-01-29 16:02:11.885Z] Download Progress: 66.43%
[taskcluster 2025-01-29 16:02:16.885Z] Download Progress: 80.72%
[taskcluster 2025-01-29 16:02:21.885Z] Download Progress: 96.09%
[taskcluster 2025-01-29 16:02:23.129Z] Downloaded artifact successfully.
[taskcluster 2025-01-29 16:02:23.129Z] Downloaded 1249.366 mb

...(3327 lines hidden)...

[task 2025-01-29T16:08:16.183Z] 16:08:16     INFO - GECKO(2023) | MEMORY STAT | vsize 10860MB | residentFast 192MB | heapAllocated 17MB
[task 2025-01-29T16:08:16.245Z] 16:08:16     INFO - TEST-OK | widget/tests/test_clipboard_asyncSetData.html | took 440ms
[task 2025-01-29T16:08:16.344Z] 16:08:16     INFO - TEST-START | widget/tests/test_clipboard_getDataSnapshot.html
[task 2025-01-29T16:08:16.580Z] 16:08:16     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: IPC message 'PClipboardReadRequest::Reply_GetData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:16.631Z] 16:08:16     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: IPC message 'PClipboardReadRequest::Reply_GetData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:16.718Z] 16:08:16     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: IPC message 'PClipboardReadRequest::Reply_GetData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:16.763Z] 16:08:16     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: IPC message 'PClipboardReadRequest::Reply_GetData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:16.813Z] 16:08:16     INFO - GECKO(2023) | ###!!! [Parent][DispatchSyncMessage] Error: PClipboardReadRequest::Msg_GetDataSync Message dropped: message could not be delivered
[task 2025-01-29T16:08:16.819Z] 16:08:16     INFO - GECKO(2023) | MEMORY STAT | vsize 10860MB | residentFast 193MB | heapAllocated 18MB
[task 2025-01-29T16:08:16.874Z] 16:08:16     INFO - TEST-OK | widget/tests/test_clipboard_getDataSnapshot.html | took 531ms
[task 2025-01-29T16:08:16.972Z] 16:08:16     INFO - TEST-START | widget/tests/test_clipboard_getDataSnapshotSync.html
[task 2025-01-29T16:08:17.198Z] 16:08:17     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: IPC message 'PClipboardReadRequest::Reply_GetData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:17.240Z] 16:08:17     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: IPC message 'PClipboardReadRequest::Reply_GetData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:17.316Z] 16:08:17     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: IPC message 'PClipboardReadRequest::Reply_GetData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:17.356Z] 16:08:17     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: IPC message 'PClipboardReadRequest::Reply_GetData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:17.406Z] 16:08:17     INFO - GECKO(2023) | ###!!! [Parent][DispatchSyncMessage] Error: PClipboardReadRequest::Msg_GetDataSync Message dropped: message could not be delivered
[task 2025-01-29T16:08:17.413Z] 16:08:17     INFO - GECKO(2023) | MEMORY STAT | vsize 10860MB | residentFast 193MB | heapAllocated 18MB
[task 2025-01-29T16:08:17.465Z] 16:08:17     INFO - TEST-OK | widget/tests/test_clipboard_getDataSnapshotSync.html | took 489ms
[task 2025-01-29T16:08:17.555Z] 16:08:17     INFO - TEST-START | widget/tests/test_contextmenu_by_mouse_on_unix.html
[task 2025-01-29T16:08:17.838Z] 16:08:17     INFO - GECKO(2023) | MEMORY STAT | vsize 10860MB | residentFast 193MB | heapAllocated 17MB
[task 2025-01-29T16:08:17.900Z] 16:08:17     INFO - TEST-OK | widget/tests/test_contextmenu_by_mouse_on_unix.html | took 345ms
[task 2025-01-29T16:08:17.988Z] 16:08:17     INFO - TEST-START | widget/tests/test_textScaleFactor_system_font.html
[task 2025-01-29T16:08:18.640Z] 16:08:18     INFO - GECKO(2023) | MEMORY STAT | vsize 2668MB | residentFast 193MB | heapAllocated 17MB
[task 2025-01-29T16:08:19.043Z] 16:08:19     INFO - TEST-OK | widget/tests/test_textScaleFactor_system_font.html | took 1054ms
[task 2025-01-29T16:08:19.170Z] 16:08:19     INFO - TEST-START | Shutdown
[task 2025-01-29T16:08:19.171Z] 16:08:19     INFO - Passed:  2815
[task 2025-01-29T16:08:19.171Z] 16:08:19     INFO - Failed:  0
[task 2025-01-29T16:08:19.172Z] 16:08:19     INFO - Todo:    68
[task 2025-01-29T16:08:19.173Z] 16:08:19     INFO - Mode:    e10s
[task 2025-01-29T16:08:19.174Z] 16:08:19     INFO - Slowest: 5318ms - /tests/widget/tests/test_assign_event_data.html
[task 2025-01-29T16:08:19.175Z] 16:08:19     INFO - SimpleTest FINISHED
[task 2025-01-29T16:08:19.176Z] 16:08:19     INFO - TEST-INFO | Ran 1 Loops
[task 2025-01-29T16:08:19.176Z] 16:08:19     INFO - SimpleTest FINISHED
[task 2025-01-29T16:08:19.683Z] 16:08:19     INFO - GECKO(2023) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 569: NotFoundError: No such JSWindowActor 'SpecialPowers'
[task 2025-01-29T16:08:19.685Z] 16:08:19     INFO - GECKO(2023) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 569: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2025-01-29T16:08:19.686Z] 16:08:19     INFO - GECKO(2023) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 569: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2025-01-29T16:08:19.769Z] 16:08:19     INFO - GECKO(2023) | 1738166899768	Marionette	TRACE	Received observer notification quit-application
[task 2025-01-29T16:08:19.771Z] 16:08:19     INFO - GECKO(2023) | 1738166899768	Marionette	INFO	Stopped listening on port 2828
[task 2025-01-29T16:08:19.773Z] 16:08:19     INFO - GECKO(2023) | 1738166899772	Marionette	DEBUG	Marionette stopped listening
[task 2025-01-29T16:08:19.838Z] 16:08:19     INFO - GECKO(2023) | [Child 2212, Main Thread] WARNING: IPC message 'PContent::Msg_StoreUserInteractionAsPermission' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:19.841Z] 16:08:19     INFO - GECKO(2023) | [Child 2212, Main Thread] WARNING: NS_ENSURE_TRUE(aWindowContext) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:459
[task 2025-01-29T16:08:19.842Z] 16:08:19     INFO - GECKO(2023) | [Child 2212, Main Thread] WARNING: IPC message 'PContent::Msg_StoreUserInteractionAsPermission' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:19.843Z] 16:08:19     INFO - GECKO(2023) | [Child 2212, Main Thread] WARNING: NS_ENSURE_TRUE(aWindowContext) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:459
[task 2025-01-29T16:08:19.843Z] 16:08:19     INFO - GECKO(2023) | [Parent 2023, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:235
[task 2025-01-29T16:08:19.844Z] 16:08:19     INFO - GECKO(2023) | [Parent 2023, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:235
[task 2025-01-29T16:08:19.846Z] 16:08:19     INFO - GECKO(2023) | [Child 2276, IPC I/O Child] WARNING: [8489117BD661F461.ACF4E7B65F424055]: GetUserData call for port '9C67516E490CC650.9B217CE3AA36142C' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:424
[task 2025-01-29T16:08:20.167Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: BlockShutdown: Init failed: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtectionStorage.cpp:378
[task 2025-01-29T16:08:20.270Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.271Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.271Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.272Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.272Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.272Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.273Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.273Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.273Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.273Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.277Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: idb: '0.008000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.277Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: idb: '0.008000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.278Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, QuotaManager IO] WARNING: quota manager shutdown step: '0.008000s: ShutdownStorageOp::DoDirectoryWork -> ShutdownStorageInternal.', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.388Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: quota manager shutdown step: '0.120000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.390Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: quota manager shutdown step: '0.120000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.391Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: idb: '0.120000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.391Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: cache: '0.120000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.392Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: sdb: '0.120000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.394Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: fs: '0.120000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.394Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: ls: '0.120000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.394Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: quota manager shutdown step: '0.120000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.394Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: quota manager shutdown step: '0.120000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.394Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPDL Background] WARNING: quota manager shutdown step: '0.120000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-29T16:08:20.508Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_TRUE(aWindowContext) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:459
[task 2025-01-29T16:08:20.512Z] 16:08:20     INFO - GECKO(2023) | [Utility 2203, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:20.531Z] 16:08:20     INFO - GECKO(2023) | [RDD 2122, Main Thread] WARNING: IPC message 'PRDD::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-29T16:08:20.534Z] 16:08:20     INFO - GECKO(2023) | [Parent 2023, IPC I/O Parent] WARNING: Call to Send() failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeChannel.cpp:195
[task 2025-01-29T16:08:20.545Z] 16:08:20     INFO - GECKO(2023) | Destroying context 7f2a9016dac0 surface 0 on display 7f2a8e612100
[task 2025-01-29T16:08:21.144Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2025-01-29T16:08:21.145Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2025-01-29T16:08:21.145Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2025-01-29T16:08:21.146Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2025-01-29T16:08:21.148Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2025-01-29T16:08:21.148Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2025-01-29T16:08:21.149Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2025-01-29T16:08:21.149Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2025-01-29T16:08:21.149Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2025-01-29T16:08:21.149Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2025-01-29T16:08:21.149Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:406
[task 2025-01-29T16:08:21.149Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, Main Thread] WARNING: NS_ENSURE_TRUE(!!svc) failed: file /builds/worker/checkouts/gecko/dom/file/uri/BlobURLProtocolHandler.cpp:506
[task 2025-01-29T16:08:21.187Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, IPC I/O Parent] WARNING: process 2203 is a zombie: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:250
[task 2025-01-29T16:08:21.188Z] 16:08:21     INFO - GECKO(2023) | [Parent 2023, IPC I/O Parent] WARNING: process 2122 is a zombie: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:250
[task 2025-01-29T16:08:21.202Z] 16:08:21     INFO - TEST-INFO | Main app process: exit 0
[task 2025-01-29T16:08:21.203Z] 16:08:21     INFO - runtests.py | Application ran for: 0:00:22.039658
[task 2025-01-29T16:08:21.203Z] 16:08:21     INFO - zombiecheck | Reading PID log: /tmp/tmp8_i_extapidlog
[task 2025-01-29T16:08:21.203Z] 16:08:21     INFO - ==> process 2023 launched child process 2096
[task 2025-01-29T16:08:21.203Z] 16:08:21     INFO - zombiecheck | Checking for orphan process with PID: 2096
[task 2025-01-29T16:08:21.203Z] 16:08:21     INFO - Stopping web server
[task 2025-01-29T16:08:21.214Z] 16:08:21     INFO - Server shut down.
[task 2025-01-29T16:08:21.214Z] 16:08:21     INFO - Web server killed.
[task 2025-01-29T16:08:21.214Z] 16:08:21     INFO - Stopping web socket server
[task 2025-01-29T16:08:21.214Z] 16:08:21     INFO - Stopping ssltunnel
[task 2025-01-29T16:08:21.215Z] 16:08:21     INFO - Stopping gst for v4l2loopback
[task 2025-01-29T16:08:21.215Z] 16:08:21     INFO - leakcheck | Processing log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks.log for scope widget/tests/mochitest.toml
[task 2025-01-29T16:08:21.215Z] 16:08:21     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2025-01-29T16:08:21.215Z] 16:08:21     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 0 bytes
[task 2025-01-29T16:08:21.215Z] 16:08:21     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2025-01-29T16:08:21.215Z] 16:08:21     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2025-01-29T16:08:21.215Z] 16:08:21     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2025-01-29T16:08:21.215Z] 16:08:21     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2025-01-29T16:08:21.218Z] 16:08:21     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_rdd_pid2122.log
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-PASS | leakcheck | rdd no leaks detected!
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_tab_pid2323.log
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_forkserver_pid2096.log
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-PASS | leakcheck | forkserver no leaks detected!
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_tab_pid2154.log
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_utility_pid2203.log
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-PASS | leakcheck | utility no leaks detected!
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_tab_pid2225.log
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_tab_pid2212.log
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_tab_pid2113.log
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_tab_pid2276.log
[task 2025-01-29T16:08:21.219Z] 16:08:21     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-29T16:08:21.220Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks_tab_pid2214.log
[task 2025-01-29T16:08:21.220Z] 16:08:21     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-29T16:08:21.221Z] 16:08:21     INFO - leakcheck | Processing leak log file /tmp/tmppqpqft8o.mozrunner/runtests_leaks.log
[task 2025-01-29T16:08:21.225Z] 16:08:21     INFO - TEST-PASS | leakcheck | default no leaks detected!
[task 2025-01-29T16:08:21.225Z] 16:08:21     INFO - runtests.py | Running tests: end.
[task 2025-01-29T16:08:21.272Z] 16:08:21     INFO - Buffered messages finished
[task 2025-01-29T16:08:21.272Z] 16:08:21     INFO -  0 INFO TEST-START | Shutdown
[task 2025-01-29T16:08:21.272Z] 16:08:21     INFO -  1 INFO Passed:  29108
[task 2025-01-29T16:08:21.272Z] 16:08:21     INFO -  2 INFO Failed:  0
[task 2025-01-29T16:08:21.272Z] 16:08:21     INFO -  3 INFO Todo:    98
[task 2025-01-29T16:08:21.272Z] 16:08:21     INFO -  4 INFO Mode:    e10s
[task 2025-01-29T16:08:21.272Z] 16:08:21     INFO -  5 INFO SimpleTest FINISHED
[task 2025-01-29T16:08:21.272Z] 16:08:21     INFO - Buffered messages finished
[task 2025-01-29T16:08:21.272Z] 16:08:21     INFO - SUITE-END | took 76s
[task 2025-01-29T16:08:21.301Z] 16:08:21     INFO - Return code: 0
[task 2025-01-29T16:08:21.301Z] 16:08:21     INFO - TinderboxPrint: mochitest-mochitest-plain<br/>80/0/0
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - The mochitest suite: mochitest-plain ran with return status: SUCCESS
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - Running post-action listener: _package_coverage_data
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - Running post-action listener: process_java_coverage_data
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - [mozharness: 2025-01-29 16:08:21.302339Z] Finished run-tests step (success)
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - [mozharness: 2025-01-29 16:08:21.302386Z] Running uninstall step.
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - Running pre-action listener: _resource_record_pre_action
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - Running main action method: uninstall
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - Skipping uninstall for non-MSIX test
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - [mozharness: 2025-01-29 16:08:21.302670Z] Finished uninstall step (success)
[task 2025-01-29T16:08:21.302Z] 16:08:21     INFO - Running post-run listener: _resource_record_post_run
[task 2025-01-29T16:08:21.374Z] 16:08:21     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2025-01-29T16:08:21.376Z] 16:08:21     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-plain.overall", "extraOptions": ["e10s", "taskcluster-projects/474530013412/machineTypes/c2-standard-4"], "subtests": [{"name": "cpu_percent", "value": 49.3774404761905}, {"name": "io_write_bytes", "value": 561451008}, {"name": "io.read_bytes", "value": 0}, {"name": "io_write_time", "value": 445240}, {"name": "io_read_time", "value": 0}]}, {"name": "mochitest.mochitest-plain.start-pulseaudio", "subtests": [{"name": "time", "value": 0.017933119000019815}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-plain.install", "subtests": [{"name": "time", "value": 6.883764455999994}, {"name": "cpu_percent", "value": 25.972014925373134}]}, {"name": "mochitest.mochitest-plain.stage-files", "subtests": [{"name": "time", "value": 0.0002342509999948561}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-plain.run-tests", "subtests": [{"name": "time", "value": 77.179182357}, {"name": "cpu_percent", "value": 51.46070038910509}]}, {"name": "mochitest.mochitest-plain.uninstall", "subtests": [{"name": "time", "value": 0.00016912400002411232}, {"name": "cpu_percent", "value": 0}]}]}
[task 2025-01-29T16:08:21.376Z] 16:08:21     INFO - Total resource usage - Wall time: 84s; CPU: Can't collect data; Read bytes: 0; Write bytes: 561451008; Read time: 0; Write time: 445240
[task 2025-01-29T16:08:21.376Z] 16:08:21     INFO - TinderboxPrint: I/O read bytes / time<br/>0 / 0
[task 2025-01-29T16:08:21.376Z] 16:08:21     INFO - TinderboxPrint: I/O write bytes / time<br/>561,451,008 / 445,240
[task 2025-01-29T16:08:21.376Z] 16:08:21     INFO - TinderboxPrint: CPU idle<br/>160.8 (48.2%)
[task 2025-01-29T16:08:21.376Z] 16:08:21     INFO - TinderboxPrint: CPU iowait<br/>7.9 (2.4%)
[task 2025-01-29T16:08:21.376Z] 16:08:21     INFO - TinderboxPrint: CPU system<br/>14.3 (4.3%)
[task 2025-01-29T16:08:21.376Z] 16:08:21     INFO - TinderboxPrint: CPU user<br/>150.2 (45.1%)
[task 2025-01-29T16:08:21.377Z] 16:08:21     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2025-01-29T16:08:21.377Z] 16:08:21     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-29T16:08:21.378Z] 16:08:21     INFO - install - Wall time: 7s; CPU: 26%; Read bytes: 0; Write bytes: 8192; Read time: 0; Write time: 0
[task 2025-01-29T16:08:21.378Z] 16:08:21     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-29T16:08:21.382Z] 16:08:21     INFO - run-tests - Wall time: 77s; CPU: 51%; Read bytes: 0; Write bytes: 561442816; Read time: 0; Write time: 445240
[task 2025-01-29T16:08:21.383Z] 16:08:21     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-29T16:08:21.556Z] 
[task 2025-01-29T16:08:21.556Z] # Run a custom mach command (this is typically used by action tasks to run
[task 2025-01-29T16:08:21.556Z] # harnesses in a particular way)
[task 2025-01-29T16:08:21.556Z] if [ "$CUSTOM_MACH_COMMAND" ]; then
[task 2025-01-29T16:08:21.556Z]     eval "'$WORKSPACE/build/venv/bin/python' '$WORKSPACE/build/tests/mach' ${CUSTOM_MACH_COMMAND} ${@}"
[task 2025-01-29T16:08:21.556Z]     exit $?
[task 2025-01-29T16:08:21.556Z] fi
[task 2025-01-29T16:08:21.556Z] + '[' '' ']'
[task 2025-01-29T16:08:21.556Z] cleanup
[task 2025-01-29T16:08:21.556Z] + cleanup
[task 2025-01-29T16:08:21.556Z] + local rv=0
[task 2025-01-29T16:08:21.556Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2025-01-29T16:08:21.556Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2025-01-29T16:08:21.558Z] + false
[task 2025-01-29T16:08:21.558Z] + '[' ']'
[task 2025-01-29T16:08:21.558Z] + true
[task 2025-01-29T16:08:21.558Z] + cleanup_xvfb
[task 2025-01-29T16:08:21.558Z] ++ pidof Xvfb
[task 2025-01-29T16:08:21.562Z] + local xvfb_pid=48
[task 2025-01-29T16:08:21.562Z] + local vnc=false
[task 2025-01-29T16:08:21.562Z] + local interactive=false
[task 2025-01-29T16:08:21.562Z] + '[' -n 48 ']'
[task 2025-01-29T16:08:21.562Z] + [[ false == false ]]
[task 2025-01-29T16:08:21.562Z] + [[ false == false ]]
[task 2025-01-29T16:08:21.562Z] + kill 48
[task 2025-01-29T16:08:21.562Z] + exit 0
[taskcluster 2025-01-29 16:08:21.989Z] === Task Finished ===
[taskcluster 2025-01-29 16:08:24.635Z] Successful task run with exit code: 0 completed in 399.263 seconds