Closed Bug 1691125 Opened 4 years ago Closed 4 years ago

Intermittent Assertion failure: false (MOZ_ASSERT_UNREACHABLE: Stale image key (delete)!), at /builds/worker/checkouts/gecko/gfx/layers/wr/WebRenderBridgeParent.cpp:657

Categories

(Core :: Graphics: Layers, defect, P5)

Product:
Core
Component:
Graphics: Layers
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Tracking Flags:
Tracking Status
firefox-esr78 --- unaffected
firefox85 --- unaffected
firefox86 --- unaffected
firefox87 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: assertion, intermittent-failure, Whiteboard: [stockwell fixed:backout])

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=329028117&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/UXKeWorXQ-60DStaxiXwvA/runs/0/artifacts/public/logs/live_backing.log

[task 2021-02-05T17:58:08.532Z] 17:58:08     INFO - TEST-OK | browser/components/extensions/test/browser/browser_ext_sidebarAction_click.js | took 952ms
[task 2021-02-05T17:58:08.532Z] 17:58:08     INFO - GECKO(1526) | [Child 1528: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x107fdb800 == 3 [pid = 1528] [id = 185]
[task 2021-02-05T17:58:08.532Z] 17:58:08     INFO - GECKO(1526) | [Child 1528: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0x11d44f200) [pid = 1528] [serial = 451] [outer = 0x0]
[task 2021-02-05T17:58:08.532Z] 17:58:08     INFO - GECKO(1526) | [Child 1528: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0x107fdec00) [pid = 1528] [serial = 452] [outer = 0x11d44f200]
[task 2021-02-05T17:58:08.600Z] 17:58:08     INFO - checking window state
[task 2021-02-05T17:58:08.630Z] 17:58:08     INFO - TEST-START | browser/components/extensions/test/browser/browser_ext_sidebarAction_context.js
[task 2021-02-05T17:58:08.714Z] 17:58:08     INFO - GECKO(1526) | [Parent 1526, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:969
[task 2021-02-05T17:58:08.715Z] 17:58:08     INFO - GECKO(1526) | [Child 1527: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x116fa2000 == 6 [pid = 1527] [id = 601]
[task 2021-02-05T17:58:08.715Z] 17:58:08     INFO - GECKO(1526) | [Child 1527: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 28 (0x116f02e40) [pid = 1527] [serial = 1694] [outer = 0x0]
[task 2021-02-05T17:58:08.715Z] 17:58:08     INFO - GECKO(1526) | [Child 1527: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 29 (0x117321400) [pid = 1527] [serial = 1695] [outer = 0x116f02e40]
[task 2021-02-05T17:58:08.715Z] 17:58:08     INFO - GECKO(1526) | [Child 1527, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2695
[task 2021-02-05T17:58:08.782Z] 17:58:08     INFO - GECKO(1526) | [Child 1527: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 30 (0x11e885000) [pid = 1527] [serial = 1696] [outer = 0x116f02e40]
[task 2021-02-05T17:58:10.132Z] 17:58:10     INFO - GECKO(1526) | [Child 1529, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp:7013
[task 2021-02-05T17:58:10.211Z] 17:58:10     INFO - GECKO(1526) | [Parent 1526: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 82 (0x12ad10c00) [pid = 1526] [serial = 788] [outer = 0x105780c80]
<...>
[task 2021-02-05T17:58:18.887Z] 17:58:18     INFO - GECKO(1526) | [2021-02-05T17:58:18Z WARN  webrender_bindings::bindings] Could not find symbol "glVertexP4uiv" by glcontext
[task 2021-02-05T17:58:18.887Z] 17:58:18     INFO - GECKO(1526) | [2021-02-05T17:58:18Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2021-02-05T17:58:18.887Z] 17:58:18     INFO - GECKO(1526) | [Child 1529: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x11a3a5000 == 6 [pid = 1529] [id = 77]
[task 2021-02-05T17:58:18.889Z] 17:58:18     INFO - GECKO(1526) | [Child 1529: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (0x11a40be40) [pid = 1529] [serial = 223] [outer = 0x0]
[task 2021-02-05T17:58:18.889Z] 17:58:18     INFO - GECKO(1526) | [Child 1529: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (0x11a3a6000) [pid = 1529] [serial = 224] [outer = 0x11a40be40]
[task 2021-02-05T17:58:18.967Z] 17:58:18     INFO - GECKO(1526) | [Parent 1526, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4256
[task 2021-02-05T17:58:18.972Z] 17:58:18     INFO - GECKO(1526) | [Parent 1526: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 96 (0x16b177400) [pid = 1526] [serial = 802] [outer = 0x15bce7200]
[task 2021-02-05T17:58:19.042Z] 17:58:19     INFO - GECKO(1526) | Assertion failure: false (MOZ_ASSERT_UNREACHABLE: Stale image key (delete)!), at /builds/worker/checkouts/gecko/gfx/layers/wr/WebRenderBridgeParent.cpp:657
[task 2021-02-05T17:58:19.043Z] 17:58:19     INFO - GECKO(1526) | #01: mozilla::layers::WebRenderBridgeParent::SetDisplayList(mozilla::gfx::RectTyped<mozilla::LayoutDevicePixel, float> const&, mozilla::ipc::ByteBuf&&, mozilla::wr::BuiltDisplayListDescriptor const&, nsTArray<mozilla::layers::OpUpdateResource> const&, nsTArray<mozilla::layers::RefCountedShmem> const&, nsTArray<mozilla::ipc::Shmem> const&, mozilla::TimeStamp const&, mozilla::wr::TransactionBuilder&, mozilla::wr::Epoch, bool) [gfx/layers/wr/WebRenderBridgeParent.cpp:1101]
[task 2021-02-05T17:58:19.043Z] 17:58:19     INFO - GECKO(1526) | #02: mozilla::layers::WebRenderBridgeParent::ProcessDisplayListData(mozilla::layers::DisplayListData&, mozilla::wr::Epoch, mozilla::TimeStamp const&, bool, bool) [gfx/layers/wr/WebRenderBridgeParent.cpp:1164]
[task 2021-02-05T17:58:19.043Z] 17:58:19     INFO - GECKO(1526) | #03: mozilla::layers::WebRenderBridgeParent::RecvSetDisplayList(mozilla::layers::DisplayListData&&, nsTArray<mozilla::layers::OpDestroy>&&, unsigned long long const&, mozilla::layers::BaseTransactionId<mozilla::layers::TransactionIdType> const&, bool const&, mozilla::layers::BaseTransactionId<mozilla::VsyncIdType> const&, mozilla::TimeStamp const&, mozilla::TimeStamp const&, mozilla::TimeStamp const&, nsTString<char> const&, mozilla::TimeStamp const&, nsTArray<mozilla::layers::CompositionPayload>&&) [gfx/layers/wr/WebRenderBridgeParent.cpp:1210]
[task 2021-02-05T17:58:19.043Z] 17:58:19     INFO - GECKO(1526) | #04: mozilla::layers::PWebRenderBridgeParent::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:49259c4a427ea31f1cb8967c6f8f898ec923bdc58fec4aa56f25fec8a767a00dc33ffc186e2830f98cb14324196aad5e1baf05b520985c030345fb935d5b6108/ipc/ipdl/PWebRenderBridgeParent.cpp::403]
[task 2021-02-05T17:58:19.043Z] 17:58:19     INFO - GECKO(1526) | #05: mozilla::layers::PCompositorManagerParent::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:465f788c5c2153f2808bde1001998ab167eaff20dd748295e96a4079a3456cfd1a822dbe432109311fce0091cc1cbeee521673d1eb10a143fbb293f855779e0e/ipc/ipdl/PCompositorManagerParent.cpp::205]
[task 2021-02-05T17:58:19.044Z] 17:58:19     INFO - GECKO(1526) | #06: mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) [ipc/glue/MessageChannel.cpp:2154]
[task 2021-02-05T17:58:19.044Z] 17:58:19     INFO - GECKO(1526) | #07: mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) [ipc/glue/MessageChannel.cpp:2077]
[task 2021-02-05T17:58:19.044Z] 17:58:19     INFO - GECKO(1526) | #08: mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) [ipc/glue/MessageChannel.cpp:1925]
[task 2021-02-05T17:58:19.044Z] 17:58:19     INFO - GECKO(1526) | #09: mozilla::ipc::MessageChannel::MessageTask::Run() [ipc/glue/MessageChannel.cpp:1958]
[task 2021-02-05T17:58:19.044Z] 17:58:19     INFO - GECKO(1526) | #10: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1152]
[task 2021-02-05T17:58:19.044Z] 17:58:19     INFO - GECKO(1526) | #11: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:548]
[task 2021-02-05T17:58:19.044Z] 17:58:19     INFO - GECKO(1526) | #12: mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:332]
[task 2021-02-05T17:58:19.044Z] 17:58:19     INFO - GECKO(1526) | #13: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:311]
[task 2021-02-05T17:58:19.045Z] 17:58:19     INFO - GECKO(1526) | #14: nsThread::ThreadFunc(void*) [xpcom/threads/nsThread.cpp:393]
[task 2021-02-05T17:58:19.307Z] 17:58:19     INFO - GECKO(1526) | #15: _pt_root [nsprpub/pr/src/pthreads/ptthread.c:204]
[task 2021-02-05T17:58:19.309Z] 17:58:19     INFO -  fix-stacks: error: failed to parse `/usr/lib/system/libsystem_pthread.dylib`
[task 2021-02-05T17:58:19.309Z] 17:58:19     INFO -  fix-stacks: unsupported object file format
[task 2021-02-05T17:58:19.309Z] 17:58:19     INFO - GECKO(1526) | #16: _pthread_body [/usr/lib/system/libsystem_pthread.dylib + 0x32eb]
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | #17: _pthread_start [/usr/lib/system/libsystem_pthread.dylib + 0x6249]
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | Exiting due to channel error.
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | Exiting due to channel error.
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | Exiting due to channel error.
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | Exiting due to channel error.
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | Exiting due to channel error.
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | Exiting due to channel error.
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | [RDD 1532, Main Thread] WARNING: Shutting down RDD process early due to a crash!: file /builds/worker/checkouts/gecko/dom/media/ipc/RDDParent.cpp:259
[task 2021-02-05T17:58:19.319Z] 17:58:19     INFO - GECKO(1526) | Exiting due to channel error.
[task 2021-02-05T17:58:19.339Z] 17:58:19     INFO - TEST-INFO | Main app process: exit 1
[task 2021-02-05T17:58:19.339Z] 17:58:19    ERROR - TEST-UNEXPECTED-FAIL | ShutdownLeaks | process() called before end of test suite
[task 2021-02-05T17:58:19.339Z] 17:58:19     INFO - TEST-INFO | Confirming we saw 1505 DOCSHELL created and 1472 destroyed log strings.
[task 2021-02-05T17:58:19.339Z] 17:58:19     INFO - TEST-INFO | Confirming we saw 3997 DOMWINDOW created and 3809 destroyed log strings.
[task 2021-02-05T17:58:19.340Z] 17:58:19     INFO - Buffered messages logged at 17:58:08
[task 2021-02-05T17:58:19.340Z] 17:58:19     INFO - Entering test bound testTabSwitchContext
[task 2021-02-05T17:58:19.340Z] 17:58:19     INFO - Extension loaded
[task 2021-02-05T17:58:19.340Z] 17:58:19     INFO - Console message: Warning: attempting to write 23188 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file. This preference will not be sent to any content processes.
[task 2021-02-05T17:58:19.340Z] 17:58:19     INFO - Buffered messages logged at 17:58:10
[task 2021-02-05T17:58:19.341Z] 17:58:19     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_sidebarAction_context.js | Promise rejected, expecting rejection to match /Invalid tab ID: 3735928559/, got "Invalid tab ID: 3735928559": Expected invalid tab ID error - ```

Andrew, could this be caused by the changes in Bug 1691065?

Flags: needinfo?(aosmond)
You need to log in before you can comment on or make changes to this bug.