Closed Bug 1711145 Opened 4 years ago Closed 4 years ago

Intermittent Assertion failure: mMagic1 == sMagic && mMagic2 == sMagic, at /builds/worker/workspace/obj-build/dist/include/mozilla/MozPromise.h:529

Categories

(Core :: XPCOM, defect, P5)

Product:
Core
Component:
XPCOM
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: assertion, intermittent-failure)

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=339632747&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bFWlKDW5QbeP8RauNAMLgg/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bFWlKDW5QbeP8RauNAMLgg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2021-05-13T20:55:28.102Z] 20:55:28     INFO - REFTEST TEST-START | image/test/reftest/ico/cur/wrapper.html?pointer.cur == image/test/reftest/ico/cur/wrapper.html?pointer.png
[task 2021-05-13T20:55:28.122Z] 20:55:28     INFO - REFTEST TEST-LOAD | file:///Z:/task_1620936825/build/tests/reftest/tests/image/test/reftest/ico/cur/wrapper.html?pointer.cur | 0 / 1 (0%)
[task 2021-05-13T20:55:28.155Z] 20:55:28     INFO - [Parent 5268, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2929
[task 2021-05-13T20:55:28.191Z] 20:55:28     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-13T20:55:28.196Z] 20:55:28     INFO - ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_1620936825\AppData\Local\Temp\tmpx5wxay6f.mozrunner\runreftest_leaks_tab_pid7448.log
[task 2021-05-13T20:55:28.197Z] 20:55:28     INFO - [7448, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:254
[task 2021-05-13T20:55:28.367Z] 20:55:28     INFO - [Child 8904, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-05-13T20:55:28.418Z] 20:55:28     INFO - [Child 8904, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-05-13T20:55:28.480Z] 20:55:28     INFO - [Child 7448, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/layout/style/Loader.cpp:2014
[task 2021-05-13T20:55:28.658Z] 20:55:28     INFO - REFTEST INFO | drawWindow flags = DRAWWINDOW_DRAW_CARET | DRAWWINDOW_DRAW_VIEW | DRAWWINDOW_USE_WIDGET_LAYERS; window size = 816,1039; test browser size = 800,1000
[task 2021-05-13T20:55:28.680Z] 20:55:28     INFO - REFTEST TEST-LOAD | file:///Z:/task_1620936825/build/tests/reftest/tests/image/test/reftest/ico/cur/wrapper.html?pointer.png | 0 / 1 (0%)
[task 2021-05-13T20:55:28.771Z] 20:55:28     INFO - REFTEST TEST-PASS | image/test/reftest/ico/cur/wrapper.html?pointer.cur == image/test/reftest/ico/cur/wrapper.html?pointer.png | image comparison, max difference: 0, number of differing pixels: 0
[task 2021-05-13T20:55:28.772Z] 20:55:28     INFO - REFTEST TEST-END | image/test/reftest/ico/cur/wrapper.html?pointer.cur == image/test/reftest/ico/cur/wrapper.html?pointer.png
[task 2021-05-13T20:55:28.803Z] 20:55:28     INFO - REFTEST INFO | Slowest test took 122ms (file:///Z:/task_1620936825/build/tests/reftest/tests/image/test/reftest/ico/cur/wrapper.html?pointer.cur)
[task 2021-05-13T20:55:28.803Z] 20:55:28     INFO - REFTEST INFO | Total canvas count = 2
[task 2021-05-13T20:55:28.894Z] 20:55:28     INFO - [Child 2276, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-13T20:55:28.895Z] 20:55:28     INFO - [Child 2276, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-13T20:55:28.936Z] 20:55:28     INFO - [Child 2276, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-13T20:55:28.939Z] 20:55:28     INFO - [Child 2276, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-13T20:55:28.941Z] 20:55:28     INFO - [Child 2276, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-13T20:55:28.946Z] 20:55:28     INFO - [Child 2276, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-13T20:55:28.951Z] 20:55:28     INFO - [Child 2276, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-13T20:55:28.954Z] 20:55:28     INFO - [Child 2276, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-13T20:55:29.161Z] 20:55:29     INFO - [Child 7448, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1349
[task 2021-05-13T20:55:29.203Z] 20:55:29     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost[task 2021-05-13T20:55:29.232Z] 20:55:29     INFO - [Parent 5268, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:224
[task 2021-05-13T20:55:29.232Z] 20:55:29     INFO - [Parent 5268, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:224
[task 2021-05-13T20:55:29.233Z] 20:55:29     INFO - [Parent 5268, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:224
[task 2021-05-13T20:55:29.545Z] 20:55:29     INFO - [Child 2276, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:111
[task 2021-05-13T20:55:29.589Z] 20:55:29     INFO - [Child 2276, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-05-13T20:55:29.645Z] 20:55:29     INFO - [Child 2276, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-05-13T20:55:29.742Z] 20:55:29     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-13T20:55:29.902Z] 20:55:29     INFO - [Child 7448, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-05-13T20:55:29.937Z] 20:55:29     INFO - [Child 7448, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-05-13T20:55:30.086Z] 20:55:30     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-13T20:55:30.132Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.133Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.134Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.134Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.136Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.000000s: Live database entry remove[Child 5528, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-05-13T20:55:30.137Z] 20:55:30     INFO - d', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.139Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.143Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.015000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.143Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.015000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.146Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.015000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.147Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.015000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.148Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.015000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.150Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.015000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.151Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.015000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.153Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: idb: '0.015000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.155Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: cache: '0.015000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.156Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: sdb: '0.015000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-05-13T20:55:30.157Z] 20:55:30     INFO - [Parent 5268, IPDL Background] WARNING: ls: '0.015000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690[task 2021-05-13T20:55:30.161Z] 20:55:30     INFO - [Child 5528, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-05-13T20:55:30.303Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.305Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:966
[task 2021-05-13T20:55:30.306Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.307Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.309Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:966
[task 2021-05-13T20:55:30.311Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.312Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.314Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:966
[task 2021-05-13T20:55:30.316Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.318Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.320Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:966
[task 2021-05-13T20:55:30.322Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.324Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.326Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:966
[task 2021-05-13T20:55:30.328Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.329Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.330Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:966
[task 2021-05-13T20:55:30.331Z] 20:55:30     INFO - [Parent 5268, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:938
[task 2021-05-13T20:55:30.490Z] 20:55:30     INFO - 1620939330489	Marionette	TRACE	Received observer notification xpcom-will-shutdown
[task 2021-05-13T20:55:30.490Z] 20:55:30     INFO - 1620939330489	Marionette	INFO	Stopped listening on port 2828
[task 2021-05-13T20:55:30.491Z] 20:55:30     INFO - 1620939330489	Marionette	DEBUG	Marionette stopped listening
[task 2021-05-13T20:55:30.502Z] 20:55:30     INFO - ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-13T20:55:30.586Z] 20:55:30     INFO - [GPU 1656, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:532
[task 2021-05-13T20:55:30.589Z] 20:55:30     INFO - [GPU 1656, Main Thread] WARNING: '!ipcActor->SendAccumulateChildHistograms(histogramsToSend)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:279
[task 2021-05-13T20:55:30.591Z] 20:55:30     INFO - [GPU 1656, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:532
[task 2021-05-13T20:55:30.593Z] 20:55:30     INFO - [GPU 1656, Main Thread] WARNING: '!ipcActor->SendAccumulateChildKeyedHistograms(keyedHistogramsToSend)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:283
[task 2021-05-13T20:55:30.595Z] 20:55:30     INFO - [GPU 1656, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:532
[task 2021-05-13T20:55:30.596Z] 20:55:30     INFO - [GPU 1656, Main Thread] WARNING: '!ipcActor->SendRecordDiscardedData(discardedData)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:298
[task 2021-05-13T20:55:30.598Z] 20:55:30     INFO - [Parent 5268, BackgroundThreadPool #5] ###!!! ASSERTION: reacquiring already acquired resource: '!IsAcquired()', file /builds/worker/checkouts/gecko/xpcom/threads/BlockingResourceBase.cpp:257
[task 2021-05-13T20:55:30.600Z] 20:55:30     INFO - Assertion failure: mMagic1 == sMagic && mMagic2 == sMagic, at /builds/worker/workspace/obj-build/dist/include/mozilla/MozPromise.h:529```

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.