Closed Bug 1629299 Opened 6 years ago Closed 5 years ago

Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/toolkit/components/extensions/webrequest/StreamFilterParent.cpp:609:7 in mozilla::extensions::StreamFilterParent::OnDataAvailable(nsIRequest*, nsIInputStream*

Categories

(WebExtensions :: Request Handling, defect, P5)

Product:
WebExtensions
Component:
Request Handling
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

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

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

Bug 1629299 fix checking disconnected state on io thread in StreamFilter
47 bytes, text/x-phabricator-request
Details | Review

Filed by: aciure [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=297248564&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WedMvoEiRf-OKm0wSML4uQ/runs/0/artifacts/public/logs/live_backing.log

[task 2020-04-11T18:14:34.487Z] 18:14:34 INFO - GECKO(3816) | Thread T25 'HTML5 Parser' (tid=4023, running) created by main thread at:
[task 2020-04-11T18:14:34.488Z] 18:14:34 INFO - GECKO(3816) | #0 pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors.cc:967:3 (firefox+0x5749b)
[task 2020-04-11T18:14:34.488Z] 18:14:34 INFO - GECKO(3816) | #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x3c383)
[task 2020-04-11T18:14:34.490Z] 18:14:34 INFO - GECKO(3816) | #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x308c2)
[task 2020-04-11T18:14:34.490Z] 18:14:34 INFO - GECKO(3816) | #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:650:8 (libxul.so+0xad3e4b)
[task 2020-04-11T18:14:34.490Z] 18:14:34 INFO - GECKO(3816) | #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, unsigned int, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:620:12 (libxul.so+0xada3ad)
[task 2020-04-11T18:14:34.491Z] 18:14:34 INFO - GECKO(3816) | #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, nsIRunnable*, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:139:57 (libxul.so+0xadc688)
[task 2020-04-11T18:14:34.492Z] 18:14:34 INFO - GECKO(3816) | #6 NS_NewNamedThread<13> /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:65:10 (libxul.so+0x1c5675a)
[task 2020-04-11T18:14:34.495Z] 18:14:34 INFO - GECKO(3816) | #7 nsHtml5Module::GetStreamParserThread() /builds/worker/checkouts/gecko/parser/html/nsHtml5Module.cpp:95:7 (libxul.so+0x1c5675a)
[task 2020-04-11T18:14:34.495Z] 18:14:34 INFO - GECKO(3816) | #8 nsHtml5StreamParser::nsHtml5StreamParser(nsHtml5TreeOpExecutor*, nsHtml5Parser*, eParserMode) /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:177:20 (libxul.so+0x1c5fdfb)
[task 2020-04-11T18:14:34.496Z] 18:14:34 INFO - GECKO(3816) | #9 nsHtml5Parser::MarkAsNotScriptCreated(char const*) /builds/worker/checkouts/gecko/parser/html/nsHtml5Parser.cpp:535:37 (libxul.so+0x1c59c02)
[task 2020-04-11T18:14:34.499Z] 18:14:34 INFO - GECKO(3816) | #10 nsHTMLDocument::StartDocumentLoad(char const*, nsIChannel*, nsILoadGroup*, nsISupports*, nsIStreamListener**, bool, nsIContentSink*) /builds/worker/checkouts/gecko/dom/html/nsHTMLDocument.cpp (libxul.so+0x3a457a7)
[task 2020-04-11T18:14:34.499Z] 18:14:34 INFO - GECKO(3816) | #11 CreateDocument /builds/worker/checkouts/gecko/layout/build/nsContentDLF.cpp:319:13 (libxul.so+0x4ef7773)
[task 2020-04-11T18:14:34.500Z] 18:14:34 INFO - GECKO(3816) | #12 nsContentDLF::CreateInstance(char const*, nsIChannel*, nsILoadGroup*, nsTSubstring<char> const&, nsIDocShell*, nsISupports*, nsIStreamListener**, nsIContentViewer**) /builds/worker/checkouts/gecko/layout/build/nsContentDLF.cpp:127:12 (libxul.so+0x4ef7773)
[task 2020-04-11T18:14:34.500Z] 18:14:34 INFO - GECKO(3816) | #13 NewContentViewerObj /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp:7770:35 (libxul.so+0x5e1934b)
[task 2020-04-11T18:14:34.501Z] 18:14:34 INFO - GECKO(3816) | #14 nsDocShell::CreateContentViewer(nsTSubstring<char> const&, nsIRequest*, nsIStreamListener**) /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp:7501:17 (libxul.so+0x5e1934b)
[task 2020-04-11T18:14:34.502Z] 18:14:34 INFO - GECKO(3816) | #15 nsDSURIContentListener::DoContent(nsTSubstring<char> const&, bool, nsIRequest*, nsIStreamListener**, bool*) /builds/worker/checkouts/gecko/docshell/base/nsDSURIContentListener.cpp:181:20 (libxul.so+0x5e18970)
[task 2020-04-11T18:14:34.502Z] 18:14:34 INFO - GECKO(3816) | #16 nsDocumentOpenInfo::TryContentListener(nsIURIContentListener*, nsIChannel*) /builds/worker/checkouts/gecko/uriloader/base/nsURILoader.cpp:631:18 (libxul.so+0x1b86145)
[task 2020-04-11T18:14:34.503Z] 18:14:34 INFO - GECKO(3816) | #17 nsDocumentOpenInfo::TryDefaultContentListener(nsIChannel*) /builds/worker/checkouts/gecko/uriloader/base/nsURILoader.cpp:660:12 (libxul.so+0x1b86cf0)
[task 2020-04-11T18:14:34.504Z] 18:14:34 INFO - GECKO(3816) | #18 nsDocumentOpenInfo::DispatchContent(nsIRequest*, nsISupports*) /builds/worker/checkouts/gecko/uriloader/base/nsURILoader.cpp:312:9 (libxul.so+0x1b84e26)
[task 2020-04-11T18:14:34.505Z] 18:14:34 INFO - GECKO(3816) | #19 nsDocumentOpenInfo::OnStartRequest(nsIRequest*) /builds/worker/checkouts/gecko/uriloader/base/nsURILoader.cpp:190:8 (libxul.so+0x1b84343)
[task 2020-04-11T18:14:34.505Z] 18:14:34 INFO - GECKO(3816) | #20 mozilla::net::HttpChannelChild::DoOnStartRequest(nsIRequest*, nsISupports*) /builds/worker/checkouts/gecko/netwerk/protocol/http/HttpChannelChild.cpp:719:20 (libxul.so+0x107191e)
[task 2020-04-11T18:14:34.506Z] 18:14:34 INFO - GECKO(3816) | #21 mozilla::net::HttpChannelChild::OnStartRequest(nsresult const&, mozilla::net::nsHttpResponseHead const&, bool const&, mozilla::net::nsHttpHeaderArray const&, mozilla::net::ParentLoadInfoForwarderArgs const&, bool const&, bool const&, bool const&, unsigned long const&, int const&, unsigned int const&, nsTString<char> const&, nsTString<char> const&, mozilla::net::NetAddr const&, mozilla::net::NetAddr const&, unsigned int const&, nsTString<char> const&, long const&, bool const&, bool const&, bool const&, mozilla::net::ResourceTimingStructArgs const&, bool const&, mozilla::Maybe<unsigned int> const&, bool const&, nsILoadInfo::CrossOriginOpenerPolicy const&) /builds/worker/checkouts/gecko/netwerk/protocol/http/HttpChannelChild.cpp:565:3 (libxul.so+0x1078127)
[task 2020-04-11T18:14:34.507Z] 18:14:34 INFO - GECKO(3816) | #22 operator() /builds/worker/checkouts/gecko/netwerk/protocol/http/HttpChannelChild.cpp:419:15 (libxul.so+0x10ae0b2)
[task 2020-04-11T18:14:34.508Z] 18:14:34 INFO - GECKO(3816) | #23 std::_Function_handler<void (), mozilla::net::HttpChannelChild::RecvOnStartRequest(nsresult const&, mozilla::net::nsHttpResponseHead const&, bool const&, mozilla::net::nsHttpHeaderArray const&, mozilla::net::ParentLoadInfoForwarderArgs const&, bool const&, bool const&, bool const&, unsigned long const&, int const&, unsigned int const&, nsTString<char> const&, nsTString<char> const&, mozilla::net::NetAddr const&, mozilla::net::NetAddr const&, short const&, unsigned int const&, nsTString<char> const&, long const&, bool const&, bool const&, bool const&, mozilla::net::ResourceTimingStructArgs const&, bool const&, mozilla::Maybe<unsigned int> const&, bool const&, nsILoadInfo::CrossOriginOpenerPolicy const&)::$_6>::_M_invoke(std::_Any_data const&) /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.4.0/../../../../include/c++/7.4.0/bits/std_function.h:316:2 (libxul.so+0x10ae0b2)
[task 2020-04-11T18:14:34.509Z] 18:14:34 INFO - GECKO(3816) | #24 operator() /builds/worker/fetches/clang/bin/../lib/gcc/x86_64-unknown-linux-gnu/7.4.0/../../../../include/c++/7.4.0/bits/std_function.h:706:14 (libxul.so+0xfeb423)
[task 2020-04-11T18:14:34.510Z] 18:14:34 INFO - GECKO(3816) | #25 mozilla::net::ChannelFunctionEvent::Run() /builds/worker/workspace/obj-build/dist/include/mozilla/net/ChannelEventQueue.h:79:25 (libxul.so+0xfeb423)
[task 2020-04-11T18:14:34.510Z] 18:14:34 INFO - GECKO(3816) | #26 mozilla::net::ChannelEventQueue::RunOrEnqueue(mozilla::net::ChannelEvent*, bool) /builds/worker/workspace/obj-build/dist/include/mozilla/net/ChannelEventQueue.h:260:10 (libxul.so+0xfd44f7)
[task 2020-04-11T18:14:34.512Z] 18:14:34 INFO - GECKO(3816) | #27 mozilla::net::HttpChannelChild::RecvOnStartRequest(nsresult const&, mozilla::net::nsHttpResponseHead const&, bool const&, mozilla::net::nsHttpHeaderArray const&, mozilla::net::ParentLoadInfoForwarderArgs const&, bool const&, bool const&, bool const&, unsigned long const&, int const&, unsigned int const&, nsTString<char> const&, nsTString<char> const&, mozilla::net::NetAddr const&, mozilla::net::NetAddr const&, short const&, unsigned int const&, nsTString<char> const&, long const&, bool const&, bool const&, bool const&, mozilla::net::ResourceTimingStructArgs const&, bool const&, mozilla::Maybe<unsigned int> const&, bool const&, nsILoadInfo::CrossOriginOpenerPolicy const&) /builds/worker/checkouts/gecko/netwerk/protocol/http/HttpChannelChild.cpp:409:12 (libxul.so+0x1077443)
[task 2020-04-11T18:14:34.513Z] 18:14:34 INFO - GECKO(3816) | #28 mozilla::net::PHttpChannelChild::OnMessageReceived(IPC::Message const&) /builds/worker/workspace/obj-build/ipc/ipdl/PHttpChannelChild.cpp:862:28 (libxul.so+0x1544c2d)
[task 2020-04-11T18:14:34.513Z] 18:14:34 INFO - GECKO(3816) | #29 mozilla::dom::PContentChild::OnMessageReceived(IPC::Message const&) /builds/worker/workspace/obj-build/ipc/ipdl/PContentChild.cpp:8409:32 (libxul.so+0x145f74a)
[task 2020-04-11T18:14:34.515Z] 18:14:34 INFO - GECKO(3816) | #30 mozilla::dom::ContentChild::OnMessageReceived(IPC::Message const&) /builds/worker/checkouts/gecko/dom/ipc/ContentChild.cpp:3612:25 (libxul.so+0x42e6050)
[task 2020-04-11T18:14:34.515Z] 18:14:34 INFO - GECKO(3816) | #31 mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:2187:25 (libxul.so+0x135c8b6)
[task 2020-04-11T18:14:34.516Z] 18:14:34 INFO - GECKO(3816) | #32 mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:2111:9 (libxul.so+0x135ac5d)
[task 2020-04-11T18:14:34.517Z] 18:14:34 INFO - GECKO(3816) | #33 mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1959:3 (libxul.so+0x135b7d1)
[task 2020-04-11T18:14:34.517Z] 18:14:34 INFO - GECKO(3816) | #34 mozilla::ipc::MessageChannel::MessageTask::Run() /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1990:13 (libxul.so+0x135bd09)
[task 2020-04-11T18:14:34.519Z] 18:14:34 INFO - GECKO(3816) | #35 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1200:14 (libxul.so+0xad630f)
[task 2020-04-11T18:14:34.520Z] 18:14:34 INFO - GECKO(3816) | #36 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:481:10 (libxul.so+0xadac62)
[task 2020-04-11T18:14:34.521Z] 18:14:34 INFO - GECKO(3816) | #37 mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:109:5 (libxul.so+0x136030e)
[task 2020-04-11T18:14:34.522Z] 18:14:34 INFO - GECKO(3816) | #38 mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:270:30 (libxul.so+0x1360c6b)
[task 2020-04-11T18:14:34.523Z] 18:14:34 INFO - GECKO(3816) | #39 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:315:10 (libxul.so+0x12f0e4c)
[task 2020-04-11T18:14:34.523Z] 18:14:34 INFO - GECKO(3816) | #40 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:308:3 (libxul.so+0x12f0e4c)
[task 2020-04-11T18:14:34.525Z] 18:14:34 INFO - GECKO(3816) | #41 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:290:3 (libxul.so+0x12f0e4c)
[task 2020-04-11T18:14:34.526Z] 18:14:34 INFO - GECKO(3816) | #42 nsBaseAppShell::Run() /builds/worker/checkouts/gecko/widget/nsBaseAppShell.cpp:137:27 (libxul.so+0x476e8f3)
[task 2020-04-11T18:14:34.526Z] 18:14:34 INFO - GECKO(3816) | #43 XRE_RunAppShell() /builds/worker/checkouts/gecko/toolkit/xre/nsEmbedFunctions.cpp:909:20 (libxul.so+0x634f699)
[task 2020-04-11T18:14:34.527Z] 18:14:34 INFO - GECKO(3816) | #44 mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:237:9 (libxul.so+0x1360c1a)
[task 2020-04-11T18:14:34.530Z] 18:14:34 INFO - GECKO(3816) | #45 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:315:10 (libxul.so+0x12f0e4c)
[task 2020-04-11T18:14:34.531Z] 18:14:34 INFO - GECKO(3816) | #46 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:308:3 (libxul.so+0x12f0e4c)
[task 2020-04-11T18:14:34.531Z] 18:14:34 INFO - GECKO(3816) | #47 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:290:3 (libxul.so+0x12f0e4c)
[task 2020-04-11T18:14:34.532Z] 18:14:34 INFO - GECKO(3816) | #48 XRE_InitChildProcess(int, char**, XREChildData const*) /builds/worker/checkouts/gecko/toolkit/xre/nsEmbedFunctions.cpp:740:34 (libxul.so+0x634f409)
[task 2020-04-11T18:14:34.532Z] 18:14:34 INFO - GECKO(3816) | #49 mozilla::BootstrapImpl::XRE_InitChildProcess(int, char**, XREChildData const*) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:67:12 (libxul.so+0x63574c2)
[task 2020-04-11T18:14:34.533Z] 18:14:34 INFO - GECKO(3816) | #50 content_process_main /builds/worker/checkouts/gecko/browser/app/../../ipc/contentproc/plugin-container.cpp:56:28 (firefox+0xc9077)
[task 2020-04-11T18:14:34.534Z] 18:14:34 INFO - GECKO(3816) | #51 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:303:18 (firefox+0xc9077)
[task 2020-04-11T18:14:34.534Z] 18:14:34 INFO - GECKO(3816) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/toolkit/components/extensions/webrequest/StreamFilterParent.cpp:609:7 in mozilla::extensions::StreamFilterParent::OnDataAvailable(nsIRequest*, nsIInputStream*, unsigned long, unsigned int)
[task 2020-04-11T18:14:34.535Z] 18:14:34 INFO - GECKO(3816) | ==================
[task 2020-04-11T18:14:34.623Z] 18:14:34 ERROR - GECKO(3816) | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2020-04-11T18:14:37.082Z] 18:14:37 INFO - GECKO(3816) | 1586628877075 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2020-04-11T18:14:37.082Z] 18:14:37 INFO - GECKO(3816) | 1586628877076 Marionette INFO Stopped listening on port 2828
[task 2020-04-11T18:14:37.083Z] 18:14:37 INFO - GECKO(3816) | 1586628877077 Marionette DEBUG Marionette stopped listening

Assignee: nobody → mixedpuppy
Status: NEW → ASSIGNED
Summary: Intermittent GECKO(3816) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/toolkit/components/extensions/webrequest/StreamFilterParent.cpp:609:7 in mozilla::extensions::StreamFilterParent::OnDataAvailable(nsIRequest*, nsIInputStream* → Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/toolkit/components/extensions/webrequest/StreamFilterParent.cpp:609:7 in mozilla::extensions::StreamFilterParent::OnDataAvailable(nsIRequest*, nsIInputStream*

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: ASSIGNED → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
See Also: → 1645941
Pushed by juhsu@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/4888a34634d0 fix checking disconnected state on io thread in StreamFilter r=zombie
Blocks: tsan
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: