Closed Bug 1695389 Opened 5 years ago Closed 4 years ago

Intermittent dom/base/test/test_bug1499169.html | assertion count 1 is more than expected 0 assertions

Categories

(Core :: DOM: Core & HTML, defect, P5)

Product:
Core
Component:
DOM: Core & HTML
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: assertion, intermittent-failure)

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

[task 2021-02-27T01:11:25.424Z] 01:11:25     INFO - TEST-START | dom/base/test/test_bug1499169.html
[task 2021-02-27T01:11:25.736Z] 01:11:25     INFO - GECKO(1438) | [Child 1440, Main Thread] WARNING: '!ClientIsValidCreationURL(mClientInfo.PrincipalInfo(), aArgs.url())', file /builds/worker/checkouts/gecko/dom/clients/manager/ClientSource.cpp, line 65
[task 2021-02-27T01:11:25.737Z] 01:11:25     INFO - GECKO(1438) | [Child 1440, Main Thread] WARNING: Listener is not retargetable: file /builds/worker/checkouts/gecko/netwerk/protocol/http/HttpChannelChild.cpp, line 3564
[task 2021-02-27T01:11:25.737Z] 01:11:25     INFO - GECKO(1438) | [Child 1440, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp, line 1131
[task 2021-02-27T01:11:26.023Z] 01:11:26     INFO - GECKO(1438) | MEMORY STAT | vsize 7372MB | residentFast 178MB | heapAllocated 27MB
[task 2021-02-27T01:11:26.531Z] 01:11:26     INFO - TEST-OK | dom/base/test/test_bug1499169.html | took 1077ms
[task 2021-02-27T01:11:26.536Z] 01:11:26     INFO - GECKO(1438) | [Child 1440, Main Thread] ###!!! ASSERTION: invalid image size: 'false', file /builds/worker/checkouts/gecko/layout/painting/nsDisplayList.cpp, line 4393
[task 2021-02-27T01:11:26.557Z] 01:11:26     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2021-02-27T01:11:44.812Z] 01:11:44     INFO - GECKO(1438) | #01: mozilla::PaintedLayerDataNode::PopAllPaintedLayerData() [layout/painting/FrameLayerBuilder.cpp:0]
[task 2021-02-27T01:11:44.812Z] 01:11:44     INFO - GECKO(1438) | #02: mozilla::PaintedLayerDataNode::FinishChildrenIntersecting(mozilla::gfx::IntRectTyped<mozilla::gfx::UnknownUnits> const&) [layout/painting/FrameLayerBuilder.cpp:3115]
[task 2021-02-27T01:11:44.814Z] 01:11:44     INFO - GECKO(1438) | #03: mozilla::ContainerState::ProcessDisplayItems(nsDisplayList*) [layout/painting/FrameLayerBuilder.cpp:3210]
[task 2021-02-27T01:11:44.815Z] 01:11:44     INFO - GECKO(1438) | #04: mozilla::FrameLayerBuilder::BuildContainerLayerFor(nsDisplayListBuilder*, mozilla::layers::LayerManager*, nsIFrame*, nsDisplayItem*, nsDisplayList*, mozilla::ContainerLayerParameters const&, mozilla::gfx::Matrix4x4Typed<mozilla::gfx::UnknownUnits, mozilla::gfx::UnknownUnits, float> const*, unsigned int) [layout/painting/FrameLayerBuilder.cpp:6307]
[task 2021-02-27T01:11:44.817Z] 01:11:44     INFO - GECKO(1438) | #05: nsDisplayList::BuildLayers(nsDisplayListBuilder*, mozilla::layers::LayerManager*, unsigned int, bool) [layout/painting/nsDisplayList.cpp:2248]
[task 2021-02-27T01:11:44.817Z] 01:11:44     INFO - GECKO(1438) | #06: nsDisplayList::PaintRoot(nsDisplayListBuilder*, gfxContext*, unsigned int) [layout/painting/nsDisplayList.cpp:2465]
[task 2021-02-27T01:11:44.819Z] 01:11:44     INFO - GECKO(1438) | #07: nsLayoutUtils::PaintFrame(gfxContext*, nsIFrame*, nsRegion const&, unsigned int, nsDisplayListBuilderMode, nsLayoutUtils::PaintFrameFlags) [layout/base/nsLayoutUtils.cpp:4150]
[task 2021-02-27T01:11:44.819Z] 01:11:44     INFO - GECKO(1438) | #08: mozilla::PresShell::Paint(nsView*, nsRegion const&, mozilla::PaintFlags) [layout/base/PresShell.cpp:6254]
[task 2021-02-27T01:11:44.820Z] 01:11:44     INFO - GECKO(1438) | #09: nsViewManager::ProcessPendingUpdatesPaint(nsIWidget*) [xpcom/ds/nsTArray.h:402]
[task 2021-02-27T01:11:44.820Z] 01:11:44     INFO - GECKO(1438) | #10: nsViewManager::ProcessPendingUpdatesForView(nsView*, bool) [mfbt/RefPtr.h:50]
[task 2021-02-27T01:11:44.822Z] 01:11:44     INFO - GECKO(1438) | #11: nsViewManager::ProcessPendingUpdates() [view/nsViewManager.cpp:1019]
[task 2021-02-27T01:11:44.822Z] 01:11:44     INFO - GECKO(1438) | #12: nsRefreshDriver::Tick(mozilla::layers::BaseTransactionId<mozilla::VsyncIdType>, mozilla::TimeStamp) [layout/base/nsRefreshDriver.cpp:2203]
[task 2021-02-27T01:11:44.822Z] 01:11:44     INFO - GECKO(1438) | #13: mozilla::RefreshDriverTimer::TickRefreshDrivers(mozilla::layers::BaseTransactionId<mozilla::VsyncIdType>, mozilla::TimeStamp, nsTArray<RefPtr<nsRefreshDriver> >&) [layout/base/nsRefreshDriver.cpp:344]
[task 2021-02-27T01:11:44.822Z] 01:11:44     INFO - GECKO(1438) | #14: mozilla::RefreshDriverTimer::Tick(mozilla::layers::BaseTransactionId<mozilla::VsyncIdType>, mozilla::TimeStamp) [layout/base/nsRefreshDriver.cpp:369]
[task 2021-02-27T01:11:44.823Z] 01:11:44     INFO - GECKO(1438) | #15: mozilla::VsyncRefreshDriverTimer::RefreshDriverVsyncObserver::TickRefreshDriver(mozilla::layers::BaseTransactionId<mozilla::VsyncIdType>, mozilla::TimeStamp) [layout/base/nsRefreshDriver.cpp:747]
[task 2021-02-27T01:11:44.825Z] 01:11:44     INFO - GECKO(1438) | #16: mozilla::VsyncRefreshDriverTimer::RefreshDriverVsyncObserver::NotifyVsync(mozilla::VsyncEvent const&) [layout/base/nsRefreshDriver.cpp:645]
[task 2021-02-27T01:11:44.825Z] 01:11:44     INFO - GECKO(1438) | #17: mozilla::layout::VsyncChild::RecvNotify(mozilla::VsyncEvent const&) [layout/ipc/VsyncChild.cpp:57]
[task 2021-02-27T01:11:44.825Z] 01:11:44     INFO - GECKO(1438) | #18: mozilla::layout::PVsyncChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:27495909b8eb16a2f6224f9af7a0c052f58ac4a1f37ddd12d240b8b6a62795d131a51db23214bbde8ed61a33c6a97d727ae972f588d3f35141a1a66f3aadceeb/ipc/ipdl/PVsyncChild.cpp::0]
[task 2021-02-27T01:11:44.825Z] 01:11:44     INFO - GECKO(1438) | #19: mozilla::ipc::PBackgroundChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:75695bbbf1ec93aad4718f03c359901f1be9ae34cba79945a5c42f3e8a2da054cc4ed1a56d373be9953080b82b366a6cd792a7b5323cd7f0d62bfa3c3b040098/ipc/ipdl/PBackgroundChild.cpp::6083]
[task 2021-02-27T01:11:44.826Z] 01:11:44     INFO - GECKO(1438) | #20: mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) [ipc/glue/MessageChannel.cpp:2191]
[task 2021-02-27T01:11:44.826Z] 01:11:44     INFO - GECKO(1438) | #21: mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) [ipc/glue/MessageChannel.cpp:2114]
[task 2021-02-27T01:11:44.828Z] 01:11:44     INFO - GECKO(1438) | #22: mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) [ipc/glue/MessageChannel.cpp:0]
[task 2021-02-27T01:11:44.828Z] 01:11:44     INFO - GECKO(1438) | #23: mozilla::ipc::MessageChannel::MessageTask::Run() [ipc/glue/MessageChannel.cpp:1995]
[task 2021-02-27T01:11:44.830Z] 01:11:44     INFO - GECKO(1438) | #24: nsThread::ProcessNextEvent(bool, bool*) [mfbt/RefPtr.h:314]
[task 2021-02-27T01:11:44.830Z] 01:11:44     INFO - GECKO(1438) | #25: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:501]
[task 2021-02-27T01:11:44.830Z] 01:11:44     INFO - GECKO(1438) | #26: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:109]
[task 2021-02-27T01:11:44.831Z] 01:11:44     INFO - GECKO(1438) | #27: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
[task 2021-02-27T01:11:44.831Z] 01:11:44     INFO - GECKO(1438) | #28: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
[task 2021-02-27T01:11:44.831Z] 01:11:44     INFO - GECKO(1438) | #29: nsAppShell::Run() [widget/cocoa/nsAppShell.mm:694]
[task 2021-02-27T01:11:44.838Z] 01:11:44     INFO - GECKO(1438) | #30: XRE_RunAppShell() [toolkit/xre/nsEmbedFunctions.cpp:913]
[task 2021-02-27T01:11:44.839Z] 01:11:44     INFO - GECKO(1438) | #31: mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:237]
[task 2021-02-27T01:11:44.839Z] 01:11:44     INFO - GECKO(1438) | #32: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
[task 2021-02-27T01:11:44.839Z] 01:11:44     INFO - GECKO(1438) | #33: XRE_InitChildProcess(int, char**, XREChildData const*) [toolkit/xre/nsEmbedFunctions.cpp:748]
[task 2021-02-27T01:11:44.839Z] 01:11:44     INFO -  fix-stacks error: failed to read breakpad symbols dir `/Users/cltbld/tasks/task_1614387906/build/symbols/plugin-container` for `/Users/cltbld/tasks/task_1614387906/build/application/Firefox NightlyDebug.app/Contents/MacOS/plugin-container.app/Contents/MacOS/plugin-container`
[task 2021-02-27T01:11:44.840Z] 01:11:44     INFO -  fix-stacks note:  this is expected and harmless for system libraries on debug automation runs
[task 2021-02-27T01:11:44.840Z] 01:11:44     INFO - GECKO(1438) | #34: ??? [/Users/cltbld/tasks/task_1614387906/build/application/Firefox NightlyDebug.app/Contents/MacOS/plugin-container.app/Contents/MacOS/plugin-container + 0xf0b]
[task 2021-02-27T01:11:44.840Z] 01:11:44     INFO - TEST-UNEXPECTED-FAIL | dom/base/test/test_bug1499169.html | assertion count 1 is more than expected 0 assertions
[task 2021-02-27T01:11:44.840Z] 01:11:44     INFO - TEST-START | dom/base/test/test_bug1576154.html
[task 2021-02-27T01:11:44.841Z] 01:11:44     INFO - GECKO(1438) | [Child 1440, Main Thread] ###!!! ASSERTION: invalid image size: 'false', file /builds/worker/checkouts/gecko/layout/painting/nsDisplayList.cpp, line 4393
[task 2021-02-27T01:11:44.841Z] 01:11:44     INFO - GECKO(1438) | #01: mozilla::PaintedLayerDataNode::PopAllPaintedLayerData() [layout/painting/FrameLayerBuilder.cpp:0]
[task 2021-02-27T01:11:44.841Z] 01:11:44     INFO - GECKO(1438) | #02: mozilla::PaintedLayerDataNode::FinishChildrenIntersecting(mozilla::gfx::IntRectTyped<mozilla::gfx::UnknownUnits> const&) [layout/painting/FrameLayerBuilder.cpp:3115]
[task 2021-02-27T01:11:44.841Z] 01:11:44     INFO - GECKO(1438) | #03: mozilla::ContainerState::ProcessDisplayItems(nsDisplayList*) [layout/painting/FrameLayerBuilder.cpp:3210]
[task 2021-02-27T01:11:44.842Z] 01:11:44     INFO - GECKO(1438) | #04: mozilla::FrameLayerBuilder::BuildContainerLayerFor(nsDisplayListBuilder*, mozilla::layers::LayerManager*, nsIFrame*, nsDisplayItem*, nsDisplayList*, mozilla::ContainerLayerParameters const&, mozilla::gfx::Matrix4x4Typed<mozilla::gfx::UnknownUnits, mozilla::gfx::UnknownUnits, float> const*, unsigned int) [layout/painting/FrameLayerBuilder.cpp:6307]
[task 2021-02-27T01:11:44.842Z] 01:11:44     INFO - GECKO(1438) | #05: nsDisplayList::BuildLayers(nsDisplayListBuilder*, mozilla::layers::LayerManager*, unsigned int, bool) [layout/painting/nsDisplayList.cpp:2248]
[task 2021-02-27T01:11:44.842Z] 01:11:44     INFO - GECKO(1438) | #06: nsDisplayList::PaintRoot(nsDisplayListBuilder*, gfxContext*, unsigned int) [layout/painting/nsDisplayList.cpp:2465]
[task 2021-02-27T01:11:44.842Z] 01:11:44     INFO - GECKO(1438) | #07: nsLayoutUtils::PaintFrame(gfxContext*, nsIFrame*, nsRegion const&, unsigned int, nsDisplayListBuilderMode, nsLayoutUtils::PaintFrameFlags) [layout/base/nsLayoutUtils.cpp:4150]
[task 2021-02-27T01:11:44.843Z] 01:11:44     INFO - GECKO(1438) | #08: mozilla::PresShell::Paint(nsView*, nsRegion const&, mozilla::PaintFlags) [layout/base/PresShell.cpp:6254]
[task 2021-02-27T01:11:44.843Z] 01:11:44     INFO - GECKO(1438) | #09: nsViewManager::ProcessPendingUpdatesPaint(nsIWidget*) [xpcom/ds/nsTArray.h:402]
[task 2021-02-27T01:11:44.843Z] 01:11:44     INFO - GECKO(1438) | #10: nsViewManager::ProcessPendingUpdatesForView(nsView*, bool) [mfbt/RefPtr.h:50]
[task 2021-02-27T01:11:44.843Z] 01:11:44     INFO - GECKO(1438) | #11: nsViewManager::ProcessPendingUpdates() [view/nsViewManager.cpp:1019]
[task 2021-02-27T01:11:44.844Z] 01:11:44     INFO - GECKO(1438) | #12: nsRefreshDriver::Tick(mozilla::layers::BaseTransactionId<mozilla::VsyncIdType>, mozilla::TimeStamp) [layout/base/nsRefreshDriver.cpp:2203]
[task 2021-02-27T01:11:44.845Z] 01:11:44     INFO - GECKO(1438) | #13: mozilla::RefreshDriverTimer::TickRefreshDrivers(mozilla::layers::BaseTransactionId<mozilla::VsyncIdType>, mozilla::TimeStamp, nsTArray<RefPtr<nsRefreshDriver> >&) [layout/base/nsRefreshDriver.cpp:344]
[task 2021-02-27T01:11:44.846Z] 01:11:44     INFO - GECKO(1438) | #14: mozilla::RefreshDriverTimer::Tick(mozilla::layers::BaseTransactionId<mozilla::VsyncIdType>, mozilla::TimeStamp) [layout/base/nsRefreshDriver.cpp:369]
[task 2021-02-27T01:11:44.846Z] 01:11:44     INFO - GECKO(1438) | #15: mozilla::VsyncRefreshDriverTimer::RefreshDriverVsyncObserver::TickRefreshDriver(mozilla::layers::BaseTransactionId<mozilla::VsyncIdType>, mozilla::TimeStamp) [layout/base/nsRefreshDriver.cpp:747]
[task 2021-02-27T01:11:44.846Z] 01:11:44     INFO - GECKO(1438) | #16: mozilla::VsyncRefreshDriverTimer::RefreshDriverVsyncObserver::NotifyVsync(mozilla::VsyncEvent const&) [layout/base/nsRefreshDriver.cpp:645]
[task 2021-02-27T01:11:44.847Z] 01:11:44     INFO - GECKO(1438) | #17: mozilla::layout::VsyncChild::RecvNotify(mozilla::VsyncEvent const&) [layout/ipc/VsyncChild.cpp:57]
[task 2021-02-27T01:11:44.847Z] 01:11:44     INFO - GECKO(1438) | #18: mozilla::layout::PVsyncChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:27495909b8eb16a2f6224f9af7a0c052f58ac4a1f37ddd12d240b8b6a62795d131a51db23214bbde8ed61a33c6a97d727ae972f588d3f35141a1a66f3aadceeb/ipc/ipdl/PVsyncChild.cpp::0]
[task 2021-02-27T01:11:44.847Z] 01:11:44     INFO - GECKO(1438) | #19: mozilla::ipc::PBackgroundChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:75695bbbf1ec93aad4718f03c359901f1be9ae34cba79945a5c42f3e8a2da054cc4ed1a56d373be9953080b82b366a6cd792a7b5323cd7f0d62bfa3c3b040098/ipc/ipdl/PBackgroundChild.cpp::6083]
[task 2021-02-27T01:11:44.847Z] 01:11:44     INFO - GECKO(1438) | #20: mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) [ipc/glue/MessageChannel.cpp:2191]
[task 2021-02-27T01:11:44.849Z] 01:11:44     INFO - GECKO(1438) | #21: mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) [ipc/glue/MessageChannel.cpp:2114]
[task 2021-02-27T01:11:44.849Z] 01:11:44     INFO - GECKO(1438) | #22: mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) [ipc/glue/MessageChannel.cpp:0]
[task 2021-02-27T01:11:44.849Z] 01:11:44     INFO - GECKO(1438) | #23: mozilla::ipc::MessageChannel::MessageTask::Run() [ipc/glue/MessageChannel.cpp:1995]
[task 2021-02-27T01:11:44.850Z] 01:11:44     INFO - GECKO(1438) | #24: nsThread::ProcessNextEvent(bool, bool*) [mfbt/RefPtr.h:314]
[task 2021-02-27T01:11:44.850Z] 01:11:44     INFO - GECKO(1438) | #25: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:501]
[task 2021-02-27T01:11:44.852Z] 01:11:44     INFO - GECKO(1438) | #26: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:87]
[task 2021-02-27T01:11:44.852Z] 01:11:44     INFO - GECKO(1438) | #27: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
[task 2021-02-27T01:11:44.852Z] 01:11:44     INFO - GECKO(1438) | #28: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
[task 2021-02-27T01:11:44.852Z] 01:11:44     INFO - GECKO(1438) | #29: nsAppShell::Run() [widget/cocoa/nsAppShell.mm:694]
[task 2021-02-27T01:11:44.852Z] 01:11:44     INFO - GECKO(1438) | #30: XRE_RunAppShell() [toolkit/xre/nsEmbedFunctions.cpp:913]
[task 2021-02-27T01:11:44.852Z] 01:11:44     INFO - GECKO(1438) | #31: mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:237]
[task 2021-02-27T01:11:44.855Z] 01:11:44     INFO - GECKO(1438) | #32: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
[task 2021-02-27T01:11:44.855Z] 01:11:44     INFO - GECKO(1438) | #33: XRE_InitChildProcess(int, char**, XREChildData const*) [toolkit/xre/nsEmbedFunctions.cpp:748]
[task 2021-02-27T01:11:44.855Z] 01:11:44     INFO - GECKO(1438) | #34: ??? [/Users/cltbld/tasks/task_1614387906/build/application/Firefox NightlyDebug.app/Contents/MacOS/plugin-container.app/Contents/MacOS/plugin-container + 0xf0b]
[task 2021-02-27T01:11:44.855Z] 01:11:44     INFO - GECKO(1438) | MEMORY STAT | vsize 7376MB | residentFast 189MB | heapAllocated 28MB
[task 2021-02-27T01:11:44.855Z] 01:11:44     INFO - TEST-OK | dom/base/test/test_bug1576154.html | took 743ms
[task 2021-02-27T01:11:44.855Z] 01:11:44     INFO - TEST-UNEXPECTED-FAIL | dom/base/test/test_bug1576154.html | assertion count 1 is more than expected 0 assertions```

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.