867318 - Intermittent test_GCrace.html | Assertion count 2 is greater than expected range (bogus request time: 'now >= requestTime')

Status: RESOLVED WORKSFORME

(Core :: Networking: Cache, defect)

Description

[Ryan VanderMeulen [:RyanVM]]

Neat trick - "finished in -15909ms"

https://tbpl.mozilla.org/php/getParsedLog.php?id=22419255&tree=Mozilla-Inbound

Rev3 WINNT 5.1 mozilla-inbound debug test mochitest-other on 2013-04-30 09:57:23 PDT for push 4b68073547c0
slave: talos-r3-xp-042

10:35:00     INFO -  0 INFO SimpleTest START
10:35:00     INFO -  1 INFO TEST-START | /tests/dom/plugins/test/mochitest/test_GCrace.html
10:35:00     INFO -  WARNING: NS_ENSURE_TRUE(currentURI) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/content/base/src/ThirdPartyUtil.cpp, line 96
10:35:00     INFO -  ++DOMWINDOW == 16 (0786A338) [serial = 16] [outer = 0CDA7CD8]
10:34:43     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, BadImage(newImage)) failed with result 0x80540006: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/image/src/ImageFactory.cpp, line 190
10:34:43     INFO -  ###!!! ASSERTION: bogus request time: 'now >= requestTime', file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/netwerk/protocol/http/nsHttpResponseHead.cpp, line 241
10:34:53     INFO -  mozilla::net::nsHttpChannel::UpdateExpirationTime() [netwerk/protocol/http/nsHttpChannel.cpp:2771]
10:34:53     INFO -  mozilla::net::nsHttpChannel::InitCacheEntry() [netwerk/protocol/http/nsHttpChannel.cpp:3636]
10:34:53     INFO -  mozilla::net::nsHttpChannel::ProcessNormal() [netwerk/protocol/http/nsHttpChannel.cpp:1413]
10:34:53     INFO -  mozilla::net::nsHttpChannel::ProcessResponse() [netwerk/protocol/http/nsHttpChannel.cpp:1324]
10:34:53     INFO -  mozilla::net::nsHttpChannel::OnStartRequest(nsIRequest *,nsISupports *) [netwerk/protocol/http/nsHttpChannel.cpp:4907]
10:34:53     INFO -  nsInputStreamPump::OnStateStart() [netwerk/base/src/nsInputStreamPump.cpp:422]
10:34:53     INFO -  nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream *) [netwerk/base/src/nsInputStreamPump.cpp:383]
10:34:53     INFO -  nsInputStreamReadyEvent::Run() [xpcom/io/nsStreamUtils.cpp:83]
10:34:53     INFO -  nsThread::ProcessNextEvent(bool,bool *) [xpcom/threads/nsThread.cpp:627]
10:34:53     INFO -  NS_ProcessNextEvent(nsIThread *,bool) [obj-firefox/xpcom/build/nsThreadUtils.cpp:238]
10:34:53     INFO -  mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:117]
10:34:53     INFO -  MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:219]
10:34:53     INFO -  MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:213]
10:34:53     INFO -  MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:187]
10:34:53     INFO -  nsBaseAppShell::Run() [widget/xpwidgets/nsBaseAppShell.cpp:165]
10:34:53     INFO -  nsAppShell::Run() [widget/windows/nsAppShell.cpp:113]
10:34:53     INFO -  nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:290]
10:34:53     INFO -  XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:3879]
10:34:53     INFO -  XREMain::XRE_main(int,char * * const,nsXREAppData const *) [toolkit/xre/nsAppRunner.cpp:3946]
10:34:53     INFO -  XRE_main [toolkit/xre/nsAppRunner.cpp:4158]
10:34:53     INFO -  do_main [browser/app/nsBrowserApp.cpp:271]
10:34:53     INFO -  NS_internal_main(int,char * *) [browser/app/nsBrowserApp.cpp:576]
10:34:53     INFO -  wmain [toolkit/xre/nsWindowsWMain.cpp:112]
10:34:53     INFO -  __tmainCRTStartup [f:/dd/vctools/crt_bld/self_x86/crt/src/crtexe.c:552]
10:34:53     INFO -  wmainCRTStartup [f:/dd/vctools/crt_bld/self_x86/crt/src/crtexe.c:371]
10:34:53     INFO -  kernel32 + 0x16d4f
10:34:53     INFO -  ###!!! ASSERTION: bogus request time: 'now >= requestTime', file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/netwerk/protocol/http/nsHttpResponseHead.cpp, line 241
10:34:53     INFO -  mozilla::net::nsHttpChannel::UpdateExpirationTime() [netwerk/protocol/http/nsHttpChannel.cpp:2771]
10:34:53     INFO -  mozilla::net::nsHttpChannel::InitCacheEntry() [netwerk/protocol/http/nsHttpChannel.cpp:3636]
10:34:53     INFO -  mozilla::net::nsHttpChannel::ProcessNormal() [netwerk/protocol/http/nsHttpChannel.cpp:1413]
10:34:53     INFO -  mozilla::net::nsHttpChannel::ProcessResponse() [netwerk/protocol/http/nsHttpChannel.cpp:1324]
10:34:53     INFO -  mozilla::net::nsHttpChannel::OnStartRequest(nsIRequest *,nsISupports *) [netwerk/protocol/http/nsHttpChannel.cpp:4907]
10:34:53     INFO -  nsInputStreamPump::OnStateStart() [netwerk/base/src/nsInputStreamPump.cpp:422]
10:34:53     INFO -  nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream *) [netwerk/base/src/nsInputStreamPump.cpp:383]
10:34:53     INFO -  nsInputStreamReadyEvent::Run() [xpcom/io/nsStreamUtils.cpp:83]
10:34:53     INFO -  nsThread::ProcessNextEvent(bool,bool *) [xpcom/threads/nsThread.cpp:627]
10:34:53     INFO -  NS_ProcessNextEvent(nsIThread *,bool) [obj-firefox/xpcom/build/nsThreadUtils.cpp:238]
10:35:00     INFO -  mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:82]
10:35:00     INFO -  MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:219]
10:35:00     INFO -  MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:213]
10:35:00     INFO -  MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:187]
10:35:00     INFO -  nsBaseAppShell::Run() [widget/xpwidgets/nsBaseAppShell.cpp:165]
10:35:00     INFO -  nsAppShell::Run() [widget/windows/nsAppShell.cpp:113]
10:35:00     INFO -  nsAppStartup::Run() [toolkit/components/startup/nsAppStartup.cpp:290]
10:35:00     INFO -  XREMain::XRE_mainRun() [toolkit/xre/nsAppRunner.cpp:3879]
10:35:00     INFO -  XREMain::XRE_main(int,char * * const,nsXREAppData const *) [toolkit/xre/nsAppRunner.cpp:3946]
10:35:00     INFO -  XRE_main [toolkit/xre/nsAppRunner.cpp:4158]
10:35:00     INFO -  do_main [browser/app/nsBrowserApp.cpp:271]
10:35:00     INFO -  NS_internal_main(int,char * *) [browser/app/nsBrowserApp.cpp:576]
10:35:00     INFO -  wmain [toolkit/xre/nsWindowsWMain.cpp:112]
10:35:00     INFO -  __tmainCRTStartup [f:/dd/vctools/crt_bld/self_x86/crt/src/crtexe.c:552]
10:35:00     INFO -  wmainCRTStartup [f:/dd/vctools/crt_bld/self_x86/crt/src/crtexe.c:371]
10:35:00     INFO -  kernel32 + 0x16d4f
10:35:00     INFO -  WARNING: NS_ENSURE_TRUE(aURI) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/caps/src/nsScriptSecurityManager.cpp, line 1909
10:35:00     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/extensions/cookie/nsPermissionManager.cpp, line 977
10:35:00     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/extensions/permissions/nsContentBlocker.cpp, line 250
10:35:00     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/extensions/permissions/nsContentBlocker.cpp, line 212
10:35:00     INFO -  For application/x-test found plugin nptest.dll
10:35:00     INFO -  2 INFO TEST-PASS | /tests/dom/plugins/test/mochitest/test_GCrace.html | Skipping this test when test plugin is not OOP.
10:35:00     INFO -  3 INFO TEST-END | /tests/dom/plugins/test/mochitest/test_GCrace.html | finished in -15909ms
10:35:00     INFO -  ++DOMWINDOW == 17 (0CEC4DC8) [serial = 17] [outer = 0CDA7CD8]
10:35:00     INFO -  4 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/plugins/test/mochitest/test_GCrace.html | Assertion count 2 is greater than expected range 0-0 assertions.

Comment 1

[Ed Morley [:emorley]]

(OrangeWFM for bugs not modified in > 2 months)