Closed Bug 1538425 Opened 5 years ago Closed 5 years ago

Intermittent dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js | Uncaught exception - at chrome://mochitests/content/browser/dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js:22 - Error: MozAfterPaint had a mismatch...

Categories

(Core Graveyard :: Plug-ins, defect, P5)

defect

Tracking

(firefox-esr60 unaffected, firefox66 unaffected, firefox67 unaffected, firefox68 fixed)

RESOLVED FIXED
mozilla68
Tracking Status
firefox-esr60 --- unaffected
firefox66 --- unaffected
firefox67 --- unaffected
firefox68 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: apavel)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell disable-recommended])

Attachments

(1 file)

#[markdown(off)]
Filed by: aiakab [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=235618795&repo=autoland

https://queue.taskcluster.net/v1/task/Yw3M-N7vQLKbyoSp6t9HDg/runs/0/artifacts/public/logs/live_backing.log

02:39:56 INFO - TEST-START | dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js
02:39:56 INFO - GECKO(4684) | ++DOCSHELL 000001EC7DA57800 == 2 [pid = 8004] [id = {51e2464a-adc8-460d-b76f-905038dcbc74}]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 5 (000001EC7C2ED3E0) [pid = 8004] [serial = 5] [outer = 0000000000000000]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 6 (000001EC7DD54800) [pid = 8004] [serial = 6] [outer = 000001EC7C2ED3E0]
02:39:56 INFO - GECKO(4684) | [Parent 8256, Main Thread] WARNING: '!parent', file z:/build/build/src/netwerk/ipc/NeckoParent.cpp, line 955
02:39:56 INFO - GECKO(4684) | ++DOCSHELL 000002242124E000 == 1 [pid = 944] [id = {97ce0bba-7cef-4442-96cc-cef2d63f8dcd}]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 1 (0000022427807020) [pid = 944] [serial = 1] [outer = 0000000000000000]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 7 (000001EC7DD57400) [pid = 8004] [serial = 7] [outer = 000001EC7C2ED3E0]
02:39:56 INFO - GECKO(4684) | ++DOCSHELL 000001EC7E702800 == 3 [pid = 8004] [id = {3f2e4910-3b90-4c07-a43b-1ac5830a502f}]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 8 (000001EC7E723880) [pid = 8004] [serial = 8] [outer = 0000000000000000]
02:39:56 INFO - GECKO(4684) | For application/x-test found plugin nptest.dll
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 2 (0000022426B84400) [pid = 944] [serial = 2] [outer = 0000022427807020]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 9 (000001EC7DD5EC00) [pid = 8004] [serial = 9] [outer = 000001EC7E723880]
02:39:56 INFO - GECKO(4684) | ++DOCSHELL 00000244E484C000 == 3 [pid = 1944] [id = {47a9768f-77e7-4a35-a6ad-af5e86ab25e6}]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 6 (00000244EA1EC5C0) [pid = 1944] [serial = 6] [outer = 0000000000000000]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 7 (00000244EAE7D000) [pid = 1944] [serial = 7] [outer = 00000244EA1EC5C0]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 8 (00000244EC230400) [pid = 1944] [serial = 8] [outer = 00000244EA1EC5C0]
02:39:56 INFO - GECKO(4684) | ++DOCSHELL 00000244EC296800 == 4 [pid = 1944] [id = {41c5863f-f3a1-4269-b6e0-c5d6a8b74f28}]
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 9 (00000244EC2424C0) [pid = 1944] [serial = 9] [outer = 0000000000000000]
02:39:56 INFO - GECKO(4684) | For application/x-test found plugin nptest.dll
02:39:56 INFO - GECKO(4684) | ++DOMWINDOW == 10 (00000244ECB3A000) [pid = 1944] [serial = 10] [outer = 00000244EC2424C0]
02:39:56 INFO - GECKO(4684) | [Child 8004, Main Thread] ###!!! ASSERTION: does it make sense to Show()/Hide() a disabled widget?: 'mEnabled', file z:/build/build/src/widget/PuppetWidget.cpp, line 185
02:39:57 INFO - GECKO(4684) | #01: static void SetPluginIsActive(class nsISupports *, void *) [layout/base/PresShell.cpp:10477]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #02: mozilla::dom::Document::EnumerateActivityObservers(void (*)(nsISupports *,void *),void *) [dom/base/Document.cpp:8857]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #03: mozilla::PresShell::SetIsActive(bool) [layout/base/PresShell.cpp:10494]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #04: nsDocShell::SetIsActive(bool) [docshell/base/nsDocShell.cpp:5309]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #05: mozilla::dom::TabChild::RecvSetDocShellIsActive(bool const &) [dom/ipc/TabChild.cpp:2332]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #06: mozilla::dom::PBrowserChild::OnMessageReceived(IPC::Message const &) [s3:gecko-generated-sources:62a78bed88168e1f4dc04e1d97142439fd400d1236984b1dc8e608cbfac8e4e48e87a96a2ee61f93051e016ac440094743f095f8603e6ebbcd80ec8997fd90f9/ipc/ipdl/PBrowserChild.cpp::0]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #07: mozilla::dom::PContentChild::OnMessageReceived(IPC::Message const &) [s3:gecko-generated-sources:004c13febd8d46ab9f5944ab61d95d2f5c392c9b3baae8b51482fb7a102efb9a099a98262a1ee8848ee3fb9d2b9bee1bd859a1f8b346e08ea3e487e8e4bfa4ae/ipc/ipdl/PContentChild.cpp::5462]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #08: mozilla::ipc::MessageChannel::DispatchAsyncMessage(IPC::Message const &) [ipc/glue/MessageChannel.cpp:2152]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #09: mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message &&) [ipc/glue/MessageChannel.cpp:2078]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #10: mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask &) [ipc/glue/MessageChannel.cpp:1938]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #11: mozilla::ipc::MessageChannel::MessageTask::Run() [ipc/glue/MessageChannel.cpp:1970]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #12: mozilla::SchedulerGroup::Runnable::Run() [xpcom/threads/SchedulerGroup.cpp:295]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #13: nsThread::ProcessNextEvent(bool,bool *) [xpcom/threads/nsThread.cpp:1167]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #14: NS_ProcessNextEvent(nsIThread *,bool) [xpcom/threads/nsThreadUtils.cpp:482]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #15: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:88]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #16: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:309]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #17: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #18: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #19: nsAppShell::Run() [widget/windows/nsAppShell.cpp:411]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #20: XRE_RunAppShell() [toolkit/xre/nsEmbedFunctions.cpp:933]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #21: mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:238]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #22: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:309]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #23: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #24: XRE_InitChildProcess(int,char * * const,XREChildData const *) [toolkit/xre/nsEmbedFunctions.cpp:775]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #25: NS_internal_main(int,char * *,char * *) [browser/app/nsBrowserApp.cpp:263]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #26: wmain [toolkit/xre/nsWindowsWMain.cpp:131]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #27: static int __scrt_common_main_seh() [f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #28: KERNEL32.DLL + 0x13034
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #29: ntdll.dll + 0x71461
02:39:57 INFO -
02:39:57 INFO - TEST-INFO | started process screenshot
02:39:57 INFO - TEST-INFO | screenshot: exit 0
02:39:57 INFO - Buffered messages logged at 02:39:56
02:39:57 INFO - Entering test bound
02:39:57 INFO - Buffered messages logged at 02:39:57
02:39:57 INFO - TEST-PASS | dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js | plugin1 is loaded -
02:39:57 INFO - TEST-PASS | dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js | plugin2 is loaded -
02:39:57 INFO - TEST-PASS | dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js | plugin2 is selected -
02:39:57 INFO - Buffered messages finished
02:39:57 INFO - TEST-UNEXPECTED-FAIL | dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js | Uncaught exception - at chrome://mochitests/content/browser/dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js:22 - Error: MozAfterPaint had a mismatched plugin visibility
02:39:57 INFO - Stack trace:
02:39:57 INFO - listener@chrome://mochitests/content/browser/dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js:22:16
02:39:57 INFO - async*waitForPluginVisibility/<@chrome://mochitests/content/browser/dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js:25:12
02:39:57 INFO - waitForPluginVisibility@chrome://mochitests/content/browser/dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js:4:10
02:39:57 INFO - @chrome://mochitests/content/browser/dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js:62:9
02:39:57 INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1106:34
02:39:57 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1134:12
02:39:57 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:995:14
02:39:57 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59
02:39:57 INFO - Leaving test bound
02:39:57 INFO - GECKO(4684) | MEMORY STAT | vsize 2103159MB | vsizeMaxContiguous 66455714MB | residentFast 317MB | heapAllocated 130MB
02:39:57 INFO - TEST-OK | dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js | took 1163ms
02:39:57 INFO - Not taking screenshot here: see the one that was previously logged
02:39:57 INFO - TEST-UNEXPECTED-FAIL | dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js | Found an unexpected tab at the end of test run: http://127.0.0.1:8888/browser/dom/plugins/test/mochitest/plugin_test.html -
02:39:57 INFO - GECKO(4684) | [Child 8004, Main Thread] ###!!! ASSERTION: does it make sense to Show()/Hide() a disabled widget?: 'mEnabled', file z:/build/build/src/widget/PuppetWidget.cpp, line 185
02:39:57 INFO - GECKO(4684) | #01: static void SetPluginIsActive(class nsISupports *, void *) [layout/base/PresShell.cpp:10477]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #02: mozilla::dom::Document::EnumerateActivityObservers(void (*)(nsISupports *,void *),void *) [dom/base/Document.cpp:8857]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #03: mozilla::PresShell::SetIsActive(bool) [layout/base/PresShell.cpp:10494]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #04: mozilla::dom::TabChild::RecvRenderLayers(bool const &,bool const &,mozilla::layers::LayersObserverEpoch const &) [dom/ipc/TabChild.cpp:2412]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #05: mozilla::dom::PBrowserChild::OnMessageReceived(IPC::Message const &) [s3:gecko-generated-sources:62a78bed88168e1f4dc04e1d97142439fd400d1236984b1dc8e608cbfac8e4e48e87a96a2ee61f93051e016ac440094743f095f8603e6ebbcd80ec8997fd90f9/ipc/ipdl/PBrowserChild.cpp::0]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #06: mozilla::dom::PContentChild::OnMessageReceived(IPC::Message const &) [s3:gecko-generated-sources:004c13febd8d46ab9f5944ab61d95d2f5c392c9b3baae8b51482fb7a102efb9a099a98262a1ee8848ee3fb9d2b9bee1bd859a1f8b346e08ea3e487e8e4bfa4ae/ipc/ipdl/PContentChild.cpp::5462]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #07: mozilla::ipc::MessageChannel::DispatchAsyncMessage(IPC::Message const &) [ipc/glue/MessageChannel.cpp:2152]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #08: mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message &&) [ipc/glue/MessageChannel.cpp:2078]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #09: mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask &) [ipc/glue/MessageChannel.cpp:1938]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #10: mozilla::ipc::MessageChannel::MessageTask::Run() [ipc/glue/MessageChannel.cpp:1970]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #11: mozilla::SchedulerGroup::Runnable::Run() [xpcom/threads/SchedulerGroup.cpp:295]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #12: nsThread::ProcessNextEvent(bool,bool *) [xpcom/threads/nsThread.cpp:1167]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #13: NS_ProcessNextEvent(nsIThread *,bool) [xpcom/threads/nsThreadUtils.cpp:482]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #14: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:88]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #15: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:309]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #16: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #17: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #18: nsAppShell::Run() [widget/windows/nsAppShell.cpp:411]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #19: XRE_RunAppShell() [toolkit/xre/nsEmbedFunctions.cpp:933]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #20: mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:238]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #21: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:309]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #22: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:291]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #23: XRE_InitChildProcess(int,char * * const,XREChildData const *) [toolkit/xre/nsEmbedFunctions.cpp:775]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #24: NS_internal_main(int,char * *,char * *) [browser/app/nsBrowserApp.cpp:263]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #25: wmain [toolkit/xre/nsWindowsWMain.cpp:131]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #26: static int __scrt_common_main_seh() [f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #27: KERNEL32.DLL + 0x13034
02:39:57 INFO -
02:39:57 INFO - GECKO(4684) | #28: ntdll.dll + 0x71461
02:39:57 INFO -
02:39:57 INFO - Not taking screenshot here: see the one that was previously logged
02:39:57 INFO - TEST-UNEXPECTED-FAIL | dom/plugins/test/mochitest/browser_tabswitchbetweenplugins.js | Found an unexpected tab at the end of test run: http://127.0.0.1:8888/browser/dom/plugins/test/mochitest/plugin_test.html -
02:39:57 INFO - GECKO(4684) | ++DOCSHELL 000001C40124D000 == 3 [pid = 600] [id = {e0faadc3-f01d-498b-9096-633ae1e3fce6}]
02:39:57 INFO - GECKO(4684) | ++DOMWINDOW == 6 (000001C40025E020) [pid = 600] [serial = 6] [outer = 0000000000000000]

Whiteboard: [stockwell disable-recommended] → [stockwell needswork:owner]

The priority flag is not set for this bug.
:jimm, could you have a look please?

Flags: needinfo?(jmathies)
Whiteboard: [stockwell disable-recommended] → [stockwell needswork:owner]
Flags: needinfo?(jmathies)
Priority: -- → P5

In the last 7 days there have been 52 occurrences on Windows 10 and Windows 7 32, Asan, Debug and Opt.

Whiteboard: [stockwell disable-recommended] → [stockwell needswork:owner]

Joel, can we get the bot not resetting the priorities ? :)
merci

Flags: needinfo?(jmaher)
Priority: -- → P5

there are two variables at play here:

  1. new test failures we see in tree (~250/week) which are filed as P5
  2. when test failures hit 30 failures/week or more, we add a stockwell tag and adjust priority to -- in order to ensure it gets triaged.

It would be easy to leave the priority flag alone for good, but we want to make sure bugs are getting triaged- the problem is:

  1. too much volume for triaging all new failures
  2. assuming we did triage all new failures, if the failure increases in frequency it needs to show up on a dashboard, setting priority to -- gets it on triage- we would need a way to ensure this is getting eyes on it.
Flags: needinfo?(jmaher)
Pushed by dvarga@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/5f1a827dc2ed
disabled browser_tabswitchbetweenplugins.js on all platforms r=jmaher
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla68
Assignee: nobody → apavel
Product: Core → Core Graveyard
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: