Closed
Bug 809317
Opened 12 years ago
Closed 9 years ago
Add SAMPLER_PRINT_LOCATION
Categories
(Core :: Gecko Profiler, defect)
Tracking
()
RESOLVED
WONTFIX
mozilla20
People
(Reporter: BenWa, Assigned: BenWa)
Details
(Whiteboard: [keep open])
Attachments
(2 files, 3 obsolete files)
(deleted),
patch
|
BenWa
:
review+
BenWa
:
checkin+
|
Details | Diff | Splinter Review |
(deleted),
patch
|
BenWa
:
review+
|
Details | Diff | Splinter Review |
I've been wanting a function such as SAMPLER_PRINT_LOCATION for debugging for quite a while. The usage is 'give me as much information about how we got here as possible' and can be useful for example to put in an object's constructor/destructors o trace its usage.
In the long term I want to evolve this support return the stack in a ExecutionStack structure that later we will be able to collect during a page mutation and forward to layout and painting. During the painting we will be able to associate that this paint was (in part) caused by this style change.
More generally this is the first step to support showing what caused an async execution.
Here's a sample of that I have so far:
Backtrace:
(root)
Startup::XRE_Main
Events::ProcessGeckoEvents
widget::ChildView::drawRect
Paint::PresShell::Paint
Attachment #679031 -
Flags: review?(ehsan)
Assignee | ||
Comment 1•12 years ago
|
||
Attachment #679047 -
Flags: review?(ehsan)
Assignee | ||
Comment 2•12 years ago
|
||
Sample with part 2, notice a few bad frames (CMMFRandTemplate) and no de-mangling:
CMMFRandTemplate
start
main
_ZL7do_mainiPPc
XRE_main
Startup::XRE_Main
CMMFRandTemplate
_ZN7XREMain8XRE_mainEiPPcPK12nsXREAppData
_ZN7XREMain11XRE_mainRunEv
_ZN12nsAppStartup3RunEv
_ZN10nsAppShell3RunEv
-[NSApplication run]
-[GeckoNSApplication nextEventMatchingMask:untilDate:inMode:dequeue:]
-[NSApplication nextEventMatchingMask:untilDate:inMode:dequeue:]
_DPSNextEvent
BlockUntilNextEventMatchingListInMode
ReceiveNextEventCommon
RunCurrentEventLoopInMode
CFRunLoopRunSpecific
__CFRunLoopRun
__CFRunLoopDoObservers
__CFRUNLOOP_IS_CALLING_OUT_TO_AN_OBSERVER_CALLBACK_FUNCTION__
__83-[NSWindow _postWindowNeedsDisplayOrLayoutOrUpdateConstraintsUnlessPostingDisabled]_block_invoke_01208
_handleWindowNeedsDisplayOrLayoutOrUpdateConstraints
-[NSView displayIfNeeded]
-[NSView _displayRectIgnoringOpacity:isVisibleRect:rectIsVisibleRectForView:]
-[NSThemeFrame _recursiveDisplayRectIfNeededIgnoringOpacity:isVisibleRect:rectIsVisibleRectForView:topView:]
-[NSView _recursiveDisplayRectIfNeededIgnoringOpacity:isVisibleRect:rectIsVisibleRectForView:topView:]
-[NSView _recursiveDisplayRectIfNeededIgnoringOpacity:isVisibleRect:rectIsVisibleRectForView:topView:]
-[NSView _recursiveDisplayRectIfNeededIgnoringOpacity:isVisibleRect:rectIsVisibleRectForView:topView:]
-[NSView _drawRect:clip:]
-[ChildView drawRect:]
widget::ChildView::drawRect
CMMFRandTemplate
-[ChildView drawRect:inContext:alternate:]
_ZN11nsChildView11PaintWindowE11nsIntRegionb
_ZThn112_N6nsView11PaintWindowEP9nsIWidget11nsIntRegionj
_ZN6nsView11PaintWindowEP9nsIWidget11nsIntRegionj
_ZN13nsViewManager11PaintWindowEP9nsIWidget11nsIntRegionj
_ZN13nsViewManager7RefreshEP6nsViewRK11nsIntRegionb
Paint::PresShell::Paint
CMMFRandTemplate
_ZN9PresShell5PaintEP7nsIViewRK8nsRegionj
_ZN7mozilla6layers15LayerManagerOGL19EndEmptyTransactionENS0_12LayerManager19EndTransactionFlagsE
_ZN7mozilla6layers15LayerManagerOGL14EndTransactionEPFvPNS0_11ThebesLayerEP10gfxContextRK11nsIntRegionS8_PvES9_NS0_12LayerManager19EndTransactionFlagsE
_ZN7mozilla6layers15LayerManagerOGL6RenderEv
Assignee | ||
Comment 3•12 years ago
|
||
Comment on attachment 679047 [details] [diff] [review]
Part 2: Support stackwalking
Review of attachment 679047 [details] [diff] [review]:
-----------------------------------------------------------------
::: tools/profiler/TableTicker.cpp
@@ +1286,1 @@
> doSampleStackTrace(stack, threadProfile, NULL);
This is missing 'ThreadProfile threadProfile(1000, stack);'
Comment 4•12 years ago
|
||
Comment on attachment 679031 [details] [diff] [review]
Part 1: Provide a pseudo stack implementation
Review of attachment 679031 [details] [diff] [review]:
-----------------------------------------------------------------
::: tools/profiler/TableTicker.cpp
@@ +277,5 @@
> }
>
> friend std::ostream& operator<<(std::ostream& stream, const ThreadProfile& profile);
>
> + void IterateTags(void callback(const ProfileEntry& entry, const char* tagStringData))
void (*callback)(...)
Even better: typedefs!
@@ +278,5 @@
>
> friend std::ostream& operator<<(std::ostream& stream, const ThreadProfile& profile);
>
> + void IterateTags(void callback(const ProfileEntry& entry, const char* tagStringData))
> + {
Please add MOZ_ASSERT(callback) as a sanity check.
@@ +283,5 @@
> + int readPos = mReadPos;
> + while (readPos != mLastFlushPos) {
> + // Number of tag consumed
> + int incBy = 1;
> + ProfileEntry entry = mEntries[readPos];
Nit: use a reference please.
@@ +1220,5 @@
> +void print_callback(const ProfileEntry& entry, const char* tagStringData) {
> + switch (entry.mTagName) {
> + case 's':
> + case 'c':
> + printf(" %s\n", tagStringData);
Send this stuff to stderr, please.
@@ +1231,5 @@
> + mozilla_sampler_init();
> +
> + ProfileStack *stack = tlsStack.get();
> + if (!stack) {
> + ASSERT(false);
MOZ_ASSERT.
@@ +1240,5 @@
> + doSampleStackTrace(stack, threadProfile, NULL);
> +
> + threadProfile.flush();
> +
> + printf("Backtrace:\n");
Here too.
Attachment #679031 -
Flags: review?(ehsan) → review+
Comment 5•12 years ago
|
||
Comment on attachment 679047 [details] [diff] [review]
Part 2: Support stackwalking
Review of attachment 679047 [details] [diff] [review]:
-----------------------------------------------------------------
r=me on the condition that you blog about this. ;-)
::: tools/profiler/TableTicker.cpp
@@ +1236,5 @@
> void print_callback(const ProfileEntry& entry, const char* tagStringData) {
> switch (entry.mTagName) {
> case 's':
> case 'c':
> printf(" %s\n", tagStringData);
You're missing a break. Is that intentional?
@@ +1240,5 @@
> printf(" %s\n", tagStringData);
> + case 'l':
> + char tagBuff[1024];
> + unsigned long long pc = (unsigned long long)(uintptr_t)entry.mTagPtr;
> + snprintf(tagBuff, DYNAMIC_MAX_STRING, "%#llx", pc);
Hmm, try printf-ing all this at the same time, no need to do it twice.
@@ +1246,5 @@
> + NS_DescribeCodeAddress((void*)pc, &details);
> + if (details.function) {
> + printf(" %s\n", details.function);
> + } else {
> + printf(" %s\n", tagBuff);
stderr
@@ +1276,5 @@
> +#endif
> +
> + TickSample sample;
> + sample.fp = (unsigned char*)bp;
> + sample.pc = NULL;
Nit: nullptr.
Attachment #679047 -
Flags: review?(ehsan) → review+
Assignee | ||
Comment 6•12 years ago
|
||
Assignee | ||
Comment 7•12 years ago
|
||
Going to land part 1, I want to send part 2 through try.
Assignee: nobody → bgirard
Attachment #679031 -
Attachment is obsolete: true
Status: NEW → ASSIGNED
Attachment #683331 -
Flags: review+
Assignee | ||
Comment 8•12 years ago
|
||
Whiteboard: [keep open]
Assignee | ||
Comment 9•12 years ago
|
||
Assignee | ||
Comment 10•12 years ago
|
||
Waiting on try run to land.
Attachment #679047 -
Attachment is obsolete: true
Attachment #683343 -
Flags: review+
Comment 11•12 years ago
|
||
Status: ASSIGNED → RESOLVED
Closed: 12 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla20
Assignee | ||
Comment 12•12 years ago
|
||
rebase
Attachment #683343 -
Attachment is obsolete: true
Attachment #688897 -
Flags: review+
Assignee | ||
Updated•12 years ago
|
Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Assignee | ||
Comment 13•12 years ago
|
||
Comment 14•12 years ago
|
||
Backed out for MSVC bustage:
e:/builds/moz2_slave/m-in-w32/build/tools/profiler/TableTicker.cpp(834) : error C3861: 'GetThreadHandle': identifier not found
e:/builds/moz2_slave/m-in-w32/build/tools/profiler/TableTicker.cpp(834) : error C3861: 'platform_data': identifier not found
e:/builds/moz2_slave/m-in-w32/build/tools/profiler/TableTicker.cpp(883) : error C2664: 'doMergeBacktrace' : cannot convert parameter 3 from 'uintptr_t' to 'void *'
e:/builds/moz2_slave/m-in-w32/build/tools/profiler/TableTicker.cpp(1318) : error C3861: '__builtin_frame_address': identifier not found
https://tbpl.mozilla.org/php/getParsedLog.php?id=17645063&tree=Mozilla-Inbound
https://hg.mozilla.org/integration/mozilla-inbound/rev/76322fb13033
Assignee | ||
Comment 15•12 years ago
|
||
Assignee | ||
Updated•12 years ago
|
Attachment #683331 -
Flags: checkin+
Assignee | ||
Comment 16•12 years ago
|
||
Flags: needinfo?(bgirard)
Assignee | ||
Comment 17•12 years ago
|
||
Flags: needinfo?(bgirard)
Comment 18•12 years ago
|
||
Assignee | ||
Comment 19•12 years ago
|
||
*sigh*. I got the patch from my try run but it's not identical. I got mixed up somehow.
Assignee | ||
Updated•12 years ago
|
Flags: needinfo?(bgirard)
Assignee | ||
Updated•11 years ago
|
Flags: needinfo?(bgirard)
Comment 20•10 years ago
|
||
Note for posterity: the code from part 1 was also removed in bug 1135236.
Assignee | ||
Comment 21•9 years ago
|
||
I don't plan on implementing this anymore.
Status: REOPENED → RESOLVED
Closed: 12 years ago → 9 years ago
Resolution: --- → WONTFIX
You need to log in
before you can comment on or make changes to this bug.
Description
•