Closed Bug 1717341 Opened 3 years ago Closed 3 years ago

Intermittent Assertion failure: map->asLinked()->canSkipMarkingTable(), at /builds/worker/checkouts/gecko/js/src/gc/Marking.cpp:1569

Categories

(Core :: JavaScript: GC, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1717405

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: assertion, crash, intermittent-failure)

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


[task 2021-06-20T10:09:02.387Z] 10:09:02     INFO - TEST-START | devtools/server/socket/tests/chrome/test_websocket-server.html
[task 2021-06-20T10:09:03.396Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/chrome/nsChromeRegistry.cpp:180
[task 2021-06-20T10:09:03.396Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/security/nsCSPService.cpp:191
[task 2021-06-20T10:09:03.409Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:03.541Z] 10:09:03     INFO - GECKO(4872) | MEMORY STAT | vsize 1032MB | vsizeMaxContiguous 1293MB | residentFast 287MB | heapAllocated 124MB
[task 2021-06-20T10:09:03.558Z] 10:09:03     INFO - TEST-OK | devtools/server/socket/tests/chrome/test_websocket-server.html | took 1170ms
[task 2021-06-20T10:09:03.567Z] 10:09:03     INFO - TEST-START | Shutdown
[task 2021-06-20T10:09:03.567Z] 10:09:03     INFO - Passed:  3
[task 2021-06-20T10:09:03.567Z] 10:09:03     INFO - Failed:  0
[task 2021-06-20T10:09:03.568Z] 10:09:03     INFO - Todo:    0
[task 2021-06-20T10:09:03.568Z] 10:09:03     INFO - Mode:    non-e10s
[task 2021-06-20T10:09:03.569Z] 10:09:03     INFO - Slowest: 1170ms - chrome://mochitests/content/chrome/devtools/server/socket/tests/chrome/test_websocket-server.html
[task 2021-06-20T10:09:03.569Z] 10:09:03     INFO - SimpleTest FINISHED
[task 2021-06-20T10:09:03.569Z] 10:09:03     INFO - TEST-INFO | Ran 1 Loops
[task 2021-06-20T10:09:03.570Z] 10:09:03     INFO - SimpleTest FINISHED
[task 2021-06-20T10:09:03.706Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:184
[task 2021-06-20T10:09:03.712Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:184
[task 2021-06-20T10:09:03.718Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:184
[task 2021-06-20T10:09:03.722Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:184
[task 2021-06-20T10:09:03.740Z] 10:09:03     INFO - GECKO(4872) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: NotFoundError: No such JSWindowActor 'SpecialPowers'
[task 2021-06-20T10:09:03.750Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-06-20T10:09:03.750Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-06-20T10:09:03.753Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-06-20T10:09:03.758Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-06-20T10:09:03.761Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-06-20T10:09:03.761Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-06-20T10:09:03.792Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:184
[task 2021-06-20T10:09:03.803Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:184
[task 2021-06-20T10:09:03.818Z] 10:09:03     INFO - GECKO(4872) | JavaScript error: resource:///modules/Interactions.jsm, line 347: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2021-06-20T10:09:03.834Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-06-20T10:09:03.835Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-06-20T10:09:03.836Z] 10:09:03     INFO - GECKO(4872) | [Parent 6772, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-06-20T10:09:03.991Z] 10:09:03     INFO - GECKO(4872) | [2021-06-20T10:09:03Z WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2021-06-20T10:09:03.991Z] 10:09:03     INFO - GECKO(4872) | [2021-06-20T10:09:03Z WARN  xulstore::persist] tried to remove key that isn't in the store[task 2021-06-20T10:09:04.186Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.187Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.187Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.188Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.189Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.190Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.191Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.192Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.192Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.193Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.193Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.194Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.195Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.195Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: idb: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.196Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: cache: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.196Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: sdb: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.196Z] 10:09:04     INFO - GECKO(4872) | [Parent 6772, IPDL Background] WARNING: ls: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3691
[task 2021-06-20T10:09:04.347Z] 10:09:04     INFO - GECKO(4872) | 1624183744347	Marionette	TRACE	Received observer notification xpcom-will-shutdown
[task 2021-06-20T10:09:04.348Z] 10:09:04     INFO - GECKO(4872) | 1624183744347	Marionette	INFO	Stopped listening on port 2828
[task 2021-06-20T10:09:04.348Z] 10:09:04     INFO - GECKO(4872) | 1624183744348	Marionette	DEBUG	Marionette stopped listening
[task 2021-06-20T10:09:04.411Z] 10:09:04     INFO - GECKO(4872) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-20T10:09:05.353Z] 10:09:05     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-06-20T10:09:05.389Z] 10:09:05     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:3404
[task 2021-06-20T10:09:05.390Z] 10:09:05     INFO - GECKO(4872) | [Parent 6772, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-06-20T10:09:05.423Z] 10:09:05     INFO - TEST-INFO | Main app process: exit 0
[task 2021-06-20T10:09:05.423Z] 10:09:05     INFO - runtests.py | Application ran for: 0:00:08.044074
[task 2021-06-20T10:09:05.424Z] 10:09:05     INFO - zombiecheck | Reading PID log: C:\Users\task_1624183372\AppData\Local\Temp\tmp817w3jegpidlog
[task 2021-06-20T10:09:05.424Z] 10:09:05     INFO - Stopping web server
[task 2021-06-20T10:09:05.430Z] 10:09:05     INFO - Server shut down.
[task 2021-06-20T10:09:05.438Z] 10:09:05     INFO - Web server killed.
[task 2021-06-20T10:09:05.438Z] 10:09:05     INFO - Stopping web socket server
[task 2021-06-20T10:09:05.468Z] 10:09:05     INFO - Stopping ssltunnel
[task 2021-06-20T10:09:05.499Z] 10:09:05     INFO - leakcheck | Processing log file C:\Users\task_1624183372\AppData\Local\Temp\tmpo46nzuy5.mozrunner\runtests_leaks.log
[task 2021-06-20T10:09:05.500Z] 10:09:05     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2021-06-20T10:09:05.500Z] 10:09:05     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2021-06-20T10:09:05.500Z] 10:09:05     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2021-06-20T10:09:05.501Z] 10:09:05     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2021-06-20T10:09:05.501Z] 10:09:05     INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2021-06-20T10:09:05.501Z] 10:09:05     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes[task 2021-06-20T10:09:05.501Z] 10:09:05     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2021-06-20T10:09:05.502Z] 10:09:05     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 1000 bytes
[task 2021-06-20T10:09:05.502Z] 10:09:05     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2021-06-20T10:09:05.502Z] 10:09:05     INFO - leakcheck | Processing leak log file C:\Users\task_1624183372\AppData\Local\Temp\tmpo46nzuy5.mozrunner\runtests_leaks.log
[task 2021-06-20T10:09:05.503Z] 10:09:05     INFO - 
[task 2021-06-20T10:09:05.503Z] 10:09:05     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 6772
[task 2021-06-20T10:09:05.503Z] 10:09:05     INFO - 
[task 2021-06-20T10:09:05.503Z] 10:09:05     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-06-20T10:09:05.504Z] 10:09:05     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-06-20T10:09:05.504Z] 10:09:05     INFO -    0 |TOTAL                                 |       38        0| 1120016        0|
[task 2021-06-20T10:09:05.506Z] 10:09:05     INFO - 
[task 2021-06-20T10:09:05.506Z] 10:09:05     INFO - nsTraceRefcnt::DumpStatistics: 2022 entries
[task 2021-06-20T10:09:05.507Z] 10:09:05     INFO - TEST-PASS | leakcheck | default no leaks detected!
[task 2021-06-20T10:09:05.507Z] 10:09:05     INFO - runtests.py | Running tests: end.
[task 2021-06-20T10:09:05.550Z] 10:09:05     INFO - Buffered messages finished
[task 2021-06-20T10:09:05.551Z] 10:09:05     INFO - Running manifest: devtools\shared\heapsnapshot\tests\chrome\chrome.ini
[task 2021-06-20T10:09:05.866Z] 10:09:05     INFO - PID 8652 | Z:\task_1624183372\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2021-06-20T10:09:05.936Z] 10:09:05     INFO - MochitestServer : launching ['Z:\\task_1624183372\\build\\tests\\bin\\xpcshell.exe', '-g', 'Z:\\task_1624183372\\build\\application\\firefox', '-f', 'Z:\\task_1624183372\\build\\tests\\bin\\components\\httpd.js', '-e', "const _PROFILE_PATH = 'C:\\\\Users\\\\task_1624183372\\\\AppData\\\\Local\\\\Temp\\\\tmpwyf5ae7t.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', 'Z:\\task_1624183372\\build\\tests\\mochitest\\server.js']
[task 2021-06-20T10:09:05.936Z] 10:09:05     INFO - runtests.py | Server pid: 1512
[task 2021-06-20T10:09:05.937Z] 10:09:05     INFO - runtests.py | Websocket server pid: 4544
[task 2021-06-20T10:09:05.940Z] 10:09:05     INFO - runtests.py | SSL tunnel pid: 10424
[task 2021-06-20T10:09:06.019Z] 10:09:06     INFO -  Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-06-20T10:09:06.026Z] 10:09:06     INFO -  [Parent 1512, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-06-20T10:09:06.026Z] 10:09:06     INFO -  [Parent 1512, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-06-20T10:09:06.027Z] 10:09:06     INFO -  [Parent 1512, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-06-20T10:09:06.028Z] 10:09:06     INFO -  [Parent 1512, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-06-20T10:09:06.064Z] 10:09:06     INFO -  [Parent 1512, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:377
[task 2021-06-20T10:09:06.092Z] 10:09:06     INFO - runtests.py | Running with scheme: http
[task 2021-06-20T10:09:06.092Z] 10:09:06     INFO - runtests.py | Running with e10s: False
[task 2021-06-20T10:09:06.092Z] 10:09:06     INFO - runtests.py | Running with fission: False
[task 2021-06-20T10:09:06.093Z] 10:09:06     INFO - runtests.py | Running with cross-origin iframes: False
[task 2021-06-20T10:09:06.093Z] 10:09:06     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2021-06-20T10:09:06.093Z] 10:09:06     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2021-06-20T10:09:06.094Z] 10:09:06     INFO - runtests.py | Running tests: start.
[task 2021-06-20T10:09:06.094Z] 10:09:06     INFO - 
[task 2021-06-20T10:09:06.189Z] 10:09:06     INFO - Application command: Z:\task_1624183372\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile C:\Users\task_1624183372\AppData\Local\Temp\tmpwyf5ae7t.mozrunner
[task 2021-06-20T10:09:06.191Z] 10:09:06     INFO - runtests.py | Application pid: 7092
[task 2021-06-20T10:09:06.192Z] 10:09:06     INFO - TEST-INFO | started process GECKO(7092)
[task 2021-06-20T10:09:06.243Z] 10:09:06     INFO - GECKO(7092) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_1624183372\AppData\Local\Temp\tmpwyf5ae7t.mozrunner\runtests_leaks.log
[task 2021-06-20T10:09:06.244Z] 10:09:06     INFO - GECKO(7092) | [6836, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:247
[task 2021-06-20T10:09:06.823Z] 10:09:06     INFO - GECKO(7092) | [2021-06-20T10:09:06Z WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2021-06-20T10:09:07.065Z] 10:09:07     INFO - GECKO(7092) | 1624183747065	Marionette	INFO	Marionette enabled[task 2021-06-20T10:09:07.172Z] 10:09:07     INFO - GECKO(7092) | 1624183747172	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-06-20T10:09:07.211Z] 10:09:07     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4248
[task 2021-06-20T10:09:07.215Z] 10:09:07     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4248
[task 2021-06-20T10:09:07.357Z] 10:09:07     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:07.738Z] 10:09:07     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4246
[task 2021-06-20T10:09:07.765Z] 10:09:07     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:08.061Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4246
[task 2021-06-20T10:09:08.077Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4248
[task 2021-06-20T10:09:08.079Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4248
[task 2021-06-20T10:09:08.081Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4248
[task 2021-06-20T10:09:08.082Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4248
[task 2021-06-20T10:09:08.085Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4248
[task 2021-06-20T10:09:08.166Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:347
[task 2021-06-20T10:09:08.167Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:347
[task 2021-06-20T10:09:08.179Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:08.184Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:08.190Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:08.196Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:08.201Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:08.252Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, MediaSupervisor #1] WARNING: Checking version is only allowed only from the content process.: file /builds/worker/checkouts/gecko/dom/media/mediacapabilities/DecoderBenchmark.cpp:215
[task 2021-06-20T10:09:08.390Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3352
[task 2021-06-20T10:09:08.391Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3352
[task 2021-06-20T10:09:08.391Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3352
[task 2021-06-20T10:09:08.392Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3352
[task 2021-06-20T10:09:08.512Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1590
[task 2021-06-20T10:09:08.528Z] 10:09:08     INFO - GECKO(7092) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_1624183372\\AppData\\Local\\Temp\\tmpwyf5ae7t.mozrunner\\search.json.mozlz4", (void 0)))
[task 2021-06-20T10:09:08.566Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4248
[task 2021-06-20T10:09:08.700Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:184
[task 2021-06-20T10:09:08.789Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:08.907Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:184
[task 2021-06-20T10:09:08.967Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-06-20T10:09:08.968Z] 10:09:08     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-06-20T10:09:09.673Z] 10:09:09     INFO - GECKO(7092) | [Parent 6836, 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 2021-06-20T10:09:09.674Z] 10:09:09     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:10.019Z] 10:09:10     INFO - GECKO(7092) | 1624183750019	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2021-06-20T10:09:10.020Z] 10:09:10     INFO - GECKO(7092) | 1624183750019	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2021-06-20T10:09:10.039Z] 10:09:10     INFO - GECKO(7092) | 1624183750039	Marionette	TRACE	All scripts recorded.
[task 2021-06-20T10:09:10.042Z] 10:09:10     INFO - GECKO(7092) | 1624183750041	Marionette	INFO	Listening on port 2828
[task 2021-06-20T10:09:10.042Z] 10:09:10     INFO - GECKO(7092) | 1624183750042	Marionette	DEBUG	Marionette is listening[task 2021-06-20T10:09:10.133Z] 10:09:10     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:10.420Z] 10:09:10     INFO - GECKO(7092) | 1624183750420	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:50158
[task 2021-06-20T10:09:10.427Z] 10:09:10     INFO - GECKO(7092) | 1624183750427	Marionette	DEBUG	Closed connection 0
[task 2021-06-20T10:09:10.428Z] 10:09:10     INFO - GECKO(7092) | 1624183750428	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:50159
[task 2021-06-20T10:09:10.454Z] 10:09:10     INFO - GECKO(7092) | 1624183750454	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-06-20T10:09:10.474Z] 10:09:10     INFO - GECKO(7092) | 1624183750473	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"72413d82-f735-49cb-b7a1-b4f15acc25ee","capabilities":{"browserName":"firefox","browserVersion":"91.0a ... t.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-06-20T10:09:10.496Z] 10:09:10     INFO - GECKO(7092) | 1624183750496	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"C:\\Users\\task_1624183372\\AppData\\Local\\Temp\\tmp2wozj6el.zip","temporary":false}]
[task 2021-06-20T10:09:10.585Z] 10:09:10     INFO - GECKO(7092) | 1624183750585	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-06-20T10:09:10.611Z] 10:09:10     INFO - GECKO(7092) | 1624183750611	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"C:\\Users\\task_1624183372\\AppData\\Local\\Temp\\tmp7ip7wfzm.zip","temporary":false}]
[task 2021-06-20T10:09:10.652Z] 10:09:10     INFO - GECKO(7092) | 1624183750652	Marionette	DEBUG	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2021-06-20T10:09:10.657Z] 10:09:10     INFO - GECKO(7092) | 1624183750657	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
[task 2021-06-20T10:09:10.657Z] 10:09:10     INFO - GECKO(7092) | 1624183750658	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
[task 2021-06-20T10:09:10.659Z] 10:09:10     INFO - GECKO(7092) | 1624183750660	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-06-20T10:09:10.660Z] 10:09:10     INFO - GECKO(7092) | 1624183750660	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2021-06-20T10:09:10.662Z] 10:09:10     INFO - GECKO(7092) | 1624183750662	Marionette	DEBUG	1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... }],"newSandbox":true,"sandbox":"default","line":1935,"filename":"Z:\\task_1624183372\\build\\tests\\mochitest\\runtests.py"}]
[task 2021-06-20T10:09:10.669Z] 10:09:10     INFO - GECKO(7092) | 1624183750669	Marionette	TRACE	[9] MarionetteCommands actor created for window id 4
[task 2021-06-20T10:09:10.673Z] 10:09:10     INFO - GECKO(7092) | 1624183750673	Marionette	TRACE	[22] MarionetteEvents actor created for window id 31
[task 2021-06-20T10:09:10.714Z] 10:09:10     INFO - GECKO(7092) | 1624183750714	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2021-06-20T10:09:10.728Z] 10:09:10     INFO - GECKO(7092) | 1624183750728	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2021-06-20T10:09:10.728Z] 10:09:10     INFO - GECKO(7092) | 1624183750728	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2021-06-20T10:09:10.746Z] 10:09:10     INFO - GECKO(7092) | 1624183750746	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2021-06-20T10:09:10.747Z] 10:09:10     INFO - GECKO(7092) | 1624183750748	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2021-06-20T10:09:10.756Z] 10:09:10     INFO - runtests.py | Waiting for browser...
[task 2021-06-20T10:09:10.758Z] 10:09:10     INFO - GECKO(7092) | 1624183750758	Marionette	DEBUG	Closed connection 1
[task 2021-06-20T10:09:10.808Z] 10:09:10     INFO - GECKO(7092) | [Parent 6836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1449
[task 2021-06-20T10:09:10.878Z] 10:09:10     INFO - GECKO(7092) | Assertion failure: map->asLinked()->canSkipMarkingTable(), at /builds/worker/checkouts/gecko/js/src/gc/Marking.cpp:1569

[task 2021-06-20T10:09:11.011Z] 10:09:11 INFO - Initializing stack-fixing for the first stack frame, this may take a while...
[task 2021-06-20T10:09:20.138Z] 10:09:20 INFO - GECKO(7092) | #01: js::GCMarker::eagerlyMarkChildren(js::PropMap*) [js/src/gc/Marking.cpp:1569]
[task 2021-06-20T10:09:20.139Z] 10:09:20 INFO - GECKO(7092) | #02: JS::MapGCThingTyped<lambda at /builds/worker/checkouts/gecko/js/src/gc/Marking.cpp:4206:27' &>(JS::GCCellPtr, js::GCMarker::traceBarrieredCell::<unnamed-tag>&) [js/public/HeapAPI.h:459] [task 2021-06-20T10:09:20.139Z] 10:09:20 INFO - GECKO(7092) | #03: js::GCMarker::traceBarrieredCell(JS::GCCellPtr) [js/src/gc/Marking.cpp:4206] [task 2021-06-20T10:09:20.139Z] 10:09:20 INFO - GECKO(7092) | #04: js::gc::BarrierTracer::performBarrier(JS::GCCellPtr) [js/src/gc/Marking.cpp:4170] [task 2021-06-20T10:09:20.140Z] 10:09:20 INFO - GECKO(7092) | #05: js::gc::PerformIncrementalBarrier(js::gc::TenuredCell*) [js/src/gc/Marking.cpp:993] [task 2021-06-20T10:09:20.140Z] 10:09:20 INFO - GECKO(7092) | #06: static js::SharedPropMap::addPropertyInternal(JSContext*, JS::MutableHandle<js::SharedPropMap *>, unsigned int*, JS::Handle<JS::PropertyKey>, js::PropertyInfoBase<unsigned int>) [js/src/vm/PropMap.cpp:434] [task 2021-06-20T10:09:20.141Z] 10:09:20 INFO - GECKO(7092) | #07: static js::SharedPropMap::addProperty(JSContext*, JSClass const*, JS::MutableHandle<js::SharedPropMap *>, unsigned int*, JS::Handle<JS::PropertyKey>, js::PropertyFlags, js::EnumFlags<js::ObjectFlag>*, unsigned int*) [js/src/vm/PropMap.cpp:277] [task 2021-06-20T10:09:20.141Z] 10:09:20 INFO - GECKO(7092) | #08: static js::NativeObject::addProperty(JSContext*, JS::Handle<js::NativeObject *>, JS::Handle<JS::PropertyKey>, js::PropertyFlags, unsigned int*) [js/src/vm/Shape.cpp:312] [task 2021-06-20T10:09:20.142Z] 10:09:20 INFO - GECKO(7092) | #09: static js::GlobalObject::addIntrinsicValue(JSContext*, JS::Handle<js::GlobalObject *>, JS::Handle<js::PropertyName *>, JS::Handle<JS::Value>) [js/src/vm/GlobalObject.cpp:1067] [task 2021-06-20T10:09:20.142Z] 10:09:20 INFO - GECKO(7092) | #10: static js::GlobalObject::getIntrinsicValueSlow(JSContext*, JS::Handle<js::GlobalObject *>, JS::Handle<js::PropertyName *>, JS::MutableHandle<JS::Value>) [js/src/vm/GlobalObject.cpp:1048] [task 2021-06-20T10:09:20.143Z] 10:09:20 INFO - GECKO(7092) | #11: js::GetIntrinsicOperation(JSContext*, JS::Handle<JSScript *>, unsigned char*, JS::MutableHandle<JS::Value>) [js/src/vm/Interpreter-inl.h:226] [task 2021-06-20T10:09:20.143Z] 10:09:20 INFO - GECKO(7092) | #12: Interpret(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:3379] [task 2021-06-20T10:09:20.143Z] 10:09:20 INFO - GECKO(7092) | #13: js::RunScript(JSContext*, js::RunState&) [js/src/vm/Interpreter.cpp:373] [task 2021-06-20T10:09:20.144Z] 10:09:20 INFO - GECKO(7092) | #14: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [js/src/vm/Interpreter.cpp:543] [task 2021-06-20T10:09:20.144Z] 10:09:20 INFO - GECKO(7092) | #15: InternalCall(JSContext*, js::AnyInvokeArgs const&, js::CallReason) [js/src/vm/Interpreter.cpp:571] [task 2021-06-20T10:09:20.145Z] 10:09:20 INFO - GECKO(7092) | #16: js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) [js/src/vm/Interpreter.cpp:588] [task 2021-06-20T10:09:20.145Z] 10:09:20 INFO - GECKO(7092) | #17: JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) [js/src/jsapi.cpp:2785] [task 2021-06-20T10:09:20.146Z] 10:09:20 INFO - GECKO(7092) | #18: mozilla::dom::EventListener::HandleEvent(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::dom::Event&, mozilla::ErrorResult&) [s3:gecko-generated-sources:1f1f4c04fd2c53359cc4b5ca976ce103ff7bf183fcec200e2de41bdd7ab1a754d6f3e9f9d45f1ac62d2abc6dfa86e94634dbfe154939386b86d91f73137bd901/dom/bindings/EventListenerBinding.cpp::58] [task 2021-06-20T10:09:20.146Z] 10:09:20 INFO - GECKO(7092) | #19: mozilla::dom::EventListener::HandleEvent<mozilla::dom::EventTarget *>(mozilla::dom::EventTarget* const&, mozilla::dom::Event&, mozilla::ErrorResult&, char const*, mozilla::dom::CallbackObject::ExceptionHandling, JS::Realm*) [s3:gecko-generated-sources:d1b28e80730968bba8d573d617e88478cf37a577c79520f1e6ec1e2f40c3f06f1f97e6d91053279cbec9c43bbbb1076f11f0cd01a1f517418af436b478c31657/dist/include/mozilla/dom/EventListenerBinding.h::65] [task 2021-06-20T10:09:20.147Z] 10:09:20 INFO - GECKO(7092) | #20: mozilla::EventListenerManager::HandleEventSubType(mozilla::EventListenerManager::Listener*, mozilla::dom::Event*, mozilla::dom::EventTarget*) [dom/events/EventListenerManager.cpp:1110] [task 2021-06-20T10:09:20.147Z] 10:09:20 INFO - GECKO(7092) | #21: mozilla::EventListenerManager::HandleEventInternal(nsPresContext*, mozilla::WidgetEvent*, mozilla::dom::Event**, mozilla::dom::EventTarget*, nsEventStatus*, bool) [dom/events/EventListenerManager.cpp:1307] [task 2021-06-20T10:09:20.148Z] 10:09:20 INFO - GECKO(7092) | #22: mozilla::EventTargetChainItem::HandleEvent(mozilla::EventChainPostVisitor&, mozilla::ELMCreationDetector&) [dom/events/EventDispatcher.cpp:351] [task 2021-06-20T10:09:20.148Z] 10:09:20 INFO - GECKO(7092) | #23: static mozilla::EventTargetChainItem::HandleEventTargetChain(nsTArray<mozilla::EventTargetChainItem>&, mozilla::EventChainPostVisitor&, mozilla::EventDispatchingCallback*, mozilla::ELMCreationDetector&) [dom/events/EventDispatcher.cpp:586] [task 2021-06-20T10:09:20.149Z] 10:09:20 INFO - GECKO(7092) | #24: static mozilla::EventDispatcher::Dispatch(nsISupports*, nsPresContext*, mozilla::WidgetEvent*, mozilla::dom::Event*, nsEventStatus*, mozilla::EventDispatchingCallback*, nsTArray<mozilla::dom::EventTarget *>*) [dom/events/EventDispatcher.cpp:1085] [task 2021-06-20T10:09:20.149Z] 10:09:20 INFO - GECKO(7092) | #25: static mozilla::EventDispatcher::DispatchDOMEvent(nsISupports*, mozilla::WidgetEvent*, mozilla::dom::Event*, nsPresContext*, nsEventStatus*) [dom/events/EventDispatcher.cpp:1202] [task 2021-06-20T10:09:20.149Z] 10:09:20 INFO - GECKO(7092) | #26: nsINode::DispatchEvent(mozilla::dom::Event&, mozilla::dom::CallerType, mozilla::ErrorResult&) [dom/base/nsINode.cpp:1331] [task 2021-06-20T10:09:20.150Z] 10:09:20 INFO - GECKO(7092) | #27: mozilla::dom::EventTarget::DispatchEvent(mozilla::dom::Event&) [dom/events/EventTarget.cpp:177] [task 2021-06-20T10:09:20.150Z] 10:09:20 INFO - GECKO(7092) | #28: mozilla::AsyncEventDispatcher::Run() [dom/events/AsyncEventDispatcher.cpp:71] [task 2021-06-20T10:09:20.150Z] 10:09:20 INFO - GECKO(7092) | #29: static nsContentUtils::AddScriptRunner(already_AddRefed<nsIRunnable>) [dom/base/nsContentUtils.cpp:5761] [task 2021-06-20T10:09:20.151Z] 10:09:20 INFO - GECKO(7092) | #30: static nsContentUtils::AddScriptRunner(nsIRunnable*) [dom/base/nsContentUtils.cpp:5767] [task 2021-06-20T10:09:20.151Z] 10:09:20 INFO - GECKO(7092) | #31: mozilla::AsyncEventDispatcher::RunDOMEventWhenSafe() [dom/events/AsyncEventDispatcher.cpp:99][task 2021-06-20T10:09:20.152Z] 10:09:20 INFO - GECKO(7092) | #32: mozilla::dom::WindowGlobalParent::RecvUpdateDocumentTitle(nsTString<char16_t> const&) [dom/ipc/WindowGlobalParent.cpp:458] [task 2021-06-20T10:09:20.152Z] 10:09:20 INFO - GECKO(7092) | #33: mozilla::dom::PWindowGlobalParent::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:a316b80c3cdc8782f1cbd4ba27522a0edff2e16e6c24bcf8d15a62350e90099b1605869eb34d9a747a4e177610f3a3df920f06e9a5cb7d67645dc24245ad3173/ipc/ipdl/PWindowGlobalParent.cpp::1222] [task 2021-06-20T10:09:20.153Z] 10:09:20 INFO - GECKO(7092) | #34: mozilla::dom::PInProcessParent::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:79ead7cc4f86972566bfeff8182b7f43e843bb773e5f74c59637910b93ddcb6c93e788ec6d3b1e0777359b5cdea3a12998e881bd386fce24f57ff1c5321a8a1a/ipc/ipdl/PInProcessParent.cpp::219] [task 2021-06-20T10:09:20.153Z] 10:09:20 INFO - GECKO(7092) | #35: mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) [ipc/glue/MessageChannel.cpp:2148] [task 2021-06-20T10:09:20.153Z] 10:09:20 INFO - GECKO(7092) | #36: mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) [ipc/glue/MessageChannel.cpp:2075] [task 2021-06-20T10:09:20.154Z] 10:09:20 INFO - GECKO(7092) | #37: mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) [ipc/glue/MessageChannel.cpp:1924] [task 2021-06-20T10:09:20.154Z] 10:09:20 INFO - GECKO(7092) | #38: mozilla::ipc::MessageChannel::MessageTask::Run() [ipc/glue/MessageChannel.cpp:1956] [task 2021-06-20T10:09:20.154Z] 10:09:20 INFO - GECKO(7092) | #39: mozilla::RunnableTask::Run() [xpcom/threads/TaskController.cpp:503] [task 2021-06-20T10:09:20.155Z] 10:09:20 INFO - GECKO(7092) | #40: mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:805] [task 2021-06-20T10:09:20.155Z] 10:09:20 INFO - GECKO(7092) | #41: mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:641] [task 2021-06-20T10:09:20.156Z] 10:09:20 INFO - GECKO(7092) | #42: mozilla::TaskController::ProcessPendingMTTask(bool) [xpcom/threads/TaskController.cpp:425] [task 2021-06-20T10:09:20.156Z] 10:09:20 INFO - GECKO(7092) | #43: mozilla::detail::RunnableFunction<lambda at /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:135:7'>::Run() [xpcom/threads/nsThreadUtils.h:535]
[task 2021-06-20T10:09:20.156Z] 10:09:20 INFO - GECKO(7092) | #44: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1156]
[task 2021-06-20T10:09:20.157Z] 10:09:20 INFO - GECKO(7092) | #45: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:548]
[task 2021-06-20T10:09:20.157Z] 10:09:20 INFO - GECKO(7092) | #46: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:85]
[task 2021-06-20T10:09:20.157Z] 10:09:20 INFO - GECKO(7092) | #47: MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:331]
[task 2021-06-20T10:09:20.158Z] 10:09:20 INFO - GECKO(7092) | #48: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:325]
[task 2021-06-20T10:09:20.158Z] 10:09:20 INFO - GECKO(7092) | #49: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:307]
[task 2021-06-20T10:09:20.158Z] 10:09:20 INFO - GECKO(7092) | #50: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
[task 2021-06-20T10:09:20.159Z] 10:09:20 INFO - GECKO(7092) | #51: nsAppShell::Run() [widget/windows/nsAppShell.cpp:603]
[task 2021-06-20T10:09:20.159Z] 10:09:20 INFO - GECKO(7092) | #52: nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:274]
[task 2021-06-20T10:09:20.159Z] 10:09:20 INFO - GECKO(7092) | #53: XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:5239]
[task 2021-06-20T10:09:20.160Z] 10:09:20 INFO - GECKO(7092) | #54: XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:5437]
[task 2021-06-20T10:09:20.160Z] 10:09:20 INFO - GECKO(7092) | #55: XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/nsAppRunner.cpp:5496]
[task 2021-06-20T10:09:20.160Z] 10:09:20 INFO - GECKO(7092) | #56: mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) [toolkit/xre/Bootstrap.cpp:45]
[task 2021-06-20T10:09:20.204Z] 10:09:20 INFO - GECKO(7092) | #57: NS_internal_main(int, char**, char**) [browser/app/nsBrowserApp.cpp:351]
[task 2021-06-20T10:09:20.205Z] 10:09:20 INFO - GECKO(7092) | #58: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2021-06-20T10:09:20.205Z] 10:09:20 INFO - GECKO(7092) | #59: __scrt_common_main_seh() [/builds/worker/workspace/obj-build/browser/app/f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2021-06-20T10:09:20.208Z] 10:09:20 INFO - fix-stacks: error: failed to read debug info file wkernel32.pdb for C:\Windows\System32\KERNEL32.DLL
[task 2021-06-20T10:09:20.209Z] 10:09:20 INFO - fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-06-20T10:09:20.209Z] 10:09:20 INFO - fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-06-20T10:09:20.209Z] 10:09:20 INFO - GECKO(7092) | #60: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x18484]
[task 2021-06-20T10:09:20.214Z] 10:09:20 INFO - fix-stacks: error: failed to read debug info file wntdll.pdb for C:\Windows\SYSTEM32\ntdll.dll
[task 2021-06-20T10:09:20.214Z] 10:09:20 INFO - fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-06-20T10:09:20.215Z] 10:09:20 INFO - fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-06-20T10:09:20.215Z] 10:09:20 INFO - GECKO(7092) | #61: RtlValidSecurityDescriptor [C:\Windows\SYSTEM32\ntdll.dll + 0x6305a]
[task 2021-06-20T10:09:20.215Z] 10:09:20 INFO - GECKO(7092) | #62: RtlValidSecurityDescriptor [C:\Windows\SYSTEM32\ntdll.dll + 0x6302a]

I can see the same for Marionette tests so that it not DevTools related:
https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=mn&selectedTaskRun=MRs-6boCRBOyyWWsidephA.0

Also there is a GFX crash right after:

https://treeherder.mozilla.org/logviewer?job_id=343393918&repo=autoland&lineNumber=3381-3400

[task 2021-06-21T15:34:31.907Z] 15:34:31     INFO -  [GPU 8024, Compositor] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-06-21T15:34:31.908Z] 15:34:31     INFO -  [GPU 8024, Main Thread] WARNING: Shutting down GPU process early due to a crash!: file /builds/worker/checkouts/gecko/gfx/ipc/GPUParent.cpp:604
[task 2021-06-21T15:34:31.944Z] 15:34:31     INFO -  [GPU 8024
[task 2021-06-21T15:36:27.633Z] 15:36:27     INFO -  mozcrash Copy/paste: Z:/task_1624289376/fetches\minidump_stackwalk\minidump_stackwalk.exe C:\Users\task_1624289376\AppData\Local\Temp\tmpucuvxael.mozrunner\minidumps\7a51fe9f-9321-482e-998b-c79f247e1622.dmp Z:\task_1624289376\build\symbols
[task 2021-06-21T15:36:41.939Z] 15:36:41     INFO -  mozcrash Saved minidump as Z:\task_1624289376\build\blobber_upload_dir\7a51fe9f-9321-482e-998b-c79f247e1622.dmp
[task 2021-06-21T15:36:41.941Z] 15:36:41     INFO -  mozcrash Saved app info as Z:\task_1624289376\build\blobber_upload_dir\7a51fe9f-9321-482e-998b-c79f247e1622.extra
[task 2021-06-21T15:36:41.955Z] 15:36:41    ERROR -  PROCESS-CRASH | testing/marionette/harness/marionette_harness/tests/unit/test_cli_arguments.py TestCommandLineArguments.test_start_in_safe_mode | application crashed [@ js::GCMarker::eagerlyMarkChildren(js::PropMap*)]

[task 2021-06-21T15:36:41.958Z] 15:36:41     INFO -   0  xul.dll!js::GCMarker::eagerlyMarkChildren(js::PropMap*) [Marking.cpp:7d32671931b7c2f5cb24b0f4d62bb6884e484175 : 1569 + 0x0]
[task 2021-06-21T15:36:41.958Z] 15:36:41     INFO -      eip = 0x6566bc2f   esp = 0x009fdecc   ebp = 0x009fdef0   ebx = 0x009fdedc
[task 2021-06-21T15:36:41.958Z] 15:36:41     INFO -      esi = 0x11207d30   edi = 0x28406240   eax = 0x6ab96600   ecx = 0x00000000
[task 2021-06-21T15:36:41.958Z] 15:36:41     INFO -      edx = 0x00000048   efl = 0x00000206
[task 2021-06-21T15:36:41.958Z] 15:36:41     INFO -      Found by: given as instruction pointer in context
[task 2021-06-21T15:36:41.959Z] 15:36:41     INFO -   1  xul.dll!JS::MapGCThingTyped<`lambda at /builds/worker/checkouts/gecko/js/src/gc/Marking.cpp:4206:27' &>(JS::GCCellPtr, js::GCMarker::traceBarrieredCell::<unnamed-tag>&) [HeapAPI.h:7d32671931b7c2f5cb24b0f4d62bb6884e484175 : 459 + 0xa05]
[task 2021-06-21T15:36:41.959Z] 15:36:41     INFO -      eip = 0x656a9e0f   esp = 0x009fdef8   ebp = 0x009fdf18   ebx = 0x11207d30
[task 2021-06-21T15:36:41.959Z] 15:36:41     INFO -      esi = 0x11207d30   edi = 0x28406240
[task 2021-06-21T15:36:41.959Z] 15:36:41     INFO -      Found by: call frame info
[task 2021-06-21T15:36:41.959Z] 15:36:41     INFO -   2  xul.dll!js::GCMarker::traceBarrieredCell(JS::GCCellPtr) [Marking.cpp:7d32671931b7c2f5cb24b0f4d62bb6884e484175 : 4206 + 0xd]
[task 2021-06-21T15:36:41.959Z] 15:36:41     INFO -      eip = 0x65675f57   esp = 0x009fdf20   ebp = 0x009fdf34   ebx = 0x28406240
[task 2021-06-21T15:36:41.960Z] 15:36:41     INFO -      esi = 0x28406247   edi = 0x11207d30
[task 2021-06-21T15:36:41.960Z] 15:36:41     INFO -      Found by: call frame info
[task 2021-06-21T15:36:41.960Z] 15:36:41     INFO -   3  xul.dll!js::gc::BarrierTracer::performBarrier(JS::GCCellPtr) [Marking.cpp:7d32671931b7c2f5cb24b0f4d62bb6884e484175 : 4170 + 0x4a]
[task 2021-06-21T15:36:41.960Z] 15:36:41     INFO -      eip = 0x656690d0   esp = 0x009fdf3c   ebp = 0x009fdfa0   ebx = 0x00000000
[task 2021-06-21T15:36:41.960Z] 15:36:41     INFO -      esi = 0x11207e28   edi = 0x28406247
[task 2021-06-21T15:36:41.961Z] 15:36:41     INFO -      Found by: call frame info
[task 2021-06-21T15:36:41.961Z] 15:36:41     INFO -   4  xul.dll!js::gc::PerformIncrementalBarrier(js::gc::TenuredCell*) [Marking.cpp:7d32671931b7c2f5cb24b0f4d62bb6884e484175 : 992 + 0x8]
[task 2021-06-21T15:36:41.961Z] 15:36:41     INFO -      eip = 0x656692ed   esp = 0x009fdfa8   ebp = 0x009fdfb8   ebx = 0x28406240
[task 2021-06-21T15:36:41.961Z] 15:36:41     INFO -      esi = 0x11207e28   edi = 0x0000000c
[task 2021-06-21T15:36:41.961Z] 15:36:41     INFO -      Found by: call frame info

Andrew, did we had some recent changes for the Garbage collector?

Component: General → JavaScript: GC
Flags: needinfo?(continuation)
Product: DevTools → Core

Jon is a better person to ask about GC issues.

Flags: needinfo?(continuation) → needinfo?(jcoppeard)
Status: NEW → RESOLVED
Closed: 3 years ago
Flags: needinfo?(jcoppeard)
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.