Closed
Bug 1050247
Opened 10 years ago
Closed 10 years ago
Intermittent test_getUserMedia_basicScreenshare.html, test_getUserMedia_basicWindowshare.html, test_peerConnection_basicScreenshare.html | Assertion count N is greater than expected ("Received "nonqueued" message 14 during a synchronous IPC message...")
Categories
(Core :: IPC, defect)
Tracking
()
RESOLVED
FIXED
mozilla34
| Tracking | Status | |
|---|---|---|
| firefox32 | --- | unaffected |
| firefox33 | --- | fixed |
| firefox34 | --- | fixed |
| firefox-esr24 | --- | unaffected |
| firefox-esr31 | --- | unaffected |
People
(Reporter: emorley, Assigned: jimm)
References
(Blocks 1 open bug)
Details
(Keywords: assertion, intermittent-failure)
New test added by bug 1039666; we've only started seeing these failures properly now due to bug 1048288.
Windows 7 32-bit mozilla-inbound debug test mochitest-3 on 2014-08-06 22:47:10 PDT for push a5abcc000a57
slave: t-w732-ix-061
https://tbpl.mozilla.org/php/getParsedLog.php?id=45394319&tree=Mozilla-Inbound
{
22:51:25 INFO - 11509 INFO TEST-START | /tests/dom/media/tests/mochitest/test_getUserMedia_basicScreenshare.html
22:51:25 INFO - 11510 INFO ++DOMWINDOW == 34 (16694800) [pid = 3408] [serial = 161] [outer = 1844EC00]
22:51:26 INFO - 11511 INFO TEST DEVICES: No test devices found (in media.{audio,video}_loopback_dev, using fake streams.
22:51:26 INFO - 11512 INFO [Parent 3408] ###!!! ASSERTION: Received "nonqueued" message 14 during a synchronous IPC message for window 65902 ("MozillaWindowClass"), sending it to DefWindowProc instead of the normal window procedure.: 'Error', file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/ipc/glue/WindowsMessageLoop.cpp, line 186
22:51:34 INFO - 11513 INFO `anonymous namespace'::ProcessOrDeferMessage(HWND__ *,unsigned int,unsigned int,long) [ipc/glue/WindowsMessageLoop.cpp:347]
22:51:34 INFO - 11514 INFO NeuteredWindowProc(HWND__ *,unsigned int,unsigned int,long) [ipc/glue/WindowsMessageLoop.cpp:380]
22:51:34 INFO - 11515 INFO USER32 + 0x1c4e7
22:51:34 INFO - 11516 INFO USER32 + 0x1c5e7
22:51:34 INFO - 11517 INFO USER32 + 0x14f0e
22:51:34 INFO - 11518 INFO USER32 + 0x36c20
22:51:34 INFO - 11519 INFO ntdll + 0x46fee
22:51:34 INFO - 11520 INFO USER32 + 0x164a1
22:51:34 INFO - 11521 INFO mozilla::ipc::MessageChannel::WaitForSyncNotify() [ipc/glue/WindowsMessageLoop.cpp:886]
22:51:34 INFO - 11522 INFO mozilla::ipc::MessageChannel::SendAndWait(IPC::Message *,IPC::Message *) [ipc/glue/MessageChannel.cpp:712]
22:51:34 INFO - 11523 INFO mozilla::ipc::MessageChannel::Send(IPC::Message *,IPC::Message *) [ipc/glue/MessageChannel.cpp:619]
22:51:34 INFO - 11524 INFO mozilla::layers::PLayerTransactionChild::SendUpdate(nsTArray<mozilla::layers::Edit> const &,unsigned __int64 const &,mozilla::layers::TargetConfig const &,bool const &,bool const &,unsigned int const &,bool const &,nsTArray<mozilla::layers::EditReply> *) [obj-firefox/ipc/ipdl/PLayerTransactionChild.cpp:238]
22:51:34 INFO - 11525 INFO mozilla::layers::ShadowLayerForwarder::EndTransaction(nsTArray<mozilla::layers::EditReply> *,nsIntRegion const &,unsigned __int64,bool,unsigned int,bool,bool *) [gfx/layers/ipc/ShadowLayers.cpp:611]
22:51:34 INFO - 11526 INFO mozilla::layers::ClientLayerManager::ForwardTransaction(bool) [gfx/layers/client/ClientLayerManager.cpp:464]
22:51:34 INFO - 11527 INFO mozilla::layers::ClientLayerManager::EndTransaction(void (*)(mozilla::layers::ThebesLayer *,gfxContext *,nsIntRegion const &,mozilla::layers::DrawRegionClip,nsIntRegion const &,void *),void *,mozilla::layers::LayerManager::EndTransactionFlags) [gfx/layers/client/ClientLayerManager.cpp:249]
22:51:34 INFO - 11528 INFO nsDisplayList::PaintForFrame(nsDisplayListBuilder *,nsRenderingContext *,nsIFrame *,unsigned int) [layout/base/nsDisplayList.cpp:1333]
22:51:34 INFO - 11529 INFO nsDisplayList::PaintRoot(nsDisplayListBuilder *,nsRenderingContext *,unsigned int) [layout/base/nsDisplayList.cpp:1180]
22:51:34 INFO - 11530 INFO nsLayoutUtils::PaintFrame(nsRenderingContext *,nsIFrame *,nsRegion const &,unsigned int,unsigned int) [layout/base/nsLayoutUtils.cpp:3065]
22:51:34 INFO - 11531 INFO PresShell::Paint(nsView *,nsRegion const &,unsigned int) [layout/base/nsPresShell.cpp:6239]
22:51:34 INFO - 11532 INFO nsViewManager::ProcessPendingUpdatesPaint(nsIWidget *) [view/nsViewManager.cpp:443]
22:51:34 INFO - 11533 INFO nsViewManager::ProcessPendingUpdatesForView(nsView *,bool) [view/nsViewManager.cpp:380]
22:51:34 INFO - 11534 INFO nsViewManager::ProcessPendingUpdates() [view/nsViewManager.cpp:1075]
22:51:34 INFO - 11535 INFO mozilla::RefreshDriverTimer::TickDriver(nsRefreshDriver *,__int64,mozilla::TimeStamp) [layout/base/nsRefreshDriver.cpp:172]
22:51:34 INFO - 11536 INFO mozilla::RefreshDriverTimer::Tick() [layout/base/nsRefreshDriver.cpp:162]
22:51:34 INFO - 11537 INFO nsTimerImpl::Fire() [xpcom/threads/nsTimerImpl.cpp:618]
22:51:34 INFO - 11538 INFO nsTimerEvent::Run() [xpcom/threads/nsTimerImpl.cpp:716]
22:51:34 INFO - 11539 INFO nsThread::ProcessNextEvent(bool,bool *) [xpcom/threads/nsThread.cpp:770]
22:51:34 INFO - 11540 INFO NS_ProcessNextEvent(nsIThread *,bool) [xpcom/glue/nsThreadUtils.cpp:265]
22:51:34 INFO - 11541 INFO mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:99]
22:51:34 INFO - 11542 INFO MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:229]
22:51:34 INFO - 11543 INFO MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:223]
22:51:34 INFO - 11544 INFO MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:197]
22:51:34 INFO - 11545 INFO nsBaseAppShell::Run() [widget/xpwidgets/nsBaseAppShell.cpp:166]
22:51:34 INFO - 11546 INFO nsAppShell::Run() [widget/windows/nsAppShell.cpp:193]
22:51:34 INFO - 11547 INFO nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:279]
22:51:34 INFO - 11548 INFO XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:4021]
22:51:34 INFO - 11549 INFO XREMain::XRE_main(int,char * * const,nsXREAppData const *) [toolkit/xre/nsAppRunner.cpp:4092]
22:51:34 INFO - 11550 INFO XRE_main [toolkit/xre/nsAppRunner.cpp:4306]
22:51:34 INFO - 11551 INFO do_main [browser/app/nsBrowserApp.cpp:282]
22:51:34 INFO - 11552 INFO NS_internal_main(int,char * *) [browser/app/nsBrowserApp.cpp:643]
22:51:34 INFO - 11553 INFO wmain [toolkit/xre/nsWindowsWMain.cpp:112]
22:51:34 INFO - 11554 INFO __tmainCRTStartup [f:/dd/vctools/crt_bld/self_x86/crt/src/crtexe.c:552]
22:51:34 INFO - 11555 INFO kernel32 + 0x53c45
22:51:34 INFO - 11556 INFO ntdll + 0x637f5
22:51:34 INFO - 11557 INFO ntdll + 0x637c8
22:51:34 INFO - 11558 INFO [Parent 3408] ###!!! ASSERTION: Received "nonqueued" message 14 during a synchronous IPC message for window 65900 ("MozillaWindowClass"), sending it to DefWindowProc instead of the normal window procedure.: 'Error', file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/ipc/glue/WindowsMessageLoop.cpp, line 186
22:51:34 INFO - 11559 INFO ++DOCSHELL 11A30000 == 9 [pid = 3408] [id = 38]
22:51:34 INFO - 11560 INFO ++DOMWINDOW == 35 (11A33C00) [pid = 3408] [serial = 162] [outer = 00000000]
22:51:34 INFO - 11561 INFO ++DOMWINDOW == 36 (12921800) [pid = 3408] [serial = 163] [outer = 11A33C00]
22:51:34 INFO - 11562 INFO 1407390694164 GMPInstallManager.simpleCheckAndInstall INFO Last check was: 1407390694 seconds ago, minimum seconds: 86400
22:51:34 INFO - 11563 INFO 1407390694165 GMPInstallManager._getURL INFO Using url: https://127.0.0.1:8888/dummy.xml
22:51:34 INFO - 11564 INFO 1407390694168 GMPInstallManager._getURL INFO Using url (with replacement): https://127.0.0.1:8888/dummy.xml
22:51:34 INFO - 11565 INFO 1407390694173 GMPInstallManager.checkForAddons INFO sending request to: https://127.0.0.1:8888/dummy.xml
22:51:34 INFO - 11566 INFO `anonymous namespace'::ProcessOrDeferMessage(HWND__ *,unsigned int,unsigned int,long) [ipc/glue/WindowsMessageLoop.cpp:347]
22:51:34 INFO - 11567 INFO NeuteredWindowProc(HWND__ *,unsigned int,unsigned int,long) [ipc/glue/WindowsMessageLoop.cpp:380]
22:51:34 INFO - 11568 INFO USER32 + 0x1c4e7
22:51:34 INFO - 11569 INFO USER32 + 0x1c5e7
22:51:34 INFO - 11570 INFO USER32 + 0x14f0e
22:51:34 INFO - 11571 INFO USER32 + 0x36c20
22:51:34 INFO - 11572 INFO ntdll + 0x46fee
22:51:34 INFO - 11573 INFO USER32 + 0x164a1
22:51:34 INFO - 11574 INFO mozilla::ipc::MessageChannel::WaitForSyncNotify() [ipc/glue/WindowsMessageLoop.cpp:886]
22:51:34 INFO - 11575 INFO mozilla::ipc::MessageChannel::SendAndWait(IPC::Message *,IPC::Message *) [ipc/glue/MessageChannel.cpp:712]
22:51:34 INFO - 11576 INFO mozilla::ipc::MessageChannel::Send(IPC::Message *,IPC::Message *) [ipc/glue/MessageChannel.cpp:619]
22:51:34 INFO - 11577 INFO mozilla::layers::PLayerTransactionChild::SendUpdate(nsTArray<mozilla::layers::Edit> const &,unsigned __int64 const &,mozilla::layers::TargetConfig const &,bool const &,bool const &,unsigned int const &,bool const &,nsTArray<mozilla::layers::EditReply> *) [obj-firefox/ipc/ipdl/PLayerTransactionChild.cpp:238]
22:51:34 INFO - 11578 INFO mozilla::layers::ShadowLayerForwarder::EndTransaction(nsTArray<mozilla::layers::EditReply> *,nsIntRegion const &,unsigned __int64,bool,unsigned int,bool,bool *) [gfx/layers/ipc/ShadowLayers.cpp:611]
22:51:34 INFO - 11579 INFO mozilla::layers::ClientLayerManager::ForwardTransaction(bool) [gfx/layers/client/ClientLayerManager.cpp:464]
22:51:34 INFO - 11580 INFO mozilla::layers::ClientLayerManager::EndTransaction(void (*)(mozilla::layers::ThebesLayer *,gfxContext *,nsIntRegion const &,mozilla::layers::DrawRegionClip,nsIntRegion const &,void *),void *,mozilla::layers::LayerManager::EndTransactionFlags) [gfx/layers/client/ClientLayerManager.cpp:249]
22:51:34 INFO - 11581 INFO nsDisplayList::PaintForFrame(nsDisplayListBuilder *,nsRenderingContext *,nsIFrame *,unsigned int) [layout/base/nsDisplayList.cpp:1333]
22:51:34 INFO - 11582 INFO nsDisplayList::PaintRoot(nsDisplayListBuilder *,nsRenderingContext *,unsigned int) [layout/base/nsDisplayList.cpp:1180]
22:51:34 INFO - 11583 INFO nsLayoutUtils::PaintFrame(nsRenderingContext *,nsIFrame *,nsRegion const &,unsigned int,unsigned int) [layout/base/nsLayoutUtils.cpp:3065]
22:51:34 INFO - 11584 INFO PresShell::Paint(nsView *,nsRegion const &,unsigned int) [layout/base/nsPresShell.cpp:6239]
22:51:34 INFO - 11585 INFO nsViewManager::ProcessPendingUpdatesPaint(nsIWidget *) [view/nsViewManager.cpp:443]
22:51:34 INFO - 11586 INFO nsViewManager::ProcessPendingUpdatesForView(nsView *,bool) [view/nsViewManager.cpp:380]
22:51:34 INFO - 11587 INFO nsViewManager::ProcessPendingUpdates() [view/nsViewManager.cpp:1075]
22:51:34 INFO - 11588 INFO mozilla::RefreshDriverTimer::TickDriver(nsRefreshDriver *,__int64,mozilla::TimeStamp) [layout/base/nsRefreshDriver.cpp:172]
22:51:34 INFO - 11589 INFO mozilla::RefreshDriverTimer::Tick() [layout/base/nsRefreshDriver.cpp:162]
22:51:34 INFO - 11590 INFO nsTimerImpl::Fire() [xpcom/threads/nsTimerImpl.cpp:618]
22:51:34 INFO - 11591 INFO nsTimerEvent::Run() [xpcom/threads/nsTimerImpl.cpp:716]
22:51:34 INFO - 11592 INFO nsThread::ProcessNextEvent(bool,bool *) [xpcom/threads/nsThread.cpp:770]
22:51:34 INFO - 11593 INFO NS_ProcessNextEvent(nsIThread *,bool) [xpcom/glue/nsThreadUtils.cpp:265]
22:51:34 INFO - 11594 INFO mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:99]
22:51:34 INFO - 11595 INFO MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:229]
22:51:34 INFO - 11596 INFO MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:223]
22:51:34 INFO - 11597 INFO MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:197]
22:51:34 INFO - 11598 INFO nsBaseAppShell::Run() [widget/xpwidgets/nsBaseAppShell.cpp:166]
22:51:34 INFO - 11599 INFO nsAppShell::Run() [widget/windows/nsAppShell.cpp:193]
22:51:34 INFO - 11600 INFO nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:279]
22:51:34 INFO - 11601 INFO XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:4021]
22:51:34 INFO - 11602 INFO XREMain::XRE_main(int,char * * const,nsXREAppData const *) [toolkit/xre/nsAppRunner.cpp:4092]
22:51:34 INFO - 11603 INFO XRE_main [toolkit/xre/nsAppRunner.cpp:4306]
22:51:34 INFO - 11604 INFO do_main [browser/app/nsBrowserApp.cpp:282]
22:51:34 INFO - 11605 INFO NS_internal_main(int,char * *) [browser/app/nsBrowserApp.cpp:643]
22:51:34 INFO - 11606 INFO wmain [toolkit/xre/nsWindowsWMain.cpp:112]
22:51:34 INFO - 11607 INFO __tmainCRTStartup [f:/dd/vctools/crt_bld/self_x86/crt/src/crtexe.c:552]
22:51:34 INFO - 11608 INFO kernel32 + 0x53c45
22:51:34 INFO - 11609 INFO ntdll + 0x637f5
22:51:34 INFO - 11610 INFO ntdll + 0x637c8
22:51:34 INFO - 11611 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLMediaElement.cpp, line 2319
22:51:34 INFO - 11612 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLMediaElement.cpp, line 2319
22:51:34 INFO - 11613 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLMediaElement.cpp, line 2319
22:51:34 INFO - 11614 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLMediaElement.cpp, line 2319
22:51:34 INFO - 11615 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLMediaElement.cpp, line 2319
22:51:34 INFO - 11616 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLMediaElement.cpp, line 2319
22:51:34 INFO - 11617 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLMediaElement.cpp, line 2319
22:51:34 INFO - 11618 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLMediaElement.cpp, line 2319
22:51:34 INFO - 11619 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLMediaElement.cpp, line 2319
22:51:34 INFO - 11620 INFO --DOCSHELL 11A30000 == 8 [pid = 3408] [id = 38]
22:51:34 INFO - 11621 INFO TEST-OK | /tests/dom/media/tests/mochitest/test_getUserMedia_basicScreenshare.html | took 8751ms
22:51:34 INFO - 11622 INFO ###!!! [Child][DispatchAsyncMessage] Error: Route error: message sent to unknown actor ID
22:51:34 INFO - 11623 INFO ###!!! [Child][DispatchAsyncMessage] Error: Route error: message sent to unknown actor ID
22:51:34 INFO - 11624 INFO [Parent 3408] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\content\src\HTMLVideoElement.cpp, line 245
22:51:34 INFO - 11625 INFO ++DOMWINDOW == 37 (12C03400) [pid = 3408] [serial = 164] [outer = 1844EC00]
22:51:34 INFO - 11626 INFO TEST-UNEXPECTED-ERROR | /tests/dom/media/tests/mochitest/test_getUserMedia_basicScreenshare.html | Assertion count 2 is greater than expected range 0-1 assertions.
}
|
Reporter | |
Comment 1•10 years ago
|
||
|
||
Comment 2•10 years ago
|
||
I think we need to whitelist another windows message type? (there's another one I flagged as well (?12?)) in a different bug, and added expectAssertions to hide it in tbpl
Component: WebRTC: Audio/Video → IPC
| Comment hidden (Legacy TBPL/Treeherder Robot) |
| Comment hidden (Legacy TBPL/Treeherder Robot) |
|
|
Reporter | |
Comment 5•10 years ago
|
||
Summary: Intermittent test_getUserMedia_basicScreenshare.html | Assertion count 2 is greater than expected range 0-1 assertions. ("ASSERTION: Received "nonqueued" message 14 during a synchronous IPC message...") → Intermittent test_getUserMedia_basicScreenshare.html, test_getUserMedia_basicWindowshare.html | Assertion count 2 (or 3) is greater than expected range 0-1 assertions. ("ASSERTION: Received "nonqueued" message 14 during a synchronous IPC message...")
|
|
Reporter | |
Comment 6•10 years ago
|
||
Summary: Intermittent test_getUserMedia_basicScreenshare.html, test_getUserMedia_basicWindowshare.html | Assertion count 2 (or 3) is greater than expected range 0-1 assertions. ("ASSERTION: Received "nonqueued" message 14 during a synchronous IPC message...") → Intermittent test_getUserMedia_basicScreenshare.html, test_getUserMedia_basicWindowshare.html, test_peerConnection_basicScreenshare.html | Assertion count N is greater than expected ("Received "nonqueued" message 14 during a synchronous IPC message...")
|
Assignee | |
Comment 7•10 years ago
|
||
The signatures are going to change a bit once bug 1052395 lands.
09:17:04 INFO - 11822 INFO [Parent 448] ###!!! ASSERTION: Received "nonqueued" ui message "WM_GETTEXTLENGTH" during a synchronous IPC message for window 0x1016C ("MozillaWindowClass"), sending it to DefWindowProc instead of the normal window procedure.: 'Error', file c:/builds/moz2_slave/try-w32-d-00000000000000000000/build/ipc/glue/WindowsMessageLoop.cpp, line 208
| Comment hidden (Legacy TBPL/Treeherder Robot) |
|
|
Assignee | |
Comment 9•10 years ago
|
||
fixed by bug 1047842
Status: NEW → RESOLVED
Closed: 10 years ago
Resolution: --- → FIXED
|
||
Updated•10 years ago
|
status-firefox32:
--- → unaffected
status-firefox33:
--- → affected
status-firefox34:
--- → fixed
status-firefox-esr24:
--- → unaffected
status-firefox-esr31:
--- → unaffected
|
|
||
Updated•10 years ago
|
QA Whiteboard: [qa-]
You need to log in
before you can comment on or make changes to this bug.
Description
•