[taskcluster 2025-01-27T12:22:18.193Z] Worker Type (gecko-t/win10-64-2009) settings:
[taskcluster 2025-01-27T12:22:18.193Z]   {
[taskcluster 2025-01-27T12:22:18.193Z]     "config": {
[taskcluster 2025-01-27T12:22:18.193Z]       "deploymentId": ""
[taskcluster 2025-01-27T12:22:18.193Z]     },
[taskcluster 2025-01-27T12:22:18.193Z]     "generic-worker": {
[taskcluster 2025-01-27T12:22:18.193Z]       "config": {
[taskcluster 2025-01-27T12:22:18.193Z]         "headlessTasks": false,
[taskcluster 2025-01-27T12:22:18.193Z]         "runTasksAsCurrentUser": false
[taskcluster 2025-01-27T12:22:18.193Z]       },
[taskcluster 2025-01-27T12:22:18.193Z]       "engine": "multiuser",
[taskcluster 2025-01-27T12:22:18.193Z]       "go-arch": "amd64",
[taskcluster 2025-01-27T12:22:18.193Z]       "go-os": "windows",
[taskcluster 2025-01-27T12:22:18.193Z]       "go-version": "go1.23.2",
[taskcluster 2025-01-27T12:22:18.193Z]       "release": "https://github.com/taskcluster/taskcluster/releases/tag/v72.1.0",
[taskcluster 2025-01-27T12:22:18.193Z]       "revision": "114df53f4887bd7e18fdfd834cdd9997e17f8774",
[taskcluster 2025-01-27T12:22:18.193Z]       "source": "https://github.com/taskcluster/taskcluster/commits/114df53f4887bd7e18fdfd834cdd9997e17f8774",
[taskcluster 2025-01-27T12:22:18.193Z]       "version": "72.1.0"
[taskcluster 2025-01-27T12:22:18.193Z]     },
[taskcluster 2025-01-27T12:22:18.193Z]     "instance-type": "Standard_F8s_v2",

...(3247 lines hidden)...

[task 2025-01-27T12:26:51.256Z] 12:26:51     INFO - GECKO(6364) | [Child 2332, Main Thread] WARNING: '!editingHost', file /builds/worker/checkouts/gecko/editor/libeditor/HTMLEditor.cpp:1127
[task 2025-01-27T12:26:51.257Z] 12:26:51     INFO - GECKO(6364) | [Child 2332, Main Thread] WARNING: mozInlineSpellCHecker::UpdateCurrentDictionary() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/EditorBase.cpp:6168
[task 2025-01-27T12:26:51.267Z] 12:26:51     INFO - GECKO(6364) | MEMORY STAT | vsize 2112085MB | vsizeMaxContiguous 66013779MB | residentFast 131MB | heapAllocated 17MB
[task 2025-01-27T12:26:51.346Z] 12:26:51     INFO - TEST-OK | widget/tests/test_autocorrect.html | took 371ms
[task 2025-01-27T12:26:51.422Z] 12:26:51     INFO - TEST-START | widget/tests/test_clipboard.html
[task 2025-01-27T12:26:51.667Z] 12:26:51     INFO - GECKO(6364) | MEMORY STAT | vsize 2112085MB | vsizeMaxContiguous 66013779MB | residentFast 131MB | heapAllocated 14MB
[task 2025-01-27T12:26:51.735Z] 12:26:51     INFO - TEST-OK | widget/tests/test_clipboard.html | took 313ms
[task 2025-01-27T12:26:51.828Z] 12:26:51     INFO - TEST-START | widget/tests/test_clipboard_asyncSetData.html
[task 2025-01-27T12:26:52.032Z] 12:26:52     INFO - GECKO(6364) | MEMORY STAT | vsize 2112085MB | vsizeMaxContiguous 66013779MB | residentFast 130MB | heapAllocated 15MB
[task 2025-01-27T12:26:52.097Z] 12:26:52     INFO - TEST-OK | widget/tests/test_clipboard_asyncSetData.html | took 269ms
[task 2025-01-27T12:26:52.179Z] 12:26:52     INFO - TEST-START | widget/tests/test_clipboard_getDataSnapshot.html
[task 2025-01-27T12:26:52.399Z] 12:26:52     INFO - GECKO(6364) | [Parent 9896, 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-27T12:26:52.678Z] 12:26:52     INFO - GECKO(6364) | [Parent 9896, 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-27T12:26:52.739Z] 12:26:52     INFO - GECKO(6364) | ###!!! [Parent][DispatchSyncMessage] Error: PClipboardReadRequest::Msg_GetDataSync Message dropped: message could not be delivered
[task 2025-01-27T12:26:52.751Z] 12:26:52     INFO - GECKO(6364) | MEMORY STAT | vsize 2112085MB | vsizeMaxContiguous 66013779MB | residentFast 129MB | heapAllocated 14MB
[task 2025-01-27T12:26:52.803Z] 12:26:52     INFO - TEST-OK | widget/tests/test_clipboard_getDataSnapshot.html | took 625ms
[task 2025-01-27T12:26:52.903Z] 12:26:52     INFO - TEST-START | widget/tests/test_clipboard_getDataSnapshotSync.html
[task 2025-01-27T12:26:53.098Z] 12:26:53     INFO - GECKO(6364) | [Parent 9896, 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-27T12:26:53.145Z] 12:26:53     INFO - GECKO(6364) | [Parent 9896, 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-27T12:26:53.214Z] 12:26:53     INFO - GECKO(6364) | ###!!! [Parent][DispatchSyncMessage] Error: PClipboardReadRequest::Msg_GetDataSync Message dropped: message could not be delivered
[task 2025-01-27T12:26:53.226Z] 12:26:53     INFO - GECKO(6364) | MEMORY STAT | vsize 2112085MB | vsizeMaxContiguous 66013779MB | residentFast 131MB | heapAllocated 16MB
[task 2025-01-27T12:26:53.282Z] 12:26:53     INFO - TEST-OK | widget/tests/test_clipboard_getDataSnapshotSync.html | took 379ms
[task 2025-01-27T12:26:53.364Z] 12:26:53     INFO - TEST-START | widget/tests/test_contextmenu_chorded_buttons.html
[task 2025-01-27T12:26:53.632Z] 12:26:53     INFO - GECKO(6364) | [WARN  webrender::renderer::init] asking to enable_gpu_markers but no supporting extension was found
[task 2025-01-27T12:26:53.674Z] 12:26:53     INFO - GECKO(6364) | MEMORY STAT | vsize 2112085MB | vsizeMaxContiguous 66013779MB | residentFast 130MB | heapAllocated 15MB
[task 2025-01-27T12:26:53.710Z] 12:26:53     INFO - GECKO(6364) | [Child 2332, Main Thread] WARNING: NS_ENSURE_TRUE(mDoneSetup) failed: file /builds/worker/checkouts/gecko/editor/composer/nsEditingSession.cpp:1163
[task 2025-01-27T12:26:53.726Z] 12:26:53     INFO - TEST-OK | widget/tests/test_contextmenu_chorded_buttons.html | took 363ms
[task 2025-01-27T12:26:53.810Z] 12:26:53     INFO - TEST-START | widget/tests/test_textScaleFactor_system_font.html
[task 2025-01-27T12:26:53.951Z] 12:26:53     INFO - GECKO(6364) | [Parent 9896, Main Thread] WARNING: '!mColorFilterWatcher', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:69
[task 2025-01-27T12:26:54.043Z] 12:26:54     INFO - GECKO(6364) | [Parent 9896, Main Thread] WARNING: '!mColorFilterWatcher', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:69
[task 2025-01-27T12:26:54.102Z] 12:26:54     INFO - GECKO(6364) | MEMORY STAT | vsize 2112086MB | vsizeMaxContiguous 66013779MB | residentFast 132MB | heapAllocated 14MB
[task 2025-01-27T12:26:54.194Z] 12:26:54     INFO - GECKO(6364) | [Parent 9896, Main Thread] WARNING: '!mColorFilterWatcher', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:69
[task 2025-01-27T12:26:54.340Z] 12:26:54     INFO - TEST-OK | widget/tests/test_textScaleFactor_system_font.html | took 530ms
[task 2025-01-27T12:26:54.426Z] 12:26:54     INFO - TEST-START | Shutdown
[task 2025-01-27T12:26:54.427Z] 12:26:54     INFO - Passed:  3019
[task 2025-01-27T12:26:54.428Z] 12:26:54     INFO - Failed:  0
[task 2025-01-27T12:26:54.428Z] 12:26:54     INFO - Todo:    76
[task 2025-01-27T12:26:54.429Z] 12:26:54     INFO - Mode:    e10s
[task 2025-01-27T12:26:54.429Z] 12:26:54     INFO - Slowest: 4880ms - /tests/widget/tests/test_assign_event_data.html
[task 2025-01-27T12:26:54.430Z] 12:26:54     INFO - SimpleTest FINISHED
[task 2025-01-27T12:26:54.431Z] 12:26:54     INFO - TEST-INFO | Ran 1 Loops
[task 2025-01-27T12:26:54.432Z] 12:26:54     INFO - SimpleTest FINISHED
[task 2025-01-27T12:26:54.907Z] 12:26:54     INFO - GECKO(6364) | [Parent 9896, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorParent.cpp:42
[task 2025-01-27T12:26:54.986Z] 12:26:54     INFO - GECKO(6364) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 569: NotFoundError: No such JSWindowActor 'SpecialPowers'
[task 2025-01-27T12:26:54.992Z] 12:26:54     INFO - GECKO(6364) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 569: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2025-01-27T12:26:54.993Z] 12:26:54     INFO - GECKO(6364) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 569: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2025-01-27T12:26:55.078Z] 12:26:55     INFO - GECKO(6364) | 1737980815077	Marionette	TRACE	Received observer notification quit-application
[task 2025-01-27T12:26:55.079Z] 12:26:55     INFO - GECKO(6364) | 1737980815078	Marionette	INFO	Stopped listening on port 2828
[task 2025-01-27T12:26:55.083Z] 12:26:55     INFO - GECKO(6364) | 1737980815082	Marionette	DEBUG	Marionette stopped listening
[task 2025-01-27T12:26:55.165Z] 12:26:55     INFO - GECKO(6364) | [Child 2332, 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-27T12:26:55.167Z] 12:26:55     INFO - GECKO(6364) | [Child 2332, Main Thread] WARNING: NS_ENSURE_TRUE(aWindowContext) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:459
[task 2025-01-27T12:26:55.168Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:235
[task 2025-01-27T12:26:55.169Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:235
[task 2025-01-27T12:26:55.174Z] 12:26:55     INFO - GECKO(6364) | [Child 6556, IPC I/O Child] WARNING: [9263F24497ED3C3E.96303BE21A0FF9E1]: GetUserData call for port '681DAE1D675E40FF.40C2FBC94DC857E2' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:424
[task 2025-01-27T12:26:55.176Z] 12:26:55     INFO - GECKO(6364) | [Child 2332, IPC I/O Child] WARNING: [A143F4C77C0C6AF9.F7475B7EF3D377C9]: GetUserData call for port 'BBF743DEF83E655F.55324A06AFC7E3C5' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:424
[task 2025-01-27T12:26:55.405Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, Main Thread] WARNING: BlockShutdown: Init failed: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtectionStorage.cpp:378
[task 2025-01-27T12:26:55.435Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.439Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.439Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.440Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.441Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.442Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.443Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.443Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.444Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.445Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.445Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.446Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.447Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, QuotaManager IO] WARNING: quota manager shutdown step: '0.000000s: ShutdownStorageOp::DoDirectoryWork -> ShutdownStorageInternal.', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.447Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.448Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.448Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: idb: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.449Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: cache: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.450Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: sdb: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.451Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: fs: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.452Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: ls: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.452Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.453Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.454Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2318
[task 2025-01-27T12:26:55.554Z] 12:26:55     INFO - GECKO(6364) | [Parent 9896, Main Thread] WARNING: NS_ENSURE_TRUE(aWindowContext) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:459
[task 2025-01-27T12:26:55.565Z] 12:26:55     INFO - GECKO(6364) | [Utility 5320, 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-27T12:26:55.572Z] 12:26:55     INFO - GECKO(6364) | [GPU 4556, Main Thread] WARNING: IPC message 'PGPU::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:558
[task 2025-01-27T12:26:55.579Z] 12:26:55     INFO - GECKO(6364) | Destroying context 1f430e08000 surface 1f42dc8f420 on display 1f42dccd100
[task 2025-01-27T12:26:55.582Z] 12:26:55     INFO - GECKO(6364) | [RDD 1100, 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-27T12:26:56.292Z] 12:26:56     INFO - TEST-INFO | Main app process: exit 0
[task 2025-01-27T12:26:56.294Z] 12:26:56     INFO - runtests.py | Application ran for: 0:00:21.447348
[task 2025-01-27T12:26:56.294Z] 12:26:56     INFO - zombiecheck | Reading PID log: C:\Users\task_173798001928528\AppData\Local\Temp\tmpfh4qonu8pidlog
[task 2025-01-27T12:26:56.295Z] 12:26:56     INFO - ==> process 9896 launched child process 4556 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20250126212632 -prefsHandle 2148 -prefsLen 26468 -prefMapHandle 2152 -prefMapSize 283412 -ipcHandle 2224 -initialChannelId {f1e9f309-c2c8-413c-8577-98cba5f2f76a} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -appDir "D:\task_173798001928528\build\application\firefox\browser" - 1 gpu)
[task 2025-01-27T12:26:56.296Z] 12:26:56     INFO - ==> process 9896 launched child process 3332 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 2864 -prefsLen 26223 -prefMapHandle 2868 -prefMapSize 283412 -jsInitHandle 2872 -jsInitLen 314440 -parentBuildID 20250126212632 -ipcHandle 2848 -initialChannelId {7ec19386-b38c-469c-9ba4-4aa9035cc2c9} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -win32kLockedDown -appDir "D:\task_173798001928528\build\application\firefox\browser" - 2 tab)
[task 2025-01-27T12:26:56.297Z] 12:26:56     INFO - ==> process 9896 launched child process 1100 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20250126212632 -prefsHandle 3132 -prefsLen 27728 -prefMapHandle 3136 -prefMapSize 283412 -ipcHandle 3144 -initialChannelId {a3e70833-0fcb-43ea-b4a3-7e2fc9f697c9} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -appDir "D:\task_173798001928528\build\application\firefox\browser" - 3 rdd)
[task 2025-01-27T12:26:56.298Z] 12:26:56     INFO - ==> process 9896 launched child process 6960 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 3524 -prefsLen 27864 -prefMapHandle 3528 -prefMapSize 283412 -jsInitHandle 3532 -jsInitLen 314440 -parentBuildID 20250126212632 -ipcHandle 3540 -initialChannelId {765507fe-e53d-4122-8832-7050f0ba2da5} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -win32kLockedDown -appDir "D:\task_173798001928528\build\application\firefox\browser" - 4 tab)
[task 2025-01-27T12:26:56.300Z] 12:26:56     INFO - ==> process 9896 launched child process 6200 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 3744 -prefsLen 26742 -prefMapHandle 3748 -prefMapSize 283412 -jsInitHandle 3752 -jsInitLen 314440 -parentBuildID 20250126212632 -ipcHandle 2760 -initialChannelId {43fa6a5f-af1b-4f77-8549-39f55dfb64fe} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -win32kLockedDown -appDir "D:\task_173798001928528\build\application\firefox\browser" - 5 tab)
[task 2025-01-27T12:26:56.301Z] 12:26:56     INFO - ==> process 9896 launched child process 5320 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -parentBuildID 20250126212632 -sandboxingKind 0 -prefsHandle 3160 -prefsLen 38466 -prefMapHandle 4052 -prefMapSize 283412 -ipcHandle 4396 -initialChannelId {b59d32c4-4e0f-4fd8-aacd-65ad7610df55} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -win32kLockedDown -appDir "D:\task_173798001928528\build\application\firefox\browser" - 6 utility)
[task 2025-01-27T12:26:56.302Z] 12:26:56     INFO - ==> process 9896 launched child process 2332 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4568 -prefsLen 35928 -prefMapHandle 4572 -prefMapSize 283412 -jsInitHandle 4576 -jsInitLen 314440 -parentBuildID 20250126212632 -ipcHandle 4584 -initialChannelId {9ac2abd1-c49c-4d0c-93ed-9cade3f21543} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -win32kLockedDown -appDir "D:\task_173798001928528\build\application\firefox\browser" - 7 tab)
[task 2025-01-27T12:26:56.303Z] 12:26:56     INFO - ==> process 9896 launched child process 7812 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4600 -prefsLen 35928 -prefMapHandle 4604 -prefMapSize 283412 -jsInitHandle 4608 -jsInitLen 314440 -parentBuildID 20250126212632 -ipcHandle 4616 -initialChannelId {4b936a3f-8d93-4970-a99b-c8e69bfcb3ec} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -win32kLockedDown -appDir "D:\task_173798001928528\build\application\firefox\browser" - 8 tab)
[task 2025-01-27T12:26:56.304Z] 12:26:56     INFO - ==> process 9896 launched child process 8024 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4660 -prefsLen 35928 -prefMapHandle 4664 -prefMapSize 283412 -jsInitHandle 4668 -jsInitLen 314440 -parentBuildID 20250126212632 -ipcHandle 4676 -initialChannelId {00fdaf5c-b0e6-4058-8581-33ab1b17149c} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -win32kLockedDown -appDir "D:\task_173798001928528\build\application\firefox\browser" - 9 tab)
[task 2025-01-27T12:26:56.305Z] 12:26:56     INFO - ==> process 9896 launched child process 6556 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 4600 -prefsLen 38518 -prefMapHandle 4584 -prefMapSize 283412 -jsInitHandle 4576 -jsInitLen 314440 -parentBuildID 20250126212632 -ipcHandle 4568 -initialChannelId {dad328af-1baa-4543-ad08-9506da2b7ce7} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -win32kLockedDown -appDir "D:\task_173798001928528\build\application\firefox\browser" - 10 tab)
[task 2025-01-27T12:26:56.306Z] 12:26:56     INFO - ==> process 9896 launched child process 5380 ("D:\task_173798001928528\build\application\firefox\firefox.exe" -contentproc -isForBrowser -prefsHandle 1368 -prefsLen 35980 -prefMapHandle 2612 -prefMapSize 283412 -jsInitHandle 3908 -jsInitLen 314440 -parentBuildID 20250126212632 -ipcHandle 3668 -initialChannelId {cfa158dc-54e2-42a7-9c62-606e16c565ed} -parentPid 9896 -crashReporter "\\.\pipe\gecko-crash-server-pipe.9896" -win32kLockedDown -appDir "D:\task_173798001928528\build\application\firefox\browser" - 11 tab)
[task 2025-01-27T12:26:56.307Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 3332
[task 2025-01-27T12:26:56.307Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 7812
[task 2025-01-27T12:26:56.307Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 5380
[task 2025-01-27T12:26:56.308Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 5320
[task 2025-01-27T12:26:56.308Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 4556
[task 2025-01-27T12:26:56.309Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 1100
[task 2025-01-27T12:26:56.309Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 6960
[task 2025-01-27T12:26:56.310Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 8024
[task 2025-01-27T12:26:56.310Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 6200
[task 2025-01-27T12:26:56.311Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 6556
[task 2025-01-27T12:26:56.311Z] 12:26:56     INFO - zombiecheck | Checking for orphan process with PID: 2332
[task 2025-01-27T12:26:56.312Z] 12:26:56     INFO - Stopping web server
[task 2025-01-27T12:26:56.312Z] 12:26:56     INFO - Server shut down.
[task 2025-01-27T12:26:56.313Z] 12:26:56     INFO - Web server killed.
[task 2025-01-27T12:26:56.313Z] 12:26:56     INFO - Stopping web socket server
[task 2025-01-27T12:26:56.313Z] 12:26:56     INFO - Stopping ssltunnel
[task 2025-01-27T12:26:56.315Z] 12:26:56     INFO - leakcheck | Processing log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks.log for scope widget/tests/mochitest.toml
[task 2025-01-27T12:26:56.315Z] 12:26:56     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2025-01-27T12:26:56.315Z] 12:26:56     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 1000 bytes
[task 2025-01-27T12:26:56.316Z] 12:26:56     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2025-01-27T12:26:56.316Z] 12:26:56     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2025-01-27T12:26:56.317Z] 12:26:56     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2025-01-27T12:26:56.317Z] 12:26:56     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2025-01-27T12:26:56.318Z] 12:26:56     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2025-01-27T12:26:56.318Z] 12:26:56     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2025-01-27T12:26:56.319Z] 12:26:56     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2025-01-27T12:26:56.319Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks.log
[task 2025-01-27T12:26:56.320Z] 12:26:56     INFO - TEST-PASS | leakcheck | default no leaks detected!
[task 2025-01-27T12:26:56.321Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_gpu_pid4556.log
[task 2025-01-27T12:26:56.322Z] 12:26:56     INFO - TEST-PASS | leakcheck | gpu no leaks detected!
[task 2025-01-27T12:26:56.323Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_rdd_pid1100.log
[task 2025-01-27T12:26:56.323Z] 12:26:56     INFO - TEST-PASS | leakcheck | rdd no leaks detected!
[task 2025-01-27T12:26:56.324Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_tab_pid2332.log
[task 2025-01-27T12:26:56.325Z] 12:26:56     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-27T12:26:56.328Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_tab_pid3332.log
[task 2025-01-27T12:26:56.329Z] 12:26:56     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-27T12:26:56.331Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_tab_pid5380.log
[task 2025-01-27T12:26:56.332Z] 12:26:56     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-27T12:26:56.332Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_tab_pid6200.log
[task 2025-01-27T12:26:56.334Z] 12:26:56     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-27T12:26:56.335Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_tab_pid6556.log
[task 2025-01-27T12:26:56.336Z] 12:26:56     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-27T12:26:56.338Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_tab_pid6960.log
[task 2025-01-27T12:26:56.339Z] 12:26:56     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-27T12:26:56.341Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_tab_pid7812.log
[task 2025-01-27T12:26:56.342Z] 12:26:56     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-27T12:26:56.342Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_tab_pid8024.log
[task 2025-01-27T12:26:56.344Z] 12:26:56     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2025-01-27T12:26:56.345Z] 12:26:56     INFO - leakcheck | Processing leak log file C:\Users\task_173798001928528\AppData\Local\Temp\tmp6yexm0rn.mozrunner\runtests_leaks_utility_pid5320.log
[task 2025-01-27T12:26:56.345Z] 12:26:56     INFO - TEST-PASS | leakcheck | utility no leaks detected!
[task 2025-01-27T12:26:56.345Z] 12:26:56     INFO - runtests.py | Running tests: end.
[task 2025-01-27T12:26:56.396Z] 12:26:56     INFO - Buffered messages finished
[task 2025-01-27T12:26:56.411Z] 12:26:56     INFO -  0 INFO TEST-START | Shutdown
[task 2025-01-27T12:26:56.411Z] 12:26:56     INFO -  1 INFO Passed:  29367
[task 2025-01-27T12:26:56.411Z] 12:26:56     INFO -  2 INFO Failed:  0
[task 2025-01-27T12:26:56.412Z] 12:26:56     INFO -  3 INFO Todo:    106
[task 2025-01-27T12:26:56.412Z] 12:26:56     INFO -  4 INFO Mode:    e10s
[task 2025-01-27T12:26:56.412Z] 12:26:56     INFO -  5 INFO SimpleTest FINISHED
[task 2025-01-27T12:26:56.413Z] 12:26:56     INFO - Buffered messages finished
[task 2025-01-27T12:26:56.413Z] 12:26:56     INFO - SUITE-END | took 84s
[task 2025-01-27T12:26:56.440Z] 12:26:56     INFO - Return code: 0
[task 2025-01-27T12:26:56.453Z] 12:26:56     INFO - TinderboxPrint: mochitest-mochitest-plain<br/>137/0/0
[task 2025-01-27T12:26:56.453Z] 12:26:56     INFO - The mochitest suite: mochitest-plain ran with return status: SUCCESS
[task 2025-01-27T12:26:56.453Z] 12:26:56     INFO - Running post-action listener: _package_coverage_data
[task 2025-01-27T12:26:56.453Z] 12:26:56     INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-27T12:26:56.453Z] 12:26:56     INFO - Running post-action listener: process_java_coverage_data
[task 2025-01-27T12:26:56.453Z] 12:26:56     INFO - [mozharness: 2025-01-27 12:26:56.453566Z] Finished run-tests step (success)
[task 2025-01-27T12:26:56.453Z] 12:26:56     INFO - [mozharness: 2025-01-27 12:26:56.453566Z] Running uninstall step.
[task 2025-01-27T12:26:56.453Z] 12:26:56     INFO - Running pre-action listener: _resource_record_pre_action
[task 2025-01-27T12:26:56.453Z] 12:26:56     INFO - Running main action method: uninstall
[task 2025-01-27T12:26:56.454Z] 12:26:56     INFO - Skipping uninstall for non-MSIX test
[task 2025-01-27T12:26:56.454Z] 12:26:56     INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-27T12:26:56.454Z] 12:26:56     INFO - [mozharness: 2025-01-27 12:26:56.454105Z] Finished uninstall step (success)
[task 2025-01-27T12:26:56.454Z] 12:26:56     INFO - Running post-run listener: _resource_record_post_run
[task 2025-01-27T12:26:56.637Z] 12:26:56     INFO - Validating Perfherder data against D:\task_173798001928528\mozharness\external_tools\performance-artifact-schema.json
[task 2025-01-27T12:26:56.647Z] 12:26:56     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-plain.overall", "extraOptions": ["e10s", "taskcluster-Standard_F8s_v2"], "subtests": [{"name": "cpu_percent", "value": 63.52241001945524}, {"name": "io_write_bytes", "value": 628195328}, {"name": "io.read_bytes", "value": 110176256}, {"name": "io_write_time", "value": 1}, {"name": "io_read_time", "value": 8}]}, {"name": "mochitest.mochitest-plain.start-pulseaudio", "subtests": [{"name": "time", "value": 0.015000000000043201}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-plain.install", "subtests": [{"name": "time", "value": 4.109999999999957}, {"name": "cpu_percent", "value": 50.776041666666664}]}, {"name": "mochitest.mochitest-plain.stage-files", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-plain.run-tests", "subtests": [{"name": "time", "value": 99.156}, {"name": "cpu_percent", "value": 63.98497983870967}]}, {"name": "mochitest.mochitest-plain.uninstall", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}]}
[task 2025-01-27T12:26:56.647Z] 12:26:56     INFO - Total resource usage - Wall time: 103s; CPU: Can't collect data; Read bytes: 110176256; Write bytes: 628195328; Read time: 8; Write time: 1
[task 2025-01-27T12:26:56.647Z] 12:26:56     INFO - TinderboxPrint: I/O read bytes / time<br/>110,176,256 / 8
[task 2025-01-27T12:26:56.647Z] 12:26:56     INFO - TinderboxPrint: I/O write bytes / time<br/>628,195,328 / 1
[task 2025-01-27T12:26:56.647Z] 12:26:56     INFO - TinderboxPrint: CPU idle<br/>299.7 (36.4%)
[task 2025-01-27T12:26:56.647Z] 12:26:56     INFO - TinderboxPrint: CPU system<br/>126.9 (15.4%)
[task 2025-01-27T12:26:56.647Z] 12:26:56     INFO - TinderboxPrint: CPU user<br/>395.4 (48.1%)
[task 2025-01-27T12:26:56.648Z] 12:26:56     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-27T12:26:56.649Z] 12:26:56     INFO - install - Wall time: 4s; CPU: 51%; Read bytes: 155648; Write bytes: 73760768; Read time: 0; Write time: 0
[task 2025-01-27T12:26:56.649Z] 12:26:56     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-27T12:26:56.661Z] 12:26:56     INFO - run-tests - Wall time: 99s; CPU: 64%; Read bytes: 110020608; Write bytes: 554434560; Read time: 8; Write time: 1
[task 2025-01-27T12:26:56.662Z] 12:26:56     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[taskcluster 2025-01-27T12:26:57.132Z]    Exit Code: 0
[taskcluster 2025-01-27T12:26:57.132Z]    User Time: 0s
[taskcluster 2025-01-27T12:26:57.132Z]  Kernel Time: 31.25ms
[taskcluster 2025-01-27T12:26:57.132Z]    Wall Time: 4m36.4350647s
[taskcluster 2025-01-27T12:26:57.132Z]       Result: SUCCEEDED
[taskcluster 2025-01-27T12:26:57.132Z] === Task Finished ===
[taskcluster 2025-01-27T12:26:57.132Z] Task Duration: 4m36.4546758s
[taskcluster 2025-01-27T12:26:57.868Z] Uploading artifact public/test_info/system-info.log from file D:\task_173798001928528\build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2025-02-26T12:05:09.053Z
[taskcluster 2025-01-27T12:26:57.884Z] Uploading artifact public/test_info/resource-usage.json from file D:\task_173798001928528\build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-02-26T12:05:09.053Z
[taskcluster 2025-01-27T12:26:57.906Z] Uploading artifact public/test_info/mochitest-plain_errorsummary.log from file D:\task_173798001928528\build\blobber_upload_dir\mochitest-plain_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2025-02-26T12:05:09.053Z
[taskcluster 2025-01-27T12:26:57.942Z] Uploading artifact public/test_info/profile_resource-usage.json from file D:\task_173798001928528\build\blobber_upload_dir\profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-02-26T12:05:09.053Z
[taskcluster 2025-01-27T12:26:57.958Z] Uploading artifact public/logs/localconfig.json from file D:\task_173798001928528\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2025-02-26T12:05:09.053Z
[taskcluster 2025-01-27T12:26:57.967Z] Uploading artifact public/test_info/manifests.list from file D:\task_173798001928528\build\blobber_upload_dir\manifests.list with content encoding "gzip", mime type "application/octet-stream" and expiry 2025-02-26T12:05:09.053Z
[taskcluster 2025-01-27T12:26:58.619Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2025-02-26T12:05:09.053Z