2 * mprof-report.c: mprof-report program source: decode and analyze the log profiler data
5 * Paolo Molaro (lupus@ximian.com)
6 * Alex Rønne Petersen (alexrp@xamarin.com)
8 * Copyright 2010 Novell, Inc (http://www.novell.com)
9 * Licensed under the MIT license. See LICENSE file in the project root for full license information.
13 * The Coverage XML output schema
23 * <coverage> - The root element of the documentation. It can contain any number of
24 * <assembly>, <class> or <method> elements.
26 * - version: The version number for the file format - (eg: "0.3")
27 * <assembly> - Contains data about assemblies. Has no child elements
29 * - name: The name of the assembly - (eg: "System.Xml")
30 * - guid: The GUID of the assembly
31 * - filename: The filename of the assembly
32 * - method-count: The number of methods in the assembly
33 * - full: The number of fully covered methods
34 * - partial: The number of partially covered methods
35 * <class> - Contains data about classes. Has no child elements
37 * - name: The name of the class
38 * - method-count: The number of methods in the class
39 * - full: The number of fully covered methods
40 * - partial: The number of partially covered methods
41 * <method> - Contains data about methods. Can contain any number of <statement> elements
43 * - assembly: The name of the parent assembly
44 * - class: The name of the parent class
45 * - name: The name of the method, with all it's parameters
46 * - filename: The name of the source file containing this method
48 * <statement> - Contains data about IL statements. Has no child elements
50 * - offset: The offset of the statement in the IL code after the previous
52 * - counter: 1 if the line was covered, 0 if it was not
53 * - line: The line number in the parent method's file
54 * - column: The column on the line
62 #if !defined(__APPLE__) && !defined(__FreeBSD__)
67 #if defined (HAVE_SYS_ZLIB)
71 #include <mono/metadata/profiler.h>
72 #include <mono/metadata/object.h>
73 #include <mono/metadata/debug-helpers.h>
74 #include <mono/utils/mono-counters.h>
76 #define HASH_SIZE 9371
77 #define SMALL_HASH_SIZE 31
80 static int collect_traces = 0;
81 static int show_traces = 0;
82 static int trace_max = 6;
83 static int verbose = 0;
84 static uintptr_t *tracked_objects = 0;
85 static int num_tracked_objects = 0;
86 static uintptr_t thread_filter = 0;
87 static uint64_t find_size = 0;
88 static const char* find_name = NULL;
89 static uint64_t time_from = 0;
90 static uint64_t time_to = 0xffffffffffffffffULL;
91 static int use_time_filter = 0;
92 static uint64_t startup_time = 0;
93 static FILE* outfile = NULL;
94 static FILE* coverage_outfile = NULL;
97 read_int16 (unsigned char *p)
100 value |= (*p++) << 8;
105 read_int32 (unsigned char *p)
107 int32_t value = *p++;
108 value |= (*p++) << 8;
109 value |= (*p++) << 16;
110 value |= (uint32_t)(*p++) << 24;
115 read_int64 (unsigned char *p)
117 uint64_t value = *p++;
118 value |= (*p++) << 8;
119 value |= (*p++) << 16;
120 value |= (uint64_t)(*p++) << 24;
121 value |= (uint64_t)(*p++) << 32;
122 value |= (uint64_t)(*p++) << 40;
123 value |= (uint64_t)(*p++) << 48;
124 value |= (uint64_t)(*p++) << 54;
129 pstrdup (const char *s)
131 int len = strlen (s) + 1;
132 char *p = (char *) g_malloc (len);
137 typedef struct _CounterValue CounterValue;
138 struct _CounterValue {
140 unsigned char *buffer;
144 typedef struct _Counter Counter;
152 CounterValue *values;
153 CounterValue *values_last;
156 typedef struct _CounterList CounterList;
157 struct _CounterList {
162 typedef struct _CounterSection CounterSection;
163 struct _CounterSection {
165 CounterList *counters;
166 CounterList *counters_last;
167 CounterSection *next;
170 typedef struct _CounterTimestamp CounterTimestamp;
171 struct _CounterTimestamp {
173 CounterSection *sections;
174 CounterSection *sections_last;
175 CounterTimestamp *next;
178 static CounterList *counters = NULL;
179 static CounterSection *counters_sections = NULL;
180 static CounterTimestamp *counters_timestamps = NULL;
184 COUNTERS_SORT_CATEGORY
187 static int counters_sort_mode = COUNTERS_SORT_TIME;
190 add_counter_to_section (Counter *counter)
192 CounterSection *csection, *s;
195 clist = (CounterList *) g_calloc (1, sizeof (CounterList));
196 clist->counter = counter;
198 for (csection = counters_sections; csection; csection = csection->next) {
199 if (strcmp (csection->value, counter->section) == 0) {
200 /* If section exist */
201 if (!csection->counters)
202 csection->counters = clist;
204 csection->counters_last->next = clist;
205 csection->counters_last = clist;
210 /* If section does not exist */
211 csection = (CounterSection *) g_calloc (1, sizeof (CounterSection));
212 csection->value = counter->section;
213 csection->counters = clist;
214 csection->counters_last = clist;
216 if (!counters_sections) {
217 counters_sections = csection;
219 s = counters_sections;
227 add_counter (const char *section, const char *name, int type, int unit, int variance, int index)
229 CounterList *list, *l;
232 for (list = counters; list; list = list->next)
233 if (list->counter->index == index)
236 counter = (Counter *) g_calloc (1, sizeof (Counter));
237 counter->section = section;
238 counter->name = name;
239 counter->type = type;
240 counter->unit = unit;
241 counter->variance = variance;
242 counter->index = index;
244 list = (CounterList *) g_calloc (1, sizeof (CounterList));
245 list->counter = counter;
256 if (counters_sort_mode == COUNTERS_SORT_CATEGORY || !verbose)
257 add_counter_to_section (counter);
261 add_counter_to_timestamp (uint64_t timestamp, Counter *counter)
263 CounterTimestamp *ctimestamp, *t;
264 CounterSection *csection;
267 clist = (CounterList *) g_calloc (1, sizeof (CounterList));
268 clist->counter = counter;
270 for (ctimestamp = counters_timestamps; ctimestamp; ctimestamp = ctimestamp->next) {
271 if (ctimestamp->value == timestamp) {
272 for (csection = ctimestamp->sections; csection; csection = csection->next) {
273 if (strcmp (csection->value, counter->section) == 0) {
274 /* if timestamp exist and section exist */
275 if (!csection->counters)
276 csection->counters = clist;
278 csection->counters_last->next = clist;
279 csection->counters_last = clist;
284 /* if timestamp exist and section does not exist */
285 csection = (CounterSection *) g_calloc (1, sizeof (CounterSection));
286 csection->value = counter->section;
287 csection->counters = clist;
288 csection->counters_last = clist;
290 if (!ctimestamp->sections)
291 ctimestamp->sections = csection;
293 ctimestamp->sections_last->next = csection;
294 ctimestamp->sections_last = csection;
299 /* If timestamp do not exist and section does not exist */
300 csection = (CounterSection *) g_calloc (1, sizeof (CounterSection));
301 csection->value = counter->section;
302 csection->counters = clist;
303 csection->counters_last = clist;
305 ctimestamp = (CounterTimestamp *) g_calloc (1, sizeof (CounterTimestamp));
306 ctimestamp->value = timestamp;
307 ctimestamp->sections = csection;
308 ctimestamp->sections_last = csection;
310 if (!counters_timestamps) {
311 counters_timestamps = ctimestamp;
313 t = counters_timestamps;
316 t->next = ctimestamp;
321 add_counter_value (int index, CounterValue *value)
325 for (list = counters; list; list = list->next) {
326 if (list->counter->index == index) {
327 if (!list->counter->values)
328 list->counter->values = value;
330 list->counter->values_last->next = value;
331 list->counter->values_last = value;
333 if (counters_sort_mode == COUNTERS_SORT_TIME)
334 add_counter_to_timestamp (value->timestamp, list->counter);
342 section_name (int section)
345 case MONO_COUNTER_JIT: return "Mono JIT";
346 case MONO_COUNTER_GC: return "Mono GC";
347 case MONO_COUNTER_METADATA: return "Mono Metadata";
348 case MONO_COUNTER_GENERICS: return "Mono Generics";
349 case MONO_COUNTER_SECURITY: return "Mono Security";
350 case MONO_COUNTER_RUNTIME: return "Mono Runtime";
351 case MONO_COUNTER_SYSTEM: return "Mono System";
352 case MONO_COUNTER_PROFILER: return "Mono Profiler";
353 default: return "<unknown>";
361 case MONO_COUNTER_INT: return "Int";
362 case MONO_COUNTER_UINT: return "UInt";
363 case MONO_COUNTER_WORD: return "Word";
364 case MONO_COUNTER_LONG: return "Long";
365 case MONO_COUNTER_ULONG: return "ULong";
366 case MONO_COUNTER_DOUBLE: return "Double";
367 case MONO_COUNTER_STRING: return "String";
368 case MONO_COUNTER_TIME_INTERVAL: return "Time Interval";
369 default: return "<unknown>";
377 case MONO_COUNTER_RAW: return "Raw";
378 case MONO_COUNTER_BYTES: return "Bytes";
379 case MONO_COUNTER_TIME: return "Time";
380 case MONO_COUNTER_COUNT: return "Count";
381 case MONO_COUNTER_PERCENTAGE: return "Percentage";
382 default: return "<unknown>";
387 variance_name (int variance)
390 case MONO_COUNTER_MONOTONIC: return "Monotonic";
391 case MONO_COUNTER_CONSTANT: return "Constant";
392 case MONO_COUNTER_VARIABLE: return "Variable";
393 default: return "<unknown>";
398 dump_counters_value (Counter *counter, const char *key_format, const char *key, void *value)
403 snprintf (format, sizeof (format), "%s : %%s\n", key_format);
404 fprintf (outfile, format, key, "<null>");
406 switch (counter->type) {
407 case MONO_COUNTER_INT:
408 #if SIZEOF_VOID_P == 4
409 case MONO_COUNTER_WORD:
411 snprintf (format, sizeof (format), "%s : %%d\n", key_format);
412 fprintf (outfile, format, key, *(int32_t*)value);
414 case MONO_COUNTER_UINT:
415 snprintf (format, sizeof (format), "%s : %%u\n", key_format);
416 fprintf (outfile, format, key, *(uint32_t*)value);
418 case MONO_COUNTER_LONG:
419 #if SIZEOF_VOID_P == 8
420 case MONO_COUNTER_WORD:
422 case MONO_COUNTER_TIME_INTERVAL:
423 if (counter->type == MONO_COUNTER_LONG && counter->unit == MONO_COUNTER_TIME) {
424 snprintf (format, sizeof (format), "%s : %%0.3fms\n", key_format);
425 fprintf (outfile, format, key, (double)*(int64_t*)value / 10000.0);
426 } else if (counter->type == MONO_COUNTER_TIME_INTERVAL) {
427 snprintf (format, sizeof (format), "%s : %%0.3fms\n", key_format);
428 fprintf (outfile, format, key, (double)*(int64_t*)value / 1000.0);
430 snprintf (format, sizeof (format), "%s : %%u\n", key_format);
431 fprintf (outfile, format, key, *(int64_t*)value);
434 case MONO_COUNTER_ULONG:
435 snprintf (format, sizeof (format), "%s : %%llu\n", key_format);
436 fprintf (outfile, format, key, *(uint64_t*)value);
438 case MONO_COUNTER_DOUBLE:
439 snprintf (format, sizeof (format), "%s : %%f\n", key_format);
440 fprintf (outfile, format, key, *(double*)value);
442 case MONO_COUNTER_STRING:
443 snprintf (format, sizeof (format), "%s : %%s\n", key_format);
444 fprintf (outfile, format, key, *(char*)value);
454 CounterValue *cvalue;
455 CounterTimestamp *ctimestamp;
456 CounterSection *csection;
458 char strtimestamp[17];
459 int i, section_printed;
461 fprintf (outfile, "\nCounters:\n");
464 char counters_to_print[][64] = {
466 "Methods JITted using mono JIT",
467 "Methods JITted using LLVM",
468 "Total time spent JITting (sec)",
476 "CPU Load Average - 1min",
477 "CPU Load Average - 5min",
478 "CPU Load Average - 15min",
482 for (csection = counters_sections; csection; csection = csection->next) {
485 for (clist = csection->counters; clist; clist = clist->next) {
486 counter = clist->counter;
487 if (!counter->values_last)
490 for (i = 0; counters_to_print [i][0] != 0; i++) {
491 if (strcmp (counters_to_print [i], counter->name) == 0) {
492 if (!section_printed) {
493 fprintf (outfile, "\t%s:\n", csection->value);
497 dump_counters_value (counter, "\t\t%-30s", counter->name, counter->values_last->buffer);
503 } else if (counters_sort_mode == COUNTERS_SORT_TIME) {
504 for (ctimestamp = counters_timestamps; ctimestamp; ctimestamp = ctimestamp->next) {
505 fprintf (outfile, "\t%llu:%02llu:%02llu:%02llu.%03llu:\n",
506 (unsigned long long) (ctimestamp->value / 1000 / 60 / 60 / 24 % 1000),
507 (unsigned long long) (ctimestamp->value / 1000 / 60 / 60 % 24),
508 (unsigned long long) (ctimestamp->value / 1000 / 60 % 60),
509 (unsigned long long) (ctimestamp->value / 1000 % 60),
510 (unsigned long long) (ctimestamp->value % 1000));
512 for (csection = ctimestamp->sections; csection; csection = csection->next) {
513 fprintf (outfile, "\t\t%s:\n", csection->value);
515 for (clist = csection->counters; clist; clist = clist->next) {
516 counter = clist->counter;
517 for (cvalue = counter->values; cvalue; cvalue = cvalue->next) {
518 if (cvalue->timestamp != ctimestamp->value)
521 dump_counters_value (counter, "\t\t\t%-30s", counter->name, cvalue->buffer);
526 } else if (counters_sort_mode == COUNTERS_SORT_CATEGORY) {
527 for (csection = counters_sections; csection; csection = csection->next) {
528 fprintf (outfile, "\t%s:\n", csection->value);
530 for (clist = csection->counters; clist; clist = clist->next) {
531 counter = clist->counter;
532 fprintf (outfile, "\t\t%s: [type: %s, unit: %s, variance: %s]\n",
533 counter->name, type_name (counter->type), unit_name (counter->unit), variance_name (counter->variance));
535 for (cvalue = counter->values; cvalue; cvalue = cvalue->next) {
536 snprintf (strtimestamp, sizeof (strtimestamp), "%llu:%02llu:%02llu:%02llu.%03llu",
537 (unsigned long long) (cvalue->timestamp / 1000 / 60 / 60 / 24 % 1000),
538 (unsigned long long) (cvalue->timestamp / 1000 / 60 / 60 % 24),
539 (unsigned long long) (cvalue->timestamp / 1000 / 60 % 60),
540 (unsigned long long) (cvalue->timestamp / 1000 % 60),
541 (unsigned long long) (cvalue->timestamp % 1000));
543 dump_counters_value (counter, "\t\t\t%s", strtimestamp, cvalue->buffer);
550 static int num_images;
551 typedef struct _ImageDesc ImageDesc;
558 static ImageDesc* image_hash [SMALL_HASH_SIZE] = {0};
561 add_image (intptr_t image, char *name)
563 int slot = ((image >> 2) & 0xffff) % SMALL_HASH_SIZE;
564 ImageDesc *cd = (ImageDesc *) g_malloc (sizeof (ImageDesc));
566 cd->filename = pstrdup (name);
567 cd->next = image_hash [slot];
568 image_hash [slot] = cd;
572 static int num_assemblies;
574 typedef struct _AssemblyDesc AssemblyDesc;
575 struct _AssemblyDesc {
581 static AssemblyDesc* assembly_hash [SMALL_HASH_SIZE] = {0};
584 add_assembly (intptr_t assembly, char *name)
586 int slot = ((assembly >> 2) & 0xffff) % SMALL_HASH_SIZE;
587 AssemblyDesc *cd = (AssemblyDesc *) g_malloc (sizeof (AssemblyDesc));
588 cd->assembly = assembly;
589 cd->asmname = pstrdup (name);
590 cd->next = assembly_hash [slot];
591 assembly_hash [slot] = cd;
595 typedef struct _BackTrace BackTrace;
607 typedef struct _ClassDesc ClassDesc;
617 static ClassDesc* class_hash [HASH_SIZE] = {0};
618 static int num_classes = 0;
621 add_class (intptr_t klass, const char *name)
623 int slot = ((klass >> 2) & 0xffff) % HASH_SIZE;
625 cd = class_hash [slot];
626 while (cd && cd->klass != klass)
628 /* we resolved an unknown class (unless we had the code unloaded) */
630 /*printf ("resolved unknown: %s\n", name);*/
632 cd->name = pstrdup (name);
635 cd = (ClassDesc *) g_calloc (sizeof (ClassDesc), 1);
637 cd->name = pstrdup (name);
638 cd->next = class_hash [slot];
641 cd->traces.count = 0;
643 cd->traces.traces = NULL;
644 class_hash [slot] = cd;
650 lookup_class (intptr_t klass)
652 int slot = ((klass >> 2) & 0xffff) % HASH_SIZE;
653 ClassDesc *cd = class_hash [slot];
654 while (cd && cd->klass != klass)
658 snprintf (buf, sizeof (buf), "unresolved class %p", (void*)klass);
659 return add_class (klass, buf);
664 typedef struct _MethodDesc MethodDesc;
673 int ignore_jit; /* when this is set, we collect the metadata but don't count this method fot jit time and code size, when filtering events */
676 uint64_t callee_time;
681 static MethodDesc* method_hash [HASH_SIZE] = {0};
682 static int num_methods = 0;
685 add_method (intptr_t method, const char *name, intptr_t code, int len)
687 int slot = ((method >> 2) & 0xffff) % HASH_SIZE;
689 cd = method_hash [slot];
690 while (cd && cd->method != method)
692 /* we resolved an unknown method (unless we had the code unloaded) */
696 /*printf ("resolved unknown: %s\n", name);*/
698 cd->name = pstrdup (name);
701 cd = (MethodDesc *) g_calloc (sizeof (MethodDesc), 1);
703 cd->name = pstrdup (name);
708 cd->traces.count = 0;
710 cd->traces.traces = NULL;
711 cd->next = method_hash [slot];
712 method_hash [slot] = cd;
718 lookup_method (intptr_t method)
720 int slot = ((method >> 2) & 0xffff) % HASH_SIZE;
721 MethodDesc *cd = method_hash [slot];
722 while (cd && cd->method != method)
726 snprintf (buf, sizeof (buf), "unknown method %p", (void*)method);
727 return add_method (method, buf, 0, 0);
732 static int num_stat_samples = 0;
733 static int size_stat_samples = 0;
734 uintptr_t *stat_samples = NULL;
735 int *stat_sample_desc = NULL;
738 add_stat_sample (int type, uintptr_t ip) {
739 if (num_stat_samples == size_stat_samples) {
740 size_stat_samples *= 2;
741 if (!size_stat_samples)
742 size_stat_samples = 32;
743 stat_samples = (uintptr_t *) g_realloc (stat_samples, size_stat_samples * sizeof (uintptr_t));
744 stat_sample_desc = (int *) g_realloc (stat_sample_desc, size_stat_samples * sizeof (int));
746 stat_samples [num_stat_samples] = ip;
747 stat_sample_desc [num_stat_samples++] = type;
751 lookup_method_by_ip (uintptr_t ip)
756 for (i = 0; i < HASH_SIZE; ++i) {
759 //printf ("checking %p against %p-%p\n", (void*)ip, (void*)(m->code), (void*)(m->code + m->len));
760 if (ip >= (uintptr_t)m->code && ip < (uintptr_t)m->code + m->len) {
770 compare_method_samples (const void *a, const void *b)
772 MethodDesc *const *A = (MethodDesc *const *)a;
773 MethodDesc *const *B = (MethodDesc *const *)b;
774 if ((*A)->sample_hits == (*B)->sample_hits)
776 if ((*B)->sample_hits < (*A)->sample_hits)
781 typedef struct _UnmanagedSymbol UnmanagedSymbol;
782 struct _UnmanagedSymbol {
783 UnmanagedSymbol *parent;
788 uintptr_t sample_hits;
791 static UnmanagedSymbol **usymbols = NULL;
792 static int usymbols_size = 0;
793 static int usymbols_num = 0;
796 compare_usymbol_addr (const void *a, const void *b)
798 UnmanagedSymbol *const *A = (UnmanagedSymbol *const *)a;
799 UnmanagedSymbol *const *B = (UnmanagedSymbol *const *)b;
800 if ((*B)->addr == (*A)->addr)
802 if ((*B)->addr > (*A)->addr)
808 compare_usymbol_samples (const void *a, const void *b)
810 UnmanagedSymbol *const *A = (UnmanagedSymbol *const *)a;
811 UnmanagedSymbol *const *B = (UnmanagedSymbol *const *)b;
812 if ((*B)->sample_hits == (*A)->sample_hits)
814 if ((*B)->sample_hits < (*A)->sample_hits)
820 add_unmanaged_symbol (uintptr_t addr, char *name, uintptr_t size)
822 UnmanagedSymbol *sym;
823 if (usymbols_num == usymbols_size) {
824 int new_size = usymbols_size * 2;
827 usymbols = (UnmanagedSymbol **) g_realloc (usymbols, sizeof (void*) * new_size);
828 usymbols_size = new_size;
830 sym = (UnmanagedSymbol *) g_calloc (sizeof (UnmanagedSymbol), 1);
834 usymbols [usymbols_num++] = sym;
837 /* only valid after the symbols are sorted */
838 static UnmanagedSymbol*
839 lookup_unmanaged_symbol (uintptr_t addr)
841 int r = usymbols_num - 1;
843 UnmanagedSymbol *sym;
848 if (addr == sym->addr)
850 if (addr < sym->addr) {
852 } else if (addr > sym->addr) {
857 if (last_best >= 0 && (addr - usymbols [last_best]->addr) < 4096)
858 return usymbols [last_best];
862 /* we use the same structure for binaries */
863 static UnmanagedSymbol **ubinaries = NULL;
864 static int ubinaries_size = 0;
865 static int ubinaries_num = 0;
868 add_unmanaged_binary (uintptr_t addr, char *name, uintptr_t size)
870 UnmanagedSymbol *sym;
871 if (ubinaries_num == ubinaries_size) {
872 int new_size = ubinaries_size * 2;
875 ubinaries = (UnmanagedSymbol **) g_realloc (ubinaries, sizeof (void*) * new_size);
876 ubinaries_size = new_size;
878 sym = (UnmanagedSymbol *) g_calloc (sizeof (UnmanagedSymbol), 1);
883 ubinaries [ubinaries_num++] = sym;
886 static UnmanagedSymbol*
887 lookup_unmanaged_binary (uintptr_t addr)
890 for (i = 0; i < ubinaries_num; ++i) {
891 UnmanagedSymbol *ubin = ubinaries [i];
892 if (addr >= ubin->addr && addr < ubin->addr + ubin->size) {
899 // For backwards compatibility.
906 SAMPLE_BRANCH_MISSES,
910 MONO_GC_EVENT_MARK_START = 1,
911 MONO_GC_EVENT_MARK_END = 2,
912 MONO_GC_EVENT_RECLAIM_START = 3,
913 MONO_GC_EVENT_RECLAIM_END = 4,
917 sample_type_name (int type)
920 case SAMPLE_CYCLES: return "cycles";
921 case SAMPLE_INSTRUCTIONS: return "instructions retired";
922 case SAMPLE_CACHE_MISSES: return "cache misses";
923 case SAMPLE_CACHE_REFS: return "cache references";
924 case SAMPLE_BRANCHES: return "executed branches";
925 case SAMPLE_BRANCH_MISSES: return "unpredicted branches";
931 set_usym_parent (UnmanagedSymbol** cachedus, int count)
934 for (i = 0; i < count; ++i) {
935 UnmanagedSymbol *ubin = lookup_unmanaged_binary (cachedus [i]->addr);
936 if (ubin == cachedus [i])
938 cachedus [i]->parent = ubin;
943 print_usym (UnmanagedSymbol* um)
946 fprintf (outfile, "\t%6zd %6.2f %-36s in %s\n", um->sample_hits, um->sample_hits*100.0/num_stat_samples, um->name, um->parent->name);
948 fprintf (outfile, "\t%6zd %6.2f %s\n", um->sample_hits, um->sample_hits*100.0/num_stat_samples, um->name);
952 sym_percent (uintptr_t sample_hits)
957 pc = sample_hits*100.0/num_stat_samples;
965 int count = 0, msize = 0;
966 int unmanaged_hits = 0;
967 int unresolved_hits = 0;
968 MethodDesc** cachedm = NULL;
969 int ucount = 0, usize = 0;
970 UnmanagedSymbol** cachedus = NULL;
971 if (!num_stat_samples)
973 qsort (usymbols, usymbols_num, sizeof (UnmanagedSymbol*), compare_usymbol_addr);
974 for (i = 0; i < num_stat_samples; ++i) {
975 MethodDesc *m = lookup_method_by_ip (stat_samples [i]);
977 if (!m->sample_hits) {
978 if (count == msize) {
982 cachedm = (MethodDesc **) g_realloc (cachedm, sizeof (void*) * msize);
984 cachedm [count++] = m;
988 UnmanagedSymbol *usym = lookup_unmanaged_symbol (stat_samples [i]);
991 //printf ("unmanaged hit at %p\n", (void*)stat_samples [i]);
992 usym = lookup_unmanaged_binary (stat_samples [i]);
995 if (!usym->sample_hits) {
996 if (ucount == usize) {
1000 cachedus = (UnmanagedSymbol **) g_realloc (cachedus, sizeof (void*) * usize);
1002 cachedus [ucount++] = usym;
1004 usym->sample_hits++;
1009 qsort (cachedm, count, sizeof (MethodDesc*), compare_method_samples);
1010 qsort (cachedus, ucount, sizeof (UnmanagedSymbol*), compare_usymbol_samples);
1011 set_usym_parent (cachedus, ucount);
1012 fprintf (outfile, "\nStatistical samples summary\n");
1013 fprintf (outfile, "\tSample type: %s\n", sample_type_name (stat_sample_desc [0]));
1014 fprintf (outfile, "\tUnmanaged hits: %6d (%4.1f%%)\n", unmanaged_hits, (100.0*unmanaged_hits)/num_stat_samples);
1015 fprintf (outfile, "\tManaged hits: %6d (%4.1f%%)\n", num_stat_samples - unmanaged_hits, (100.0*(num_stat_samples-unmanaged_hits))/num_stat_samples);
1016 fprintf (outfile, "\tUnresolved hits: %6d (%4.1f%%)\n", unresolved_hits, (100.0*unresolved_hits)/num_stat_samples);
1017 fprintf (outfile, "\t%6s %6s %s\n", "Hits", "%", "Method name");
1020 while (i < count || u < ucount) {
1022 MethodDesc *m = cachedm [i];
1024 UnmanagedSymbol *um = cachedus [u];
1025 if (um->sample_hits > m->sample_hits) {
1026 if (!sym_percent (um->sample_hits))
1033 if (!sym_percent (m->sample_hits))
1035 fprintf (outfile, "\t%6d %6.2f %s\n", m->sample_hits, m->sample_hits*100.0/num_stat_samples, m->name);
1040 UnmanagedSymbol *um = cachedus [u];
1041 if (!sym_percent (um->sample_hits))
1050 typedef struct _HeapClassDesc HeapClassDesc;
1052 HeapClassDesc *klass;
1056 struct _HeapClassDesc {
1060 HeapClassRevRef *rev_hash;
1063 uintptr_t pinned_references;
1064 uintptr_t root_references;
1068 add_rev_class_hashed (HeapClassRevRef *rev_hash, uintptr_t size, HeapClassDesc *hklass, uint64_t value)
1071 uintptr_t start_pos;
1072 start_pos = (hklass->klass->klass >> 2) % size;
1073 assert (start_pos < size);
1076 if (rev_hash [i].klass == hklass) {
1077 rev_hash [i].count += value;
1079 } else if (!rev_hash [i].klass) {
1080 rev_hash [i].klass = hklass;
1081 rev_hash [i].count += value;
1083 for (i = 0; i < size; ++i)
1084 if (rev_hash [i].klass && rev_hash [i].klass->klass == hklass->klass)
1086 assert (start_pos == 1);
1092 } while (i != start_pos);
1093 /* should not happen */
1094 printf ("failed revref store\n");
1099 add_heap_class_rev (HeapClassDesc *from, HeapClassDesc *to)
1102 if (to->rev_count * 2 >= to->rev_hash_size) {
1104 uintptr_t old_size = to->rev_hash_size;
1105 to->rev_hash_size *= 2;
1106 if (to->rev_hash_size == 0)
1107 to->rev_hash_size = 4;
1108 n = (HeapClassRevRef *) g_calloc (sizeof (HeapClassRevRef) * to->rev_hash_size, 1);
1109 for (i = 0; i < old_size; ++i) {
1110 if (to->rev_hash [i].klass)
1111 add_rev_class_hashed (n, to->rev_hash_size, to->rev_hash [i].klass, to->rev_hash [i].count);
1114 g_free (to->rev_hash);
1117 to->rev_count += add_rev_class_hashed (to->rev_hash, to->rev_hash_size, from, 1);
1122 HeapClassDesc *hklass;
1127 typedef struct _HeapShot HeapShot;
1133 HeapClassDesc **class_hash;
1134 HeapClassDesc **sorted;
1135 HeapObjectDesc **objects_hash;
1136 uintptr_t objects_count;
1137 uintptr_t objects_hash_size;
1138 uintptr_t num_roots;
1140 uintptr_t *roots_extra;
1144 static HeapShot *heap_shots = NULL;
1145 static int num_heap_shots = 0;
1148 new_heap_shot (uint64_t timestamp)
1150 HeapShot *hs = (HeapShot *) g_calloc (sizeof (HeapShot), 1);
1152 hs->class_hash = (HeapClassDesc **) g_calloc (sizeof (void*), hs->hash_size);
1153 hs->timestamp = timestamp;
1155 hs->next = heap_shots;
1160 static HeapClassDesc*
1161 heap_class_lookup (HeapShot *hs, ClassDesc *klass)
1164 unsigned int start_pos;
1165 start_pos = ((uintptr_t)klass->klass >> 2) % hs->hash_size;
1168 HeapClassDesc* cd = hs->class_hash [i];
1171 if (cd->klass == klass)
1174 if (++i == hs->hash_size)
1176 } while (i != start_pos);
1181 add_heap_hashed (HeapClassDesc **hash, HeapClassDesc **retv, uintptr_t hsize, ClassDesc *klass, uint64_t size, uint64_t count)
1184 uintptr_t start_pos;
1185 start_pos = ((uintptr_t)klass->klass >> 2) % hsize;
1188 if (hash [i] && hash [i]->klass == klass) {
1189 hash [i]->total_size += size;
1190 hash [i]->count += count;
1193 } else if (!hash [i]) {
1198 hash [i] = (HeapClassDesc *) g_calloc (sizeof (HeapClassDesc), 1);
1199 hash [i]->klass = klass;
1200 hash [i]->total_size += size;
1201 hash [i]->count += count;
1208 } while (i != start_pos);
1209 /* should not happen */
1210 printf ("failed heap class store\n");
1214 static HeapClassDesc*
1215 add_heap_shot_class (HeapShot *hs, ClassDesc *klass, uint64_t size)
1219 if (hs->class_count * 2 >= hs->hash_size) {
1221 int old_size = hs->hash_size;
1223 if (hs->hash_size == 0)
1225 n = (HeapClassDesc **) g_calloc (sizeof (void*) * hs->hash_size, 1);
1226 for (i = 0; i < old_size; ++i) {
1227 res = hs->class_hash [i];
1228 if (hs->class_hash [i])
1229 add_heap_hashed (n, &res, hs->hash_size, hs->class_hash [i]->klass, hs->class_hash [i]->total_size, hs->class_hash [i]->count);
1232 g_free (hs->class_hash);
1236 hs->class_count += add_heap_hashed (hs->class_hash, &res, hs->hash_size, klass, size, 1);
1237 //if (res->count == 1)
1238 // printf ("added heap class: %s\n", res->klass->name);
1242 static HeapObjectDesc*
1243 alloc_heap_obj (uintptr_t objaddr, HeapClassDesc *hklass, uintptr_t num_refs)
1245 HeapObjectDesc* ho = (HeapObjectDesc *) g_calloc (sizeof (HeapObjectDesc) + num_refs * sizeof (uintptr_t), 1);
1246 ho->objaddr = objaddr;
1247 ho->hklass = hklass;
1248 ho->num_refs = num_refs;
1253 heap_shot_find_obj_slot (HeapShot *hs, uintptr_t objaddr)
1256 uintptr_t start_pos;
1257 HeapObjectDesc **hash = hs->objects_hash;
1258 if (hs->objects_hash_size == 0)
1260 start_pos = ((uintptr_t)objaddr >> 3) % hs->objects_hash_size;
1263 if (hash [i] && hash [i]->objaddr == objaddr) {
1265 } else if (!hash [i]) {
1269 if (++i == hs->objects_hash_size)
1271 } while (i != start_pos);
1272 /* should not happen */
1273 //printf ("failed heap obj slot\n");
1277 static HeapObjectDesc*
1278 heap_shot_obj_add_refs (HeapShot *hs, uintptr_t objaddr, uintptr_t num, uintptr_t *ref_offset)
1280 HeapObjectDesc **hash = hs->objects_hash;
1281 uintptr_t i = heap_shot_find_obj_slot (hs, objaddr);
1283 HeapObjectDesc* ho = alloc_heap_obj (objaddr, hash [i]->hklass, hash [i]->num_refs + num);
1284 *ref_offset = hash [i]->num_refs;
1285 memcpy (ho->refs, hash [i]->refs, hash [i]->num_refs * sizeof (uintptr_t));
1290 /* should not happen */
1291 printf ("failed heap obj update\n");
1297 add_heap_hashed_obj (HeapObjectDesc **hash, uintptr_t hsize, HeapObjectDesc *obj)
1300 uintptr_t start_pos;
1301 start_pos = ((uintptr_t)obj->objaddr >> 3) % hsize;
1304 if (hash [i] && hash [i]->objaddr == obj->objaddr) {
1305 printf ("duplicate object!\n");
1307 } else if (!hash [i]) {
1314 } while (i != start_pos);
1315 /* should not happen */
1316 printf ("failed heap obj store\n");
1321 add_heap_shot_obj (HeapShot *hs, HeapObjectDesc *obj)
1324 if (hs->objects_count * 2 >= hs->objects_hash_size) {
1326 uintptr_t old_size = hs->objects_hash_size;
1327 hs->objects_hash_size *= 2;
1328 if (hs->objects_hash_size == 0)
1329 hs->objects_hash_size = 4;
1330 n = (HeapObjectDesc **) g_calloc (sizeof (void*) * hs->objects_hash_size, 1);
1331 for (i = 0; i < old_size; ++i) {
1332 if (hs->objects_hash [i])
1333 add_heap_hashed_obj (n, hs->objects_hash_size, hs->objects_hash [i]);
1335 if (hs->objects_hash)
1336 g_free (hs->objects_hash);
1337 hs->objects_hash = n;
1339 hs->objects_count += add_heap_hashed_obj (hs->objects_hash, hs->objects_hash_size, obj);
1343 heap_shot_resolve_reverse_refs (HeapShot *hs)
1346 for (i = 0; i < hs->objects_hash_size; ++i) {
1348 HeapObjectDesc *ho = hs->objects_hash [i];
1351 for (r = 0; r < ho->num_refs; ++r) {
1352 uintptr_t oi = heap_shot_find_obj_slot (hs, ho->refs [r]);
1353 add_heap_class_rev (ho->hklass, hs->objects_hash [oi]->hklass);
1359 #define MARK_BLACK 2
1362 heap_shot_mark_objects (HeapShot *hs)
1365 unsigned char *marks;
1366 HeapObjectDesc *obj, *ref;
1368 uintptr_t num_marked = 0, num_unmarked;
1369 for (i = 0; i < hs->num_roots; ++i) {
1371 oi = heap_shot_find_obj_slot (hs, hs->roots [i]);
1375 obj = hs->objects_hash [oi];
1377 if (hs->roots_types [i] & MONO_PROFILER_GC_ROOT_PINNING)
1378 cd->pinned_references++;
1379 cd->root_references++;
1383 /* consistency checks: it seems not all the objects are walked in the heap in some cases */
1384 marks = (unsigned char *) g_calloc (hs->objects_hash_size, 1);
1387 for (i = 0; i < hs->num_roots; ++i) {
1388 oi = heap_shot_find_obj_slot (hs, hs->roots [i]);
1390 fprintf (outfile, "root type 0x%x for obj %p (%s) not found in heap\n", hs->roots_types [i], (void*)hs->roots [i], lookup_class (hs->roots_extra [i])->name);
1393 obj = hs->objects_hash [oi];
1395 marks [oi] = obj->num_refs? MARK_GRAY: MARK_BLACK;
1400 while (marked_some) {
1402 for (i = 0; i < hs->objects_hash_size; ++i) {
1403 if (marks [i] != MARK_GRAY)
1405 marks [i] = MARK_BLACK;
1406 obj = hs->objects_hash [i];
1407 for (r = 0; r < obj->num_refs; ++r) {
1408 oi = heap_shot_find_obj_slot (hs, obj->refs [r]);
1410 fprintf (outfile, "referenced obj %p not found in heap\n", (void*)obj->refs [r]);
1413 ref = hs->objects_hash [oi];
1415 marks [oi] = ref->num_refs? MARK_GRAY: MARK_BLACK;
1423 for (i = 0; i < hs->objects_hash_size; ++i) {
1424 if (hs->objects_hash [i] && !marks [i]) {
1426 fprintf (outfile, "object %p (%s) unmarked\n", (void*)hs->objects_hash [i], hs->objects_hash [i]->hklass->klass->name);
1429 fprintf (outfile, "Total unmarked: %zd/%zd\n", num_unmarked, hs->objects_count);
1434 heap_shot_free_objects (HeapShot *hs)
1437 for (i = 0; i < hs->objects_hash_size; ++i) {
1438 HeapObjectDesc *ho = hs->objects_hash [i];
1442 if (hs->objects_hash)
1443 g_free (hs->objects_hash);
1444 hs->objects_hash = NULL;
1445 hs->objects_hash_size = 0;
1446 hs->objects_count = 0;
1455 MethodDesc *methods [1];
1458 static BackTrace *backtrace_hash [HASH_SIZE];
1459 static BackTrace **backtraces = NULL;
1460 static int num_backtraces = 0;
1461 static int next_backtrace = 0;
1464 hash_backtrace (int count, MethodDesc **methods)
1468 for (i = 0; i < count; ++i) {
1469 hash = (hash << 5) - hash + methods [i]->method;
1475 compare_backtrace (BackTrace *bt, int count, MethodDesc **methods)
1478 if (bt->count != count)
1480 for (i = 0; i < count; ++i)
1481 if (methods [i] != bt->methods [i])
1487 add_backtrace (int count, MethodDesc **methods)
1489 int hash = hash_backtrace (count, methods);
1490 int slot = (hash & 0xffff) % HASH_SIZE;
1491 BackTrace *bt = backtrace_hash [slot];
1493 if (bt->hash == hash && compare_backtrace (bt, count, methods))
1497 bt = (BackTrace *) g_malloc (sizeof (BackTrace) + ((count - 1) * sizeof (void*)));
1498 bt->next = backtrace_hash [slot];
1499 backtrace_hash [slot] = bt;
1500 if (next_backtrace == num_backtraces) {
1501 num_backtraces *= 2;
1502 if (!num_backtraces)
1503 num_backtraces = 16;
1504 backtraces = (BackTrace **) g_realloc (backtraces, sizeof (void*) * num_backtraces);
1506 bt->id = next_backtrace++;
1507 backtraces [bt->id] = bt;
1510 for (slot = 0; slot < count; ++slot)
1511 bt->methods [slot] = methods [slot];
1516 typedef struct _MonitorDesc MonitorDesc;
1517 typedef struct _ThreadContext ThreadContext;
1518 typedef struct _DomainContext DomainContext;
1519 typedef struct _RemCtxContext RemCtxContext;
1523 #if defined (HAVE_SYS_ZLIB)
1537 uint64_t startup_time;
1538 ThreadContext *threads;
1539 ThreadContext *current_thread;
1540 DomainContext *domains;
1541 DomainContext *current_domain;
1542 RemCtxContext *remctxs;
1543 RemCtxContext *current_remctx;
1546 struct _ThreadContext {
1547 ThreadContext *next;
1550 /* emulated stack */
1552 uint64_t *time_stack;
1553 uint64_t *callee_time_stack;
1555 uint64_t contention_start;
1556 MonitorDesc *monitor;
1559 HeapShot *current_heap_shot;
1560 uintptr_t num_roots;
1561 uintptr_t size_roots;
1563 uintptr_t *roots_extra;
1565 uint64_t gc_start_times [3];
1568 struct _DomainContext {
1569 DomainContext *next;
1571 const char *friendly_name;
1574 struct _RemCtxContext {
1575 RemCtxContext *next;
1581 ensure_buffer (ProfContext *ctx, int size)
1583 if (ctx->size < size) {
1584 ctx->buf = (unsigned char *) g_realloc (ctx->buf, size);
1590 load_data (ProfContext *ctx, int size)
1592 ensure_buffer (ctx, size);
1593 #if defined (HAVE_SYS_ZLIB)
1595 int r = gzread (ctx->gzfile, ctx->buf, size);
1597 return size == 0? 1: 0;
1602 int r = fread (ctx->buf, size, 1, ctx->file);
1604 return size == 0? 1: 0;
1609 static ThreadContext*
1610 get_thread (ProfContext *ctx, intptr_t thread_id)
1612 ThreadContext *thread;
1613 if (ctx->current_thread && ctx->current_thread->thread_id == thread_id)
1614 return ctx->current_thread;
1615 thread = ctx->threads;
1617 if (thread->thread_id == thread_id) {
1620 thread = thread->next;
1622 thread = (ThreadContext *) g_calloc (sizeof (ThreadContext), 1);
1623 thread->next = ctx->threads;
1624 ctx->threads = thread;
1625 thread->thread_id = thread_id;
1626 thread->last_time = 0;
1627 thread->stack_id = 0;
1628 thread->stack_size = 32;
1629 thread->stack = (MethodDesc **) g_malloc (thread->stack_size * sizeof (void*));
1630 thread->time_stack = (uint64_t *) g_malloc (thread->stack_size * sizeof (uint64_t));
1631 thread->callee_time_stack = (uint64_t *) g_malloc (thread->stack_size * sizeof (uint64_t));
1635 static DomainContext *
1636 get_domain (ProfContext *ctx, intptr_t domain_id)
1638 if (ctx->current_domain && ctx->current_domain->domain_id == domain_id)
1639 return ctx->current_domain;
1641 DomainContext *domain = ctx->domains;
1644 if (domain->domain_id == domain_id)
1647 domain = domain->next;
1650 domain = (DomainContext *) g_calloc (sizeof (DomainContext), 1);
1651 domain->next = ctx->domains;
1652 ctx->domains = domain;
1653 domain->domain_id = domain_id;
1658 static RemCtxContext *
1659 get_remctx (ProfContext *ctx, intptr_t remctx_id)
1661 if (ctx->current_remctx && ctx->current_remctx->remctx_id == remctx_id)
1662 return ctx->current_remctx;
1664 RemCtxContext *remctx = ctx->remctxs;
1667 if (remctx->remctx_id == remctx_id)
1670 remctx = remctx->next;
1673 remctx = (RemCtxContext *) g_calloc (sizeof (RemCtxContext), 1);
1674 remctx->next = ctx->remctxs;
1675 ctx->remctxs = remctx;
1676 remctx->remctx_id = remctx_id;
1681 static ThreadContext*
1682 load_thread (ProfContext *ctx, intptr_t thread_id)
1684 ThreadContext *thread = get_thread (ctx, thread_id);
1685 ctx->current_thread = thread;
1690 ensure_thread_stack (ThreadContext *thread)
1692 if (thread->stack_id == thread->stack_size) {
1693 thread->stack_size *= 2;
1694 thread->stack = (MethodDesc **) g_realloc (thread->stack, thread->stack_size * sizeof (void*));
1695 thread->time_stack = (uint64_t *) g_realloc (thread->time_stack, thread->stack_size * sizeof (uint64_t));
1696 thread->callee_time_stack = (uint64_t *) g_realloc (thread->callee_time_stack, thread->stack_size * sizeof (uint64_t));
1701 add_trace_hashed (CallContext *traces, int size, BackTrace *bt, uint64_t value)
1704 unsigned int start_pos;
1705 start_pos = bt->hash % size;
1708 if (traces [i].bt == bt) {
1709 traces [i].count += value;
1711 } else if (!traces [i].bt) {
1713 traces [i].count += value;
1719 } while (i != start_pos);
1720 /* should not happen */
1721 printf ("failed trace store\n");
1726 add_trace_bt (BackTrace *bt, TraceDesc *trace, uint64_t value)
1729 if (!collect_traces)
1731 if (trace->count * 2 >= trace->size) {
1733 int old_size = trace->size;
1735 if (trace->size == 0)
1737 n = (CallContext *) g_calloc (sizeof (CallContext) * trace->size, 1);
1738 for (i = 0; i < old_size; ++i) {
1739 if (trace->traces [i].bt)
1740 add_trace_hashed (n, trace->size, trace->traces [i].bt, trace->traces [i].count);
1743 g_free (trace->traces);
1746 trace->count += add_trace_hashed (trace->traces, trace->size, bt, value);
1750 add_trace_thread (ThreadContext *thread, TraceDesc *trace, uint64_t value)
1753 int count = thread->stack_id;
1754 if (!collect_traces)
1756 if (count > trace_max)
1758 bt = add_backtrace (count, thread->stack + thread->stack_id - count);
1759 add_trace_bt (bt, trace, value);
1764 add_trace_methods (MethodDesc **methods, int count, TraceDesc *trace, uint64_t value)
1767 if (!collect_traces)
1769 if (count > trace_max)
1771 bt = add_backtrace (count, methods);
1772 add_trace_bt (bt, trace, value);
1777 thread_add_root (ThreadContext *ctx, uintptr_t obj, int root_type, uintptr_t extra_info)
1779 if (ctx->num_roots == ctx->size_roots) {
1780 int new_size = ctx->size_roots * 2;
1783 ctx->roots = (uintptr_t *) g_realloc (ctx->roots, new_size * sizeof (uintptr_t));
1784 ctx->roots_extra = (uintptr_t *) g_realloc (ctx->roots_extra, new_size * sizeof (uintptr_t));
1785 ctx->roots_types = (int *) g_realloc (ctx->roots_types, new_size * sizeof (int));
1786 ctx->size_roots = new_size;
1788 ctx->roots_types [ctx->num_roots] = root_type;
1789 ctx->roots_extra [ctx->num_roots] = extra_info;
1790 ctx->roots [ctx->num_roots++] = obj;
1794 compare_callc (const void *a, const void *b)
1796 const CallContext *A = (const CallContext *)a;
1797 const CallContext *B = (const CallContext *)b;
1798 if (B->count == A->count)
1800 if (B->count < A->count)
1806 sort_context_array (TraceDesc* traces)
1809 for (i = 0, j = 0; i < traces->size; ++i) {
1810 if (traces->traces [i].bt) {
1811 traces->traces [j].bt = traces->traces [i].bt;
1812 traces->traces [j].count = traces->traces [i].count;
1816 qsort (traces->traces, traces->count, sizeof (CallContext), compare_callc);
1820 push_method (ThreadContext *thread, MethodDesc *method, uint64_t timestamp)
1822 ensure_thread_stack (thread);
1823 thread->time_stack [thread->stack_id] = timestamp;
1824 thread->callee_time_stack [thread->stack_id] = 0;
1825 thread->stack [thread->stack_id++] = method;
1826 method->recurse_count++;
1830 pop_method (ThreadContext *thread, MethodDesc *method, uint64_t timestamp)
1832 method->recurse_count--;
1833 if (thread->stack_id > 0 && thread->stack [thread->stack_id - 1] == method) {
1837 if (timestamp < thread->time_stack [thread->stack_id])
1838 fprintf (outfile, "time went backwards for %s\n", method->name);
1839 tdiff = timestamp - thread->time_stack [thread->stack_id];
1840 if (thread->callee_time_stack [thread->stack_id] > tdiff)
1841 fprintf (outfile, "callee time bigger for %s\n", method->name);
1842 method->self_time += tdiff - thread->callee_time_stack [thread->stack_id];
1843 method->callee_time += thread->callee_time_stack [thread->stack_id];
1844 if (thread->stack_id)
1845 thread->callee_time_stack [thread->stack_id - 1] += tdiff;
1846 //fprintf (outfile, "method %s took %d\n", method->name, (int)(tdiff/1000));
1848 fprintf (outfile, "unmatched leave at stack pos: %d for method %s\n", thread->stack_id, method->name);
1853 uint64_t total_time;
1857 static GCDesc gc_info [3];
1858 static uint64_t max_heap_size;
1859 static uint64_t gc_object_moves;
1860 static int gc_resizes;
1867 TraceDesc destroy_traces;
1869 static HandleInfo handle_info [4];
1872 gc_event_name (int ev)
1875 case MONO_GC_EVENT_START: return "start";
1876 case MONO_GC_EVENT_MARK_START: return "mark start";
1877 case MONO_GC_EVENT_MARK_END: return "mark end";
1878 case MONO_GC_EVENT_RECLAIM_START: return "reclaim start";
1879 case MONO_GC_EVENT_RECLAIM_END: return "reclaim end";
1880 case MONO_GC_EVENT_END: return "end";
1881 case MONO_GC_EVENT_PRE_STOP_WORLD: return "pre stop";
1882 case MONO_GC_EVENT_PRE_STOP_WORLD_LOCKED: return "pre stop lock";
1883 case MONO_GC_EVENT_POST_STOP_WORLD: return "post stop";
1884 case MONO_GC_EVENT_PRE_START_WORLD: return "pre start";
1885 case MONO_GC_EVENT_POST_START_WORLD: return "post start";
1886 case MONO_GC_EVENT_POST_START_WORLD_UNLOCKED: return "post start unlock";
1893 sync_point_name (int type)
1896 case SYNC_POINT_PERIODIC: return "periodic";
1897 case SYNC_POINT_WORLD_STOP: return "world stop";
1898 case SYNC_POINT_WORLD_START: return "world start";
1904 static uint64_t clause_summary [MONO_EXCEPTION_CLAUSE_FAULT + 1];
1905 static uint64_t throw_count = 0;
1906 static TraceDesc exc_traces;
1909 clause_name (int type)
1912 case MONO_EXCEPTION_CLAUSE_NONE: return "catch";
1913 case MONO_EXCEPTION_CLAUSE_FILTER: return "filter";
1914 case MONO_EXCEPTION_CLAUSE_FINALLY: return "finally";
1915 case MONO_EXCEPTION_CLAUSE_FAULT: return "fault";
1916 default: return "invalid";
1920 static uint64_t monitor_contention;
1921 static uint64_t monitor_failed;
1922 static uint64_t monitor_acquired;
1924 struct _MonitorDesc {
1927 uintptr_t contentions;
1929 uint64_t max_wait_time;
1933 static MonitorDesc* monitor_hash [SMALL_HASH_SIZE] = {0};
1934 static int num_monitors = 0;
1937 lookup_monitor (uintptr_t objid)
1939 int slot = ((objid >> 3) & 0xffff) % SMALL_HASH_SIZE;
1940 MonitorDesc *cd = monitor_hash [slot];
1941 while (cd && cd->objid != objid)
1944 cd = (MonitorDesc *) g_calloc (sizeof (MonitorDesc), 1);
1946 cd->next = monitor_hash [slot];
1947 monitor_hash [slot] = cd;
1954 monitor_ev_name (int ev)
1957 case MONO_PROFILER_MONITOR_CONTENTION: return "contended";
1958 case MONO_PROFILER_MONITOR_DONE: return "acquired";
1959 case MONO_PROFILER_MONITOR_FAIL: return "not taken";
1960 default: return "invalid";
1965 get_handle_name (int htype)
1968 case 0: return "weak";
1969 case 1: return "weaktrack";
1970 case 2: return "normal";
1971 case 3: return "pinned";
1972 default: return "unknown";
1977 get_root_name (int rtype)
1979 switch (rtype & MONO_PROFILER_GC_ROOT_TYPEMASK) {
1980 case MONO_PROFILER_GC_ROOT_STACK: return "stack";
1981 case MONO_PROFILER_GC_ROOT_FINALIZER: return "finalizer";
1982 case MONO_PROFILER_GC_ROOT_HANDLE: return "handle";
1983 case MONO_PROFILER_GC_ROOT_OTHER: return "other";
1984 case MONO_PROFILER_GC_ROOT_MISC: return "misc";
1985 default: return "unknown";
1990 decode_uleb128 (uint8_t *buf, uint8_t **endbuf)
1997 res |= (((uint64_t) (b & 0x7f)) << shift);
2011 decode_sleb128 (uint8_t *buf, uint8_t **endbuf)
2021 res = res | (((intptr_t) (b & 0x7f)) << shift);
2025 if (shift < sizeof (intptr_t) * 8 && (b & 0x40))
2026 res |= - ((intptr_t) 1 << shift);
2038 decode_bt (ProfContext *ctx, MethodDesc** sframes, int *size, unsigned char *p, unsigned char **endp, intptr_t ptr_base, intptr_t *method_base)
2040 MethodDesc **frames;
2042 if (ctx->data_version < 13)
2043 decode_uleb128 (p, &p); /* flags */
2044 int count = decode_uleb128 (p, &p);
2046 frames = (MethodDesc **) g_malloc (count * sizeof (void*));
2049 for (i = 0; i < count; ++i) {
2050 intptr_t ptrdiff = decode_sleb128 (p, &p);
2051 if (ctx->data_version > 12) {
2052 *method_base += ptrdiff;
2053 frames [i] = lookup_method (*method_base);
2055 frames [i] = lookup_method (ptr_base + ptrdiff);
2064 tracked_creation (uintptr_t obj, ClassDesc *cd, uint64_t size, BackTrace *bt, uint64_t timestamp)
2067 for (i = 0; i < num_tracked_objects; ++i) {
2068 if (tracked_objects [i] != obj)
2070 fprintf (outfile, "Object %p created (%s, %llu bytes) at %.3f secs.\n", (void*)obj, cd->name, (unsigned long long) size, (timestamp - startup_time)/1000000000.0);
2071 if (bt && bt->count) {
2073 for (k = 0; k < bt->count; ++k)
2074 fprintf (outfile, "\t%s\n", bt->methods [k]->name);
2080 track_handle (uintptr_t obj, int htype, uint32_t handle, BackTrace *bt, uint64_t timestamp)
2083 for (i = 0; i < num_tracked_objects; ++i) {
2084 if (tracked_objects [i] != obj)
2086 fprintf (outfile, "Object %p referenced from handle %u at %.3f secs.\n", (void*)obj, handle, (timestamp - startup_time) / 1000000000.0);
2087 if (bt && bt->count) {
2089 for (k = 0; k < bt->count; ++k)
2090 fprintf (outfile, "\t%s\n", bt->methods [k]->name);
2096 track_move (uintptr_t src, uintptr_t dst)
2099 for (i = 0; i < num_tracked_objects; ++i) {
2100 if (tracked_objects [i] == src)
2101 fprintf (outfile, "Object %p moved to %p\n", (void*)src, (void*)dst);
2102 else if (tracked_objects [i] == dst)
2103 fprintf (outfile, "Object %p moved from %p\n", (void*)dst, (void*)src);
2108 track_obj_reference (uintptr_t obj, uintptr_t parent, ClassDesc *cd)
2111 for (i = 0; i < num_tracked_objects; ++i) {
2112 if (tracked_objects [i] == obj)
2113 fprintf (outfile, "Object %p referenced from %p (%s).\n", (void*)obj, (void*)parent, cd->name);
2118 found_object (uintptr_t obj)
2120 num_tracked_objects ++;
2121 tracked_objects = (uintptr_t *) g_realloc (tracked_objects, num_tracked_objects * sizeof (tracked_objects [0]));
2122 tracked_objects [num_tracked_objects - 1] = obj;
2125 static int num_jit_helpers = 0;
2126 static int jit_helpers_code_size = 0;
2129 code_buffer_desc (int type)
2132 case MONO_PROFILER_CODE_BUFFER_METHOD:
2134 case MONO_PROFILER_CODE_BUFFER_METHOD_TRAMPOLINE:
2135 return "method trampoline";
2136 case MONO_PROFILER_CODE_BUFFER_UNBOX_TRAMPOLINE:
2137 return "unbox trampoline";
2138 case MONO_PROFILER_CODE_BUFFER_IMT_TRAMPOLINE:
2139 return "imt trampoline";
2140 case MONO_PROFILER_CODE_BUFFER_GENERICS_TRAMPOLINE:
2141 return "generics trampoline";
2142 case MONO_PROFILER_CODE_BUFFER_SPECIFIC_TRAMPOLINE:
2143 return "specific trampoline";
2144 case MONO_PROFILER_CODE_BUFFER_HELPER:
2145 return "misc helper";
2146 case MONO_PROFILER_CODE_BUFFER_MONITOR:
2147 return "monitor/lock";
2148 case MONO_PROFILER_CODE_BUFFER_DELEGATE_INVOKE:
2149 return "delegate invoke";
2150 case MONO_PROFILER_CODE_BUFFER_EXCEPTION_HANDLING:
2151 return "exception handling";
2153 return "unspecified";
2157 typedef struct _CoverageAssembly CoverageAssembly;
2158 struct _CoverageAssembly {
2162 int number_of_methods;
2164 int partially_covered;
2167 typedef struct _CoverageClass CoverageClass;
2168 struct _CoverageClass {
2169 char *assembly_name;
2171 int number_of_methods;
2173 int partially_covered;
2176 typedef struct _CoverageCoverage CoverageCoverage;
2177 struct _CoverageCoverage {
2185 typedef struct _CoverageMethod CoverageMethod;
2186 struct _CoverageMethod {
2187 char *assembly_name;
2190 char *method_signature;
2195 GPtrArray *coverage;
2197 static GPtrArray *coverage_assemblies = NULL;
2198 static GPtrArray *coverage_methods = NULL;
2199 static GPtrArray *coverage_statements = NULL;
2200 static GHashTable *coverage_methods_hash = NULL;
2201 static GPtrArray *coverage_classes = NULL;
2202 static GHashTable *coverage_assembly_classes = NULL;
2205 gather_coverage_statements (void)
2207 for (guint i = 0; i < coverage_statements->len; i++) {
2208 CoverageCoverage *coverage = (CoverageCoverage *)coverage_statements->pdata[i];
2209 CoverageMethod *method = (CoverageMethod *)g_hash_table_lookup (coverage_methods_hash, GINT_TO_POINTER (coverage->method_id));
2210 if (method == NULL) {
2211 fprintf (outfile, "Cannot find method with ID: %d\n", coverage->method_id);
2215 g_ptr_array_add (method->coverage, coverage);
2220 coverage_add_assembly (CoverageAssembly *assembly)
2222 if (coverage_assemblies == NULL)
2223 coverage_assemblies = g_ptr_array_new ();
2225 g_ptr_array_add (coverage_assemblies, assembly);
2229 coverage_add_method (CoverageMethod *method)
2231 if (coverage_methods == NULL) {
2232 coverage_methods = g_ptr_array_new ();
2233 coverage_methods_hash = g_hash_table_new (NULL, NULL);
2236 g_ptr_array_add (coverage_methods, method);
2237 g_hash_table_insert (coverage_methods_hash, GINT_TO_POINTER (method->method_id), method);
2241 coverage_add_class (CoverageClass *klass)
2243 GPtrArray *classes = NULL;
2245 if (coverage_classes == NULL) {
2246 coverage_classes = g_ptr_array_new ();
2247 coverage_assembly_classes = g_hash_table_new (g_str_hash, g_str_equal);
2250 g_ptr_array_add (coverage_classes, klass);
2251 classes = (GPtrArray *)g_hash_table_lookup (coverage_assembly_classes, klass->assembly_name);
2252 if (classes == NULL) {
2253 classes = g_ptr_array_new ();
2254 g_hash_table_insert (coverage_assembly_classes, klass->assembly_name, classes);
2256 g_ptr_array_add (classes, klass);
2260 coverage_add_coverage (CoverageCoverage *coverage)
2262 if (coverage_statements == NULL)
2263 coverage_statements = g_ptr_array_new ();
2265 g_ptr_array_add (coverage_statements, coverage);
2268 #define OBJ_ADDR(diff) ((obj_base + diff) << 3)
2269 #define LOG_TIME(base,diff) /*fprintf("outfile, time %llu + %llu near offset %d\n", base, diff, p - ctx->buf)*/
2273 #define BUFFER_HEADER_SIZE 48
2276 int count, min_size, max_size, bytes;
2279 static int buffer_count;
2280 static EventStat stats [256];
2283 record_event_stats (int type, int size)
2285 ++stats [type].count;
2286 if (!stats [type].min_size)
2287 stats [type].min_size = size;
2288 stats [type].min_size = MIN (stats [type].min_size, size);
2289 stats [type].max_size = MAX (stats [type].max_size, size);
2290 stats [type].bytes += size;
2294 decode_buffer (ProfContext *ctx)
2301 intptr_t method_base;
2303 uint64_t file_offset;
2305 ThreadContext *thread;
2307 #ifdef HAVE_SYS_ZLIB
2309 file_offset = gztell (ctx->gzfile);
2312 file_offset = ftell (ctx->file);
2313 if (!load_data (ctx, 48))
2316 if (read_int32 (p) != BUF_ID) {
2317 fprintf (outfile, "Incorrect buffer id: 0x%x\n", read_int32 (p));
2318 for (i = 0; i < 48; ++i) {
2319 fprintf (outfile, "0x%x%s", p [i], i % 8?" ":"\n");
2323 len = read_int32 (p + 4);
2324 time_base = read_int64 (p + 8);
2325 ptr_base = read_int64 (p + 16);
2326 obj_base = read_int64 (p + 24);
2327 thread_id = read_int64 (p + 32);
2328 method_base = read_int64 (p + 40);
2330 fprintf (outfile, "buf: thread:%zx, len: %d, time: %llu, file offset: %llu\n", thread_id, len, (unsigned long long) time_base, (unsigned long long) file_offset);
2331 thread = load_thread (ctx, thread_id);
2332 if (!load_data (ctx, len))
2337 if (!startup_time) {
2338 startup_time = time_base;
2339 if (use_time_filter) {
2340 time_from += startup_time;
2341 time_to += startup_time;
2344 for (i = 0; i < thread->stack_id; ++i)
2345 thread->stack [i]->recurse_count++;
2349 unsigned char *start = p;
2350 unsigned char event = *p;
2353 int subtype = *p & 0xf0;
2354 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2355 LOG_TIME (time_base, tdiff);
2357 if (subtype == TYPE_GC_RESIZE) {
2358 uint64_t new_size = decode_uleb128 (p, &p);
2360 fprintf (outfile, "gc heap resized to %llu\n", (unsigned long long) new_size);
2362 if (new_size > max_heap_size)
2363 max_heap_size = new_size;
2364 } else if (subtype == TYPE_GC_EVENT) {
2366 if (ctx->data_version > 12)
2369 ev = decode_uleb128 (p, &p);
2371 if (ctx->data_version > 12)
2374 gen = decode_uleb128 (p, &p);
2376 fprintf (outfile, "gc event for gen%d: %s at %llu (thread: 0x%zx)\n", gen, gc_event_name (ev), (unsigned long long) time_base, thread->thread_id);
2378 fprintf (outfile, "incorrect gc gen: %d\n", gen);
2381 if (ev == MONO_GC_EVENT_START) {
2382 thread->gc_start_times [gen] = time_base;
2383 gc_info [gen].count++;
2384 } else if (ev == MONO_GC_EVENT_END) {
2385 tdiff = time_base - thread->gc_start_times [gen];
2386 gc_info [gen].total_time += tdiff;
2387 if (tdiff > gc_info [gen].max_time)
2388 gc_info [gen].max_time = tdiff;
2390 } else if (subtype == TYPE_GC_MOVE) {
2391 int j, num = decode_uleb128 (p, &p);
2392 gc_object_moves += num / 2;
2393 for (j = 0; j < num; j += 2) {
2394 intptr_t obj1diff = decode_sleb128 (p, &p);
2395 intptr_t obj2diff = decode_sleb128 (p, &p);
2396 if (num_tracked_objects)
2397 track_move (OBJ_ADDR (obj1diff), OBJ_ADDR (obj2diff));
2399 fprintf (outfile, "moved obj %p to %p\n", (void*)OBJ_ADDR (obj1diff), (void*)OBJ_ADDR (obj2diff));
2402 } else if (subtype == TYPE_GC_HANDLE_CREATED || subtype == TYPE_GC_HANDLE_CREATED_BT) {
2403 int has_bt = subtype == TYPE_GC_HANDLE_CREATED_BT;
2405 MethodDesc *sframes [8];
2406 MethodDesc **frames = sframes;
2407 int htype = decode_uleb128 (p, &p);
2408 uint32_t handle = decode_uleb128 (p, &p);
2409 intptr_t objdiff = decode_sleb128 (p, &p);
2412 frames = decode_bt (ctx, sframes, &num_bt, p, &p, ptr_base, &method_base);
2414 fprintf (outfile, "Cannot load backtrace\n");
2420 if ((thread_filter && thread_filter == thread->thread_id) || (time_base >= time_from && time_base < time_to)) {
2421 handle_info [htype].created++;
2422 handle_info [htype].live++;
2423 if (handle_info [htype].live > handle_info [htype].max_live)
2424 handle_info [htype].max_live = handle_info [htype].live;
2427 bt = add_trace_methods (frames, num_bt, &handle_info [htype].traces, 1);
2429 bt = add_trace_thread (thread, &handle_info [htype].traces, 1);
2430 if (num_tracked_objects)
2431 track_handle (OBJ_ADDR (objdiff), htype, handle, bt, time_base);
2434 fprintf (outfile, "handle (%s) %u created for object %p\n", get_handle_name (htype), handle, (void*)OBJ_ADDR (objdiff));
2435 if (frames != sframes)
2437 } else if (subtype == TYPE_GC_HANDLE_DESTROYED || subtype == TYPE_GC_HANDLE_DESTROYED_BT) {
2438 int has_bt = subtype == TYPE_GC_HANDLE_DESTROYED_BT;
2440 MethodDesc *sframes [8];
2441 MethodDesc **frames = sframes;
2442 int htype = decode_uleb128 (p, &p);
2443 uint32_t handle = decode_uleb128 (p, &p);
2446 frames = decode_bt (ctx, sframes, &num_bt, p, &p, ptr_base, &method_base);
2448 fprintf (outfile, "Cannot load backtrace\n");
2454 if ((thread_filter && thread_filter == thread->thread_id) || (time_base >= time_from && time_base < time_to)) {
2455 handle_info [htype].destroyed ++;
2456 handle_info [htype].live--;
2459 bt = add_trace_methods (frames, num_bt, &handle_info [htype].destroy_traces, 1);
2461 bt = add_trace_thread (thread, &handle_info [htype].destroy_traces, 1);
2462 /* TODO: track_handle_free () - would need to record and keep track of the associated object address... */
2465 fprintf (outfile, "handle (%s) %u destroyed\n", get_handle_name (htype), handle);
2466 if (frames != sframes)
2468 } else if (subtype == TYPE_GC_FINALIZE_START) {
2469 // TODO: Generate a finalizer report based on these events.
2471 fprintf (outfile, "gc finalizer queue being processed at %llu\n", (unsigned long long) time_base);
2472 } else if (subtype == TYPE_GC_FINALIZE_END) {
2474 fprintf (outfile, "gc finalizer queue finished processing at %llu\n", (unsigned long long) time_base);
2475 } else if (subtype == TYPE_GC_FINALIZE_OBJECT_START) {
2476 intptr_t objdiff = decode_sleb128 (p, &p);
2478 fprintf (outfile, "gc finalizing object %p at %llu\n", (void *) OBJ_ADDR (objdiff), (unsigned long long) time_base);
2479 } else if (subtype == TYPE_GC_FINALIZE_OBJECT_END) {
2480 intptr_t objdiff = decode_sleb128 (p, &p);
2482 fprintf (outfile, "gc finalized object %p at %llu\n", (void *) OBJ_ADDR (objdiff), (unsigned long long) time_base);
2486 case TYPE_METADATA: {
2487 int subtype = *p & 0xf0;
2488 const char *load_str = subtype == TYPE_END_LOAD ? "loaded" : "unloaded";
2489 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2491 intptr_t ptrdiff = decode_sleb128 (p, &p);
2492 LOG_TIME (time_base, tdiff);
2494 if (mtype == TYPE_CLASS) {
2495 intptr_t imptrdiff = decode_sleb128 (p, &p);
2496 if (ctx->data_version < 13)
2497 decode_uleb128 (p, &p); /* flags */
2499 fprintf (outfile, "%s class %p (%s in %p) at %llu\n", load_str, (void*)(ptr_base + ptrdiff), p, (void*)(ptr_base + imptrdiff), (unsigned long long) time_base);
2500 add_class (ptr_base + ptrdiff, (char*)p);
2503 } else if (mtype == TYPE_IMAGE) {
2504 if (ctx->data_version < 13)
2505 decode_uleb128 (p, &p); /* flags */
2507 fprintf (outfile, "%s image %p (%s) at %llu\n", load_str, (void*)(ptr_base + ptrdiff), p, (unsigned long long) time_base);
2508 if (subtype == TYPE_END_LOAD)
2509 add_image (ptr_base + ptrdiff, (char*)p);
2512 } else if (mtype == TYPE_ASSEMBLY) {
2513 if (ctx->data_version > 13)
2514 decode_sleb128 (p, &p); // image
2515 if (ctx->data_version < 13)
2516 decode_uleb128 (p, &p); /* flags */
2518 fprintf (outfile, "%s assembly %p (%s) at %llu\n", load_str, (void*)(ptr_base + ptrdiff), p, (unsigned long long) time_base);
2519 if (subtype == TYPE_END_LOAD)
2520 add_assembly (ptr_base + ptrdiff, (char*)p);
2523 } else if (mtype == TYPE_DOMAIN) {
2524 if (ctx->data_version < 13)
2525 decode_uleb128 (p, &p); /* flags */
2526 DomainContext *nd = get_domain (ctx, ptr_base + ptrdiff);
2527 /* no subtype means it's a name event, rather than start/stop */
2529 nd->friendly_name = pstrdup ((char *) p);
2532 fprintf (outfile, "domain %p named at %llu: %s\n", (void *) (ptr_base + ptrdiff), (unsigned long long) time_base, p);
2534 fprintf (outfile, "%s thread %p at %llu\n", load_str, (void *) (ptr_base + ptrdiff), (unsigned long long) time_base);
2540 } else if (mtype == TYPE_CONTEXT) {
2541 if (ctx->data_version < 13)
2542 decode_uleb128 (p, &p); /* flags */
2543 intptr_t domaindiff = decode_sleb128 (p, &p);
2545 fprintf (outfile, "%s context %p (%p) at %llu\n", load_str, (void*)(ptr_base + ptrdiff), (void *) (ptr_base + domaindiff), (unsigned long long) time_base);
2546 if (subtype == TYPE_END_LOAD)
2547 get_remctx (ctx, ptr_base + ptrdiff)->domain_id = ptr_base + domaindiff;
2548 } else if (mtype == TYPE_THREAD) {
2549 if (ctx->data_version < 13)
2550 decode_uleb128 (p, &p); /* flags */
2551 ThreadContext *nt = get_thread (ctx, ptr_base + ptrdiff);
2552 /* no subtype means it's a name event, rather than start/stop */
2554 nt->name = pstrdup ((char*)p);
2557 fprintf (outfile, "thread %p named at %llu: %s\n", (void*)(ptr_base + ptrdiff), (unsigned long long) time_base, p);
2559 fprintf (outfile, "%s thread %p at %llu\n", load_str, (void *) (ptr_base + ptrdiff), (unsigned long long) time_base);
2569 int has_bt = *p & TYPE_ALLOC_BT;
2570 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2571 intptr_t ptrdiff = decode_sleb128 (p, &p);
2572 intptr_t objdiff = decode_sleb128 (p, &p);
2575 MethodDesc* sframes [8];
2576 MethodDesc** frames = sframes;
2577 ClassDesc *cd = lookup_class (ptr_base + ptrdiff);
2578 len = decode_uleb128 (p, &p);
2579 LOG_TIME (time_base, tdiff);
2582 fprintf (outfile, "alloced object %p, size %llu (%s) at %llu\n", (void*)OBJ_ADDR (objdiff), (unsigned long long) len, lookup_class (ptr_base + ptrdiff)->name, (unsigned long long) time_base);
2585 frames = decode_bt (ctx, sframes, &num_bt, p, &p, ptr_base, &method_base);
2587 fprintf (outfile, "Cannot load backtrace\n");
2591 if ((thread_filter && thread_filter == thread->thread_id) || (time_base >= time_from && time_base < time_to)) {
2594 cd->alloc_size += len;
2596 bt = add_trace_methods (frames, num_bt, &cd->traces, len);
2598 bt = add_trace_thread (thread, &cd->traces, len);
2599 if (find_size && len >= find_size) {
2600 if (!find_name || strstr (cd->name, find_name))
2601 found_object (OBJ_ADDR (objdiff));
2602 } else if (!find_size && find_name && strstr (cd->name, find_name)) {
2603 found_object (OBJ_ADDR (objdiff));
2605 if (num_tracked_objects)
2606 tracked_creation (OBJ_ADDR (objdiff), cd, len, bt, time_base);
2608 if (frames != sframes)
2613 int subtype = *p & 0xf0;
2614 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2615 int64_t ptrdiff = decode_sleb128 (p, &p);
2616 LOG_TIME (time_base, tdiff);
2618 method_base += ptrdiff;
2619 if (subtype == TYPE_JIT) {
2620 intptr_t codediff = decode_sleb128 (p, &p);
2621 int codelen = decode_uleb128 (p, &p);
2622 MethodDesc *jitted_method;
2624 fprintf (outfile, "jitted method %p (%s), size: %d, code: %p\n", (void*)(method_base), p, codelen, (void*)(ptr_base + codediff));
2625 jitted_method = add_method (method_base, (char*)p, ptr_base + codediff, codelen);
2626 if (!(time_base >= time_from && time_base < time_to))
2627 jitted_method->ignore_jit = 1;
2632 if ((thread_filter && thread_filter != thread->thread_id))
2634 if (!(time_base >= time_from && time_base < time_to))
2636 method = lookup_method (method_base);
2637 if (subtype == TYPE_ENTER) {
2638 add_trace_thread (thread, &method->traces, 1);
2639 push_method (thread, method, time_base);
2641 pop_method (thread, method, time_base);
2644 fprintf (outfile, "%s method %s\n", subtype == TYPE_ENTER? "enter": subtype == TYPE_EXC_LEAVE? "exleave": "leave", method->name);
2649 int subtype = *p & 0xf0;
2650 if (subtype == TYPE_HEAP_OBJECT) {
2651 HeapObjectDesc *ho = NULL;
2654 if (ctx->data_version > 12) {
2655 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2656 LOG_TIME (time_base, tdiff);
2658 objdiff = decode_sleb128 (p, &p);
2660 objdiff = decode_sleb128 (p + 1, &p);
2661 intptr_t ptrdiff = decode_sleb128 (p, &p);
2662 uint64_t size = decode_uleb128 (p, &p);
2663 uintptr_t num = decode_uleb128 (p, &p);
2664 uintptr_t ref_offset = 0;
2665 uintptr_t last_obj_offset = 0;
2666 ClassDesc *cd = lookup_class (ptr_base + ptrdiff);
2668 HeapClassDesc *hcd = add_heap_shot_class (thread->current_heap_shot, cd, size);
2669 if (collect_traces) {
2670 ho = alloc_heap_obj (OBJ_ADDR (objdiff), hcd, num);
2671 add_heap_shot_obj (thread->current_heap_shot, ho);
2676 ho = heap_shot_obj_add_refs (thread->current_heap_shot, OBJ_ADDR (objdiff), num, &ref_offset);
2678 for (i = 0; i < num; ++i) {
2679 /* FIXME: use object distance to measure how good
2680 * the GC is at keeping related objects close
2682 uintptr_t offset = ctx->data_version > 1? last_obj_offset + decode_uleb128 (p, &p): -1;
2683 intptr_t obj1diff = decode_sleb128 (p, &p);
2684 last_obj_offset = offset;
2686 ho->refs [ref_offset + i] = OBJ_ADDR (obj1diff);
2687 if (num_tracked_objects)
2688 track_obj_reference (OBJ_ADDR (obj1diff), OBJ_ADDR (objdiff), cd);
2691 fprintf (outfile, "traced object %p, size %llu (%s), refs: %zd\n", (void*)OBJ_ADDR (objdiff), (unsigned long long) size, cd->name, num);
2692 } else if (subtype == TYPE_HEAP_ROOT) {
2694 if (ctx->data_version > 12) {
2695 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2696 LOG_TIME (time_base, tdiff);
2698 num = decode_uleb128 (p, &p);
2700 num = decode_uleb128 (p + 1, &p);
2701 uintptr_t gc_num G_GNUC_UNUSED = decode_uleb128 (p, &p);
2703 for (i = 0; i < num; ++i) {
2704 intptr_t objdiff = decode_sleb128 (p, &p);
2706 if (ctx->data_version == 13)
2709 root_type = decode_uleb128 (p, &p);
2710 /* we just discard the extra info for now */
2711 uintptr_t extra_info = decode_uleb128 (p, &p);
2713 fprintf (outfile, "object %p is a %s root\n", (void*)OBJ_ADDR (objdiff), get_root_name (root_type));
2715 thread_add_root (thread, OBJ_ADDR (objdiff), root_type, extra_info);
2717 } else if (subtype == TYPE_HEAP_END) {
2718 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2719 LOG_TIME (time_base, tdiff);
2722 fprintf (outfile, "heap shot end\n");
2723 if (collect_traces) {
2724 HeapShot *hs = thread->current_heap_shot;
2725 if (hs && thread->num_roots) {
2726 /* transfer the root ownershipt to the heapshot */
2727 hs->num_roots = thread->num_roots;
2728 hs->roots = thread->roots;
2729 hs->roots_extra = thread->roots_extra;
2730 hs->roots_types = thread->roots_types;
2732 g_free (thread->roots);
2733 g_free (thread->roots_extra);
2734 g_free (thread->roots_types);
2736 thread->num_roots = 0;
2737 thread->size_roots = 0;
2738 thread->roots = NULL;
2739 thread->roots_extra = NULL;
2740 thread->roots_types = NULL;
2741 heap_shot_resolve_reverse_refs (hs);
2742 heap_shot_mark_objects (hs);
2743 heap_shot_free_objects (hs);
2745 thread->current_heap_shot = NULL;
2746 } else if (subtype == TYPE_HEAP_START) {
2747 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2748 LOG_TIME (time_base, tdiff);
2751 fprintf (outfile, "heap shot start\n");
2752 thread->current_heap_shot = new_heap_shot (time_base);
2756 case TYPE_MONITOR: {
2757 int has_bt = *p & TYPE_MONITOR_BT;
2759 if (ctx->data_version < 13)
2760 event = (*p >> 4) & 0x3;
2761 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2762 if (ctx->data_version > 13)
2764 intptr_t objdiff = decode_sleb128 (p, &p);
2765 MethodDesc* sframes [8];
2766 MethodDesc** frames = sframes;
2769 LOG_TIME (time_base, tdiff);
2771 record = (!thread_filter || thread_filter == thread->thread_id);
2772 if (!(time_base >= time_from && time_base < time_to))
2774 MonitorDesc *mdesc = lookup_monitor (OBJ_ADDR (objdiff));
2775 if (event == MONO_PROFILER_MONITOR_CONTENTION) {
2777 monitor_contention++;
2778 mdesc->contentions++;
2779 thread->monitor = mdesc;
2780 thread->contention_start = time_base;
2782 } else if (event == MONO_PROFILER_MONITOR_FAIL) {
2785 if (thread->monitor && thread->contention_start) {
2786 uint64_t wait_time = time_base - thread->contention_start;
2787 if (wait_time > thread->monitor->max_wait_time)
2788 thread->monitor->max_wait_time = wait_time;
2789 thread->monitor->wait_time += wait_time;
2790 thread->monitor = NULL;
2791 thread->contention_start = 0;
2794 } else if (event == MONO_PROFILER_MONITOR_DONE) {
2797 if (thread->monitor && thread->contention_start) {
2798 uint64_t wait_time = time_base - thread->contention_start;
2799 if (wait_time > thread->monitor->max_wait_time)
2800 thread->monitor->max_wait_time = wait_time;
2801 thread->monitor->wait_time += wait_time;
2802 thread->monitor = NULL;
2803 thread->contention_start = 0;
2809 frames = decode_bt (ctx, sframes, &num_bt, p, &p, ptr_base, &method_base);
2811 fprintf (outfile, "Cannot load backtrace\n");
2814 if (record && event == MONO_PROFILER_MONITOR_CONTENTION)
2815 add_trace_methods (frames, num_bt, &mdesc->traces, 1);
2818 add_trace_thread (thread, &mdesc->traces, 1);
2821 fprintf (outfile, "monitor %s for object %p\n", monitor_ev_name (event), (void*)OBJ_ADDR (objdiff));
2822 if (frames != sframes)
2826 case TYPE_EXCEPTION: {
2827 int subtype = *p & 0x70;
2828 int has_bt = *p & TYPE_THROW_BT;
2829 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2830 MethodDesc* sframes [8];
2831 MethodDesc** frames = sframes;
2833 LOG_TIME (time_base, tdiff);
2835 record = (!thread_filter || thread_filter == thread->thread_id);
2836 if (!(time_base >= time_from && time_base < time_to))
2838 if (subtype == TYPE_CLAUSE) {
2840 if (ctx->data_version > 12)
2843 clause_type = decode_uleb128 (p, &p);
2844 int clause_num = decode_uleb128 (p, &p);
2845 int64_t ptrdiff = decode_sleb128 (p, &p);
2846 method_base += ptrdiff;
2847 if (ctx->data_version > 13)
2848 decode_uleb128 (p, &p); // exception object
2850 clause_summary [clause_type]++;
2852 fprintf (outfile, "clause %s (%d) in method %s\n", clause_name (clause_type), clause_num, lookup_method (method_base)->name);
2854 intptr_t objdiff = decode_sleb128 (p, &p);
2859 frames = decode_bt (ctx, sframes, &has_bt, p, &p, ptr_base, &method_base);
2861 fprintf (outfile, "Cannot load backtrace\n");
2865 add_trace_methods (frames, has_bt, &exc_traces, 1);
2868 add_trace_thread (thread, &exc_traces, 1);
2870 if (frames != sframes)
2873 fprintf (outfile, "throw %p\n", (void*)OBJ_ADDR (objdiff));
2877 case TYPE_RUNTIME: {
2878 int subtype = *p & 0xf0;
2879 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2880 LOG_TIME (time_base, tdiff);
2882 if (subtype == TYPE_JITHELPER) {
2884 if (ctx->data_version > 12)
2887 type = decode_uleb128 (p, &p);
2888 intptr_t codediff = decode_sleb128 (p, &p);
2889 int codelen = decode_uleb128 (p, &p);
2891 if (type == MONO_PROFILER_CODE_BUFFER_SPECIFIC_TRAMPOLINE) {
2892 name = (const char *)p;
2896 name = code_buffer_desc (type);
2899 jit_helpers_code_size += codelen;
2901 fprintf (outfile, "jit helper %s, size: %d, code: %p\n", name, codelen, (void*)(ptr_base + codediff));
2906 int subtype = *p & 0xf0;
2907 if (subtype == TYPE_SAMPLE_HIT) {
2911 if (ctx->data_version > 12) {
2912 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2913 LOG_TIME (time_base, tdiff);
2915 if (ctx->data_version < 14)
2918 sample_type = SAMPLE_CYCLES;
2921 sample_type = decode_uleb128 (p + 1, &p);
2922 tstamp = decode_uleb128 (p, &p);
2924 void *tid = (void *) thread_id;
2925 if (ctx->data_version > 10)
2926 tid = (void *) (ptr_base + decode_sleb128 (p, &p));
2927 int count = decode_uleb128 (p, &p);
2928 for (i = 0; i < count; ++i) {
2929 uintptr_t ip = ptr_base + decode_sleb128 (p, &p);
2930 if ((tstamp >= time_from && tstamp < time_to))
2931 add_stat_sample (sample_type, ip);
2933 fprintf (outfile, "sample hit, type: %d at %p for thread %p\n", sample_type, (void*)ip, tid);
2935 if (ctx->data_version > 5) {
2936 count = decode_uleb128 (p, &p);
2937 for (i = 0; i < count; ++i) {
2939 int64_t ptrdiff = decode_sleb128 (p, &p);
2940 method_base += ptrdiff;
2941 method = lookup_method (method_base);
2943 fprintf (outfile, "sample hit bt %d: %s\n", i, method->name);
2944 if (ctx->data_version < 13) {
2945 decode_sleb128 (p, &p); /* il offset */
2946 decode_sleb128 (p, &p); /* native offset */
2950 } else if (subtype == TYPE_SAMPLE_USYM) {
2951 /* un unmanaged symbol description */
2953 if (ctx->data_version > 12) {
2954 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2955 LOG_TIME (time_base, tdiff);
2957 addr = ptr_base + decode_sleb128 (p, &p);
2959 addr = ptr_base + decode_sleb128 (p + 1, &p);
2960 uintptr_t size = decode_uleb128 (p, &p);
2962 name = pstrdup ((char*)p);
2963 add_unmanaged_symbol (addr, name, size);
2965 fprintf (outfile, "unmanaged symbol %s at %p\n", name, (void*)addr);
2968 } else if (subtype == TYPE_SAMPLE_UBIN) {
2969 /* un unmanaged binary loaded in memory */
2970 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2971 uintptr_t addr = decode_sleb128 (p, &p);
2972 if (ctx->data_version > 13)
2974 uint64_t offset G_GNUC_UNUSED = decode_uleb128 (p, &p);
2975 uintptr_t size = decode_uleb128 (p, &p);
2977 LOG_TIME (time_base, tdiff);
2979 name = pstrdup ((char*)p);
2980 add_unmanaged_binary (addr, name, size);
2982 fprintf (outfile, "unmanaged binary %s at %p\n", name, (void*)addr);
2985 } else if (subtype == TYPE_SAMPLE_COUNTERS_DESC) {
2987 if (ctx->data_version > 12) {
2988 uint64_t tdiff = decode_uleb128 (p + 1, &p);
2989 LOG_TIME (time_base, tdiff);
2991 len = decode_uleb128 (p, &p);
2993 len = decode_uleb128 (p + 1, &p);
2994 for (i = 0; i < len; i++) {
2995 uint64_t type, unit, variance, index;
2996 uint64_t section = decode_uleb128 (p, &p);
2997 char *section_str, *name;
2998 if (section != MONO_COUNTER_PERFCOUNTERS) {
2999 section_str = (char*) section_name (section);
3001 section_str = pstrdup ((char*)p);
3004 name = pstrdup ((char*)p);
3006 if (ctx->data_version > 12) {
3011 type = decode_uleb128 (p, &p);
3012 unit = decode_uleb128 (p, &p);
3013 variance = decode_uleb128 (p, &p);
3015 index = decode_uleb128 (p, &p);
3016 add_counter (section_str, name, (int)type, (int)unit, (int)variance, (int)index);
3018 } else if (subtype == TYPE_SAMPLE_COUNTERS) {
3020 CounterValue *value, *previous = NULL;
3022 uint64_t timestamp; // milliseconds since startup
3023 if (ctx->data_version > 12) {
3024 uint64_t tdiff = decode_uleb128 (p + 1, &p);
3025 LOG_TIME (time_base, tdiff);
3027 timestamp = (time_base - startup_time) / 1000 / 1000;
3029 timestamp = decode_uleb128 (p + 1, &p);
3030 uint64_t time_between = timestamp / 1000 * 1000 * 1000 * 1000 + startup_time;
3032 uint64_t type, index = decode_uleb128 (p, &p);
3036 for (list = counters; list; list = list->next) {
3037 if (list->counter->index == (int)index) {
3038 previous = list->counter->values_last;
3043 if (ctx->data_version > 12)
3046 type = decode_uleb128 (p, &p);
3048 value = (CounterValue *) g_calloc (1, sizeof (CounterValue));
3049 value->timestamp = timestamp;
3052 case MONO_COUNTER_INT:
3053 #if SIZEOF_VOID_P == 4
3054 case MONO_COUNTER_WORD:
3056 value->buffer = (unsigned char *)g_malloc (sizeof (int32_t));
3057 *(int32_t*)value->buffer = (int32_t)decode_sleb128 (p, &p) + (previous ? (*(int32_t*)previous->buffer) : 0);
3059 case MONO_COUNTER_UINT:
3060 value->buffer = (unsigned char *) g_malloc (sizeof (uint32_t));
3061 *(uint32_t*)value->buffer = (uint32_t)decode_uleb128 (p, &p) + (previous ? (*(uint32_t*)previous->buffer) : 0);
3063 case MONO_COUNTER_LONG:
3064 #if SIZEOF_VOID_P == 8
3065 case MONO_COUNTER_WORD:
3067 case MONO_COUNTER_TIME_INTERVAL:
3068 value->buffer = (unsigned char *) g_malloc (sizeof (int64_t));
3069 *(int64_t*)value->buffer = (int64_t)decode_sleb128 (p, &p) + (previous ? (*(int64_t*)previous->buffer) : 0);
3071 case MONO_COUNTER_ULONG:
3072 value->buffer = (unsigned char *) g_malloc (sizeof (uint64_t));
3073 *(uint64_t*)value->buffer = (uint64_t)decode_uleb128 (p, &p) + (previous ? (*(uint64_t*)previous->buffer) : 0);
3075 case MONO_COUNTER_DOUBLE:
3076 value->buffer = (unsigned char *) g_malloc (sizeof (double));
3077 #if TARGET_BYTE_ORDER == G_LITTLE_ENDIAN
3078 for (i = 0; i < sizeof (double); i++)
3080 for (i = sizeof (double) - 1; i >= 0; i--)
3082 value->buffer[i] = *p++;
3084 case MONO_COUNTER_STRING:
3086 value->buffer = NULL;
3088 value->buffer = (unsigned char*) pstrdup ((char*)p);
3093 if (time_between >= time_from && time_between <= time_to)
3094 add_counter_value (index, value);
3101 case TYPE_COVERAGE:{
3102 int subtype = *p & 0xf0;
3104 case TYPE_COVERAGE_METHOD: {
3105 CoverageMethod *method = g_new0 (CoverageMethod, 1);
3106 const char *assembly, *klass, *name, *sig, *filename;
3107 int token, n_offsets, method_id;
3111 if (ctx->data_version > 12) {
3112 uint64_t tdiff = decode_uleb128 (p, &p);
3113 LOG_TIME (time_base, tdiff);
3117 assembly = (const char *)p; while (*p) p++; p++;
3118 klass = (const char *)p; while (*p) p++; p++;
3119 name = (const char *)p; while (*p) p++; p++;
3120 sig = (const char *)p; while (*p) p++; p++;
3121 filename = (const char *)p; while (*p) p++; p++;
3123 token = decode_uleb128 (p, &p);
3124 method_id = decode_uleb128 (p, &p);
3125 n_offsets = decode_uleb128 (p, &p);
3127 method->assembly_name = g_strdup (assembly);
3128 method->class_name = g_strdup (klass);
3129 method->method_name = g_strdup (name);
3130 method->method_signature = g_strdup (sig);
3131 method->filename = g_strdup (filename);
3132 method->token = token;
3133 method->n_statements = n_offsets;
3134 method->coverage = g_ptr_array_new ();
3135 method->method_id = method_id;
3137 coverage_add_method (method);
3141 case TYPE_COVERAGE_STATEMENT: {
3142 CoverageCoverage *coverage = g_new0 (CoverageCoverage, 1);
3143 int offset, count, line, column, method_id;
3147 if (ctx->data_version > 12) {
3148 uint64_t tdiff = decode_uleb128 (p, &p);
3149 LOG_TIME (time_base, tdiff);
3153 method_id = decode_uleb128 (p, &p);
3154 offset = decode_uleb128 (p, &p);
3155 count = decode_uleb128 (p, &p);
3156 line = decode_uleb128 (p, &p);
3157 column = decode_uleb128 (p, &p);
3159 coverage->method_id = method_id;
3160 coverage->offset = offset;
3161 coverage->count = count;
3162 coverage->line = line;
3163 coverage->column = column;
3165 coverage_add_coverage (coverage);
3168 case TYPE_COVERAGE_ASSEMBLY: {
3169 CoverageAssembly *assembly = g_new0 (CoverageAssembly, 1);
3170 char *name, *guid, *filename;
3171 int number_of_methods, fully_covered, partially_covered;
3174 if (ctx->data_version > 12) {
3175 uint64_t tdiff = decode_uleb128 (p, &p);
3176 LOG_TIME (time_base, tdiff);
3180 name = (char *)p; while (*p) p++; p++;
3181 guid = (char *)p; while (*p) p++; p++;
3182 filename = (char *)p; while (*p) p++; p++;
3183 number_of_methods = decode_uleb128 (p, &p);
3184 fully_covered = decode_uleb128 (p, &p);
3185 partially_covered = decode_uleb128 (p, &p);
3187 assembly->name = g_strdup (name);
3188 assembly->guid = g_strdup (guid);
3189 assembly->filename = g_strdup (filename);
3190 assembly->number_of_methods = number_of_methods;
3191 assembly->fully_covered = fully_covered;
3192 assembly->partially_covered = partially_covered;
3194 coverage_add_assembly (assembly);
3197 case TYPE_COVERAGE_CLASS: {
3198 CoverageClass *klass = g_new0 (CoverageClass, 1);
3199 char *assembly_name, *class_name;
3200 int number_of_methods, fully_covered, partially_covered;
3203 if (ctx->data_version > 12) {
3204 uint64_t tdiff = decode_uleb128 (p, &p);
3205 LOG_TIME (time_base, tdiff);
3209 assembly_name = (char *)p; while (*p) p++; p++;
3210 class_name = (char *)p; while (*p) p++; p++;
3211 number_of_methods = decode_uleb128 (p, &p);
3212 fully_covered = decode_uleb128 (p, &p);
3213 partially_covered = decode_uleb128 (p, &p);
3215 klass->assembly_name = g_strdup (assembly_name);
3216 klass->class_name = g_strdup (class_name);
3217 klass->number_of_methods = number_of_methods;
3218 klass->fully_covered = fully_covered;
3219 klass->partially_covered = partially_covered;
3221 coverage_add_class (klass);
3231 int subtype = *p & 0xf0;
3232 uint64_t tdiff = decode_uleb128 (p + 1, &p);
3233 LOG_TIME (time_base, tdiff);
3235 if (subtype == TYPE_SYNC_POINT) {
3238 fprintf (outfile, "sync point %i (%s)\n", type, sync_point_name (type));
3243 fprintf (outfile, "unhandled profiler event: 0x%x at file offset: %llu + %lld (len: %d\n)\n", *p, (unsigned long long) file_offset, (long long) (p - ctx->buf), len);
3246 record_event_stats (event, p - start);
3248 thread->last_time = time_base;
3249 for (i = 0; i < thread->stack_id; ++i)
3250 thread->stack [i]->recurse_count = 0;
3255 read_header_string (ProfContext *ctx, char **field)
3257 if (!load_data (ctx, 4))
3260 if (!load_data (ctx, read_int32 (ctx->buf)))
3263 *field = pstrdup ((const char *) ctx->buf);
3269 load_file (char *name)
3272 ProfContext *ctx = (ProfContext *) g_calloc (sizeof (ProfContext), 1);
3273 if (strcmp (name, "-") == 0)
3276 ctx->file = fopen (name, "rb");
3278 printf ("Cannot open file: %s\n", name);
3281 #if defined (HAVE_SYS_ZLIB)
3282 if (ctx->file != stdin)
3283 ctx->gzfile = gzdopen (fileno (ctx->file), "rb");
3285 if (!load_data (ctx, 30))
3288 if (read_int32 (p) != LOG_HEADER_ID || p [6] > LOG_DATA_VERSION)
3290 ctx->version_major = p [4];
3291 ctx->version_minor = p [5];
3292 ctx->data_version = p [6];
3293 /* reading 64 bit files on 32 bit systems not supported yet */
3294 if (p [7] > sizeof (void*))
3296 if (read_int32 (p + 20)) /* flags must be 0 */
3298 ctx->startup_time = read_int64 (p + 8);
3299 ctx->timer_overhead = read_int32 (p + 16);
3300 ctx->pid = read_int32 (p + 24);
3301 ctx->port = read_int16 (p + 28);
3302 if (ctx->version_major >= 1) {
3303 if (!read_header_string (ctx, &ctx->args))
3305 if (!read_header_string (ctx, &ctx->arch))
3307 if (!read_header_string (ctx, &ctx->os))
3310 if (!load_data (ctx, 2)) /* old opsys field, was never used */
3320 static int alloc_sort_mode = ALLOC_SORT_BYTES;
3323 compare_class (const void *a, const void *b)
3325 ClassDesc *const *A = (ClassDesc *const *)a;
3326 ClassDesc *const *B = (ClassDesc *const *)b;
3327 uint64_t vala, valb;
3328 if (alloc_sort_mode == ALLOC_SORT_BYTES) {
3329 vala = (*A)->alloc_size;
3330 valb = (*B)->alloc_size;
3332 vala = (*A)->allocs;
3333 valb = (*B)->allocs;
3343 dump_header (ProfContext *ctx)
3345 time_t st = ctx->startup_time / 1000;
3346 char *t = ctime (&st);
3347 fprintf (outfile, "\nMono log profiler data\n");
3348 fprintf (outfile, "\tProfiler version: %d.%d\n", ctx->version_major, ctx->version_minor);
3349 fprintf (outfile, "\tData version: %d\n", ctx->data_version);
3350 if (ctx->version_major >= 1) {
3351 fprintf (outfile, "\tArguments: %s\n", ctx->args);
3352 fprintf (outfile, "\tArchitecture: %s\n", ctx->arch);
3353 fprintf (outfile, "\tOperating system: %s\n", ctx->os);
3355 fprintf (outfile, "\tMean timer overhead: %d nanoseconds\n", ctx->timer_overhead);
3356 fprintf (outfile, "\tProgram startup: %s", t);
3358 fprintf (outfile, "\tProgram ID: %d\n", ctx->pid);
3360 fprintf (outfile, "\tServer listening on: %d\n", ctx->port);
3364 dump_traces (TraceDesc *traces, const char *desc)
3371 sort_context_array (traces);
3372 for (j = 0; j < traces->count; ++j) {
3375 bt = traces->traces [j].bt;
3378 fprintf (outfile, "\t%llu %s from:\n", (unsigned long long) traces->traces [j].count, desc);
3379 for (k = 0; k < bt->count; ++k)
3380 fprintf (outfile, "\t\t%s\n", bt->methods [k]->name);
3385 dump_threads (ProfContext *ctx)
3387 ThreadContext *thread;
3388 fprintf (outfile, "\nThread summary\n");
3389 for (thread = ctx->threads; thread; thread = thread->next) {
3390 if (thread->thread_id) {
3391 fprintf (outfile, "\tThread: %p, name: \"%s\"\n", (void*)thread->thread_id, thread->name? thread->name: "");
3397 dump_domains (ProfContext *ctx)
3399 fprintf (outfile, "\nDomain summary\n");
3401 for (DomainContext *domain = ctx->domains; domain; domain = domain->next)
3402 fprintf (outfile, "\tDomain: %p, friendly name: \"%s\"\n", (void *) domain->domain_id, domain->friendly_name);
3406 dump_remctxs (ProfContext *ctx)
3408 fprintf (outfile, "\nContext summary\n");
3410 for (RemCtxContext *remctx = ctx->remctxs; remctx; remctx = remctx->next)
3411 fprintf (outfile, "\tContext: %p, domain: %p\n", (void *) remctx->remctx_id, (void *) remctx->domain_id);
3415 dump_exceptions (void)
3418 fprintf (outfile, "\nException summary\n");
3419 fprintf (outfile, "\tThrows: %llu\n", (unsigned long long) throw_count);
3420 dump_traces (&exc_traces, "throws");
3421 for (i = 0; i <= MONO_EXCEPTION_CLAUSE_FAULT; ++i) {
3422 if (!clause_summary [i])
3424 fprintf (outfile, "\tExecuted %s clauses: %llu\n", clause_name (i), (unsigned long long) clause_summary [i]);
3429 compare_monitor (const void *a, const void *b)
3431 MonitorDesc *const *A = (MonitorDesc *const *)a;
3432 MonitorDesc *const *B = (MonitorDesc *const *)b;
3433 if ((*B)->wait_time == (*A)->wait_time)
3435 if ((*B)->wait_time < (*A)->wait_time)
3441 dump_monitors (void)
3443 MonitorDesc **monitors;
3447 monitors = (MonitorDesc **) g_malloc (sizeof (void*) * num_monitors);
3448 for (i = 0, j = 0; i < SMALL_HASH_SIZE; ++i) {
3449 MonitorDesc *mdesc = monitor_hash [i];
3451 monitors [j++] = mdesc;
3452 mdesc = mdesc->next;
3455 qsort (monitors, num_monitors, sizeof (void*), compare_monitor);
3456 fprintf (outfile, "\nMonitor lock summary\n");
3457 for (i = 0; i < num_monitors; ++i) {
3458 MonitorDesc *mdesc = monitors [i];
3459 fprintf (outfile, "\tLock object %p: %d contentions\n", (void*)mdesc->objid, (int)mdesc->contentions);
3460 fprintf (outfile, "\t\t%.6f secs total wait time, %.6f max, %.6f average\n",
3461 mdesc->wait_time/1000000000.0, mdesc->max_wait_time/1000000000.0, mdesc->wait_time/1000000000.0/mdesc->contentions);
3462 dump_traces (&mdesc->traces, "contentions");
3464 fprintf (outfile, "\tLock contentions: %llu\n", (unsigned long long) monitor_contention);
3465 fprintf (outfile, "\tLock acquired: %llu\n", (unsigned long long) monitor_acquired);
3466 fprintf (outfile, "\tLock failures: %llu\n", (unsigned long long) monitor_failed);
3473 fprintf (outfile, "\nGC summary\n");
3474 fprintf (outfile, "\tGC resizes: %d\n", gc_resizes);
3475 fprintf (outfile, "\tMax heap size: %llu\n", (unsigned long long) max_heap_size);
3476 fprintf (outfile, "\tObject moves: %llu\n", (unsigned long long) gc_object_moves);
3477 for (i = 0; i < 3; ++i) {
3478 if (!gc_info [i].count)
3480 fprintf (outfile, "\tGen%d collections: %d, max time: %lluus, total time: %lluus, average: %lluus\n",
3481 i, gc_info [i].count,
3482 (unsigned long long) (gc_info [i].max_time / 1000),
3483 (unsigned long long) (gc_info [i].total_time / 1000),
3484 (unsigned long long) (gc_info [i].total_time / gc_info [i].count / 1000));
3486 for (i = 0; i < 3; ++i) {
3487 if (!handle_info [i].max_live)
3489 fprintf (outfile, "\tGC handles %s: created: %llu, destroyed: %llu, max: %llu\n",
3490 get_handle_name (i),
3491 (unsigned long long) (handle_info [i].created),
3492 (unsigned long long) (handle_info [i].destroyed),
3493 (unsigned long long) (handle_info [i].max_live));
3494 dump_traces (&handle_info [i].traces, "created");
3495 dump_traces (&handle_info [i].destroy_traces, "destroyed");
3504 int compiled_methods = 0;
3506 fprintf (outfile, "\nJIT summary\n");
3507 for (i = 0; i < HASH_SIZE; ++i) {
3508 m = method_hash [i];
3509 for (m = method_hash [i]; m; m = m->next) {
3510 if (!m->code || m->ignore_jit)
3513 code_size += m->len;
3516 fprintf (outfile, "\tCompiled methods: %d\n", compiled_methods);
3517 fprintf (outfile, "\tGenerated code size: %d\n", code_size);
3518 fprintf (outfile, "\tJIT helpers: %d\n", num_jit_helpers);
3519 fprintf (outfile, "\tJIT helpers code size: %d\n", jit_helpers_code_size);
3523 dump_allocations (void)
3526 intptr_t allocs = 0;
3528 int header_done = 0;
3529 ClassDesc **classes = (ClassDesc **) g_malloc (num_classes * sizeof (void*));
3532 for (i = 0; i < HASH_SIZE; ++i) {
3533 cd = class_hash [i];
3539 qsort (classes, num_classes, sizeof (void*), compare_class);
3540 for (i = 0; i < num_classes; ++i) {
3544 allocs += cd->allocs;
3545 size += cd->alloc_size;
3546 if (!header_done++) {
3547 fprintf (outfile, "\nAllocation summary\n");
3548 fprintf (outfile, "%10s %10s %8s Type name\n", "Bytes", "Count", "Average");
3550 fprintf (outfile, "%10llu %10zd %8llu %s\n",
3551 (unsigned long long) (cd->alloc_size),
3553 (unsigned long long) (cd->alloc_size / cd->allocs),
3555 dump_traces (&cd->traces, "bytes");
3558 fprintf (outfile, "Total memory allocated: %llu bytes in %zd objects\n", (unsigned long long) size, allocs);
3567 static int method_sort_mode = METHOD_SORT_TOTAL;
3570 compare_method (const void *a, const void *b)
3572 MethodDesc *const *A = (MethodDesc *const *)a;
3573 MethodDesc *const *B = (MethodDesc *const *)b;
3574 uint64_t vala, valb;
3575 if (method_sort_mode == METHOD_SORT_SELF) {
3576 vala = (*A)->self_time;
3577 valb = (*B)->self_time;
3578 } else if (method_sort_mode == METHOD_SORT_CALLS) {
3582 vala = (*A)->total_time;
3583 valb = (*B)->total_time;
3593 dump_metadata (void)
3595 fprintf (outfile, "\nMetadata summary\n");
3596 fprintf (outfile, "\tLoaded images: %d\n", num_images);
3600 for (i = 0; i < SMALL_HASH_SIZE; ++i) {
3601 image = image_hash [i];
3603 fprintf (outfile, "\t\t%s\n", image->filename);
3604 image = image->next;
3608 fprintf (outfile, "\tLoaded assemblies: %d\n", num_assemblies);
3610 AssemblyDesc *assembly;
3612 for (i = 0; i < SMALL_HASH_SIZE; ++i) {
3613 assembly = assembly_hash [i];
3615 fprintf (outfile, "\t\t%s\n", assembly->asmname);
3616 assembly = assembly->next;
3627 int header_done = 0;
3628 MethodDesc **methods = (MethodDesc **) g_malloc (num_methods * sizeof (void*));
3631 for (i = 0; i < HASH_SIZE; ++i) {
3632 cd = method_hash [i];
3634 cd->total_time = cd->self_time + cd->callee_time;
3639 qsort (methods, num_methods, sizeof (void*), compare_method);
3640 for (i = 0; i < num_methods; ++i) {
3647 msecs = cd->total_time / 1000000;
3648 smsecs = (cd->total_time - cd->callee_time) / 1000000;
3649 if (!msecs && !verbose)
3651 if (!header_done++) {
3652 fprintf (outfile, "\nMethod call summary\n");
3653 fprintf (outfile, "%8s %8s %10s Method name\n", "Total(ms)", "Self(ms)", "Calls");
3655 fprintf (outfile, "%8llu %8llu %10llu %s\n",
3656 (unsigned long long) (msecs),
3657 (unsigned long long) (smsecs),
3658 (unsigned long long) (cd->calls),
3660 dump_traces (&cd->traces, "calls");
3663 fprintf (outfile, "Total calls: %llu\n", (unsigned long long) calls);
3667 compare_heap_class (const void *a, const void *b)
3669 HeapClassDesc *const *A = (HeapClassDesc *const *)a;
3670 HeapClassDesc *const *B = (HeapClassDesc *const *)b;
3671 uint64_t vala, valb;
3672 if (alloc_sort_mode == ALLOC_SORT_BYTES) {
3673 vala = (*A)->total_size;
3674 valb = (*B)->total_size;
3687 compare_rev_class (const void *a, const void *b)
3689 const HeapClassRevRef *A = (const HeapClassRevRef *)a;
3690 const HeapClassRevRef *B = (const HeapClassRevRef *)b;
3691 if (B->count == A->count)
3693 if (B->count < A->count)
3699 dump_rev_claases (HeapClassRevRef *revs, int count)
3706 for (j = 0; j < count; ++j) {
3707 HeapClassDesc *cd = revs [j].klass;
3708 fprintf (outfile, "\t\t%llu references from: %s\n",
3709 (unsigned long long) (revs [j].count),
3715 heap_shot_summary (HeapShot *hs, int hs_num, HeapShot *last_hs)
3722 HeapClassDesc **sorted;
3723 sorted = (HeapClassDesc **) g_malloc (sizeof (void*) * hs->class_count);
3724 for (i = 0; i < hs->hash_size; ++i) {
3725 cd = hs->class_hash [i];
3729 size += cd->total_size;
3730 sorted [ccount++] = cd;
3732 hs->sorted = sorted;
3733 qsort (sorted, ccount, sizeof (void*), compare_heap_class);
3734 fprintf (outfile, "\n\tHeap shot %d at %.3f secs: size: %llu, object count: %llu, class count: %d, roots: %zd\n",
3736 (hs->timestamp - startup_time)/1000000000.0,
3737 (unsigned long long) (size),
3738 (unsigned long long) (count),
3739 ccount, hs->num_roots);
3740 if (!verbose && ccount > 30)
3742 fprintf (outfile, "\t%10s %10s %8s Class name\n", "Bytes", "Count", "Average");
3743 for (i = 0; i < ccount; ++i) {
3744 HeapClassRevRef *rev_sorted;
3746 HeapClassDesc *ocd = NULL;
3749 ocd = heap_class_lookup (last_hs, cd->klass);
3750 fprintf (outfile, "\t%10llu %10llu %8llu %s",
3751 (unsigned long long) (cd->total_size),
3752 (unsigned long long) (cd->count),
3753 (unsigned long long) (cd->total_size / cd->count),
3756 int64_t bdiff = cd->total_size - ocd->total_size;
3757 int64_t cdiff = cd->count - ocd->count;
3758 fprintf (outfile, " (bytes: %+lld, count: %+lld)\n", (long long) bdiff, (long long) cdiff);
3760 fprintf (outfile, "\n");
3762 if (!collect_traces)
3764 rev_sorted = (HeapClassRevRef *) g_malloc (cd->rev_count * sizeof (HeapClassRevRef));
3766 for (j = 0; j < cd->rev_hash_size; ++j) {
3767 if (cd->rev_hash [j].klass)
3768 rev_sorted [k++] = cd->rev_hash [j];
3770 assert (cd->rev_count == k);
3771 qsort (rev_sorted, cd->rev_count, sizeof (HeapClassRevRef), compare_rev_class);
3772 if (cd->root_references)
3773 fprintf (outfile, "\t\t%zd root references (%zd pinning)\n", cd->root_references, cd->pinned_references);
3774 dump_rev_claases (rev_sorted, cd->rev_count);
3775 g_free (rev_sorted);
3781 compare_heap_shots (const void *a, const void *b)
3783 HeapShot *const *A = (HeapShot *const *)a;
3784 HeapShot *const *B = (HeapShot *const *)b;
3785 if ((*B)->timestamp == (*A)->timestamp)
3787 if ((*B)->timestamp > (*A)->timestamp)
3793 dump_heap_shots (void)
3795 HeapShot **hs_sorted;
3797 HeapShot *last_hs = NULL;
3801 hs_sorted = (HeapShot **) g_malloc (num_heap_shots * sizeof (void*));
3802 fprintf (outfile, "\nHeap shot summary\n");
3804 for (hs = heap_shots; hs; hs = hs->next)
3805 hs_sorted [i++] = hs;
3806 qsort (hs_sorted, num_heap_shots, sizeof (void*), compare_heap_shots);
3807 for (i = 0; i < num_heap_shots; ++i) {
3809 heap_shot_summary (hs, i, last_hs);
3814 /* This is a very basic escape function that escapes < > and &
3815 Ideally we'd use g_markup_escape_string but that function isn't
3816 available in Mono's eglib. This was written without looking at the
3817 source of that function in glib. */
3819 escape_string_for_xml (const char *string)
3821 GString *string_builder = g_string_new (NULL);
3822 const char *start, *p;
3826 while (*p && *p != '&' && *p != '<' && *p != '>')
3829 g_string_append_len (string_builder, start, p - start);
3836 g_string_append (string_builder, "<");
3840 g_string_append (string_builder, ">");
3844 g_string_append (string_builder, "&");
3855 return g_string_free (string_builder, FALSE);
3859 sort_assemblies (gconstpointer a, gconstpointer b)
3861 CoverageAssembly *assembly_a = *(CoverageAssembly **)a;
3862 CoverageAssembly *assembly_b = *(CoverageAssembly **)b;
3864 if (assembly_a->name == NULL && assembly_b->name == NULL)
3866 else if (assembly_a->name == NULL)
3868 else if (assembly_b->name == NULL)
3871 return strcmp (assembly_a->name, assembly_b->name);
3875 dump_coverage (void)
3877 if (!coverage_methods && !coverage_assemblies)
3880 gather_coverage_statements ();
3881 fprintf (outfile, "\nCoverage Summary:\n");
3883 if (coverage_outfile) {
3884 fprintf (coverage_outfile, "<?xml version=\"1.0\"?>\n");
3885 fprintf (coverage_outfile, "<coverage version=\"0.3\">\n");
3888 g_ptr_array_sort (coverage_assemblies, sort_assemblies);
3890 for (guint i = 0; i < coverage_assemblies->len; i++) {
3891 CoverageAssembly *assembly = (CoverageAssembly *)coverage_assemblies->pdata[i];
3894 if (assembly->number_of_methods != 0) {
3895 int percentage = ((assembly->fully_covered + assembly->partially_covered) * 100) / assembly->number_of_methods;
3896 fprintf (outfile, "\t%s (%s) %d%% covered (%d methods - %d covered)\n", assembly->name, assembly->filename, percentage, assembly->number_of_methods, assembly->fully_covered);
3898 fprintf (outfile, "\t%s (%s) ?%% covered (%d methods - %d covered)\n", assembly->name, assembly->filename, assembly->number_of_methods, assembly->fully_covered);
3900 if (coverage_outfile) {
3901 char *escaped_name, *escaped_filename;
3902 escaped_name = escape_string_for_xml (assembly->name);
3903 escaped_filename = escape_string_for_xml (assembly->filename);
3905 fprintf (coverage_outfile, "\t<assembly name=\"%s\" guid=\"%s\" filename=\"%s\" method-count=\"%d\" full=\"%d\" partial=\"%d\"/>\n", escaped_name, assembly->guid, escaped_filename, assembly->number_of_methods, assembly->fully_covered, assembly->partially_covered);
3907 g_free (escaped_name);
3908 g_free (escaped_filename);
3911 classes = (GPtrArray *)g_hash_table_lookup (coverage_assembly_classes, assembly->name);
3913 for (guint j = 0; j < classes->len; j++) {
3914 CoverageClass *klass = (CoverageClass *)classes->pdata [j];
3916 if (klass->number_of_methods > 0) {
3917 int percentage = ((klass->fully_covered + klass->partially_covered) * 100) / klass->number_of_methods;
3918 fprintf (outfile, "\t\t%s %d%% covered (%d methods - %d covered)\n", klass->class_name, percentage, klass->number_of_methods, klass->fully_covered);
3920 fprintf (outfile, "\t\t%s ?%% covered (%d methods - %d covered)\n", klass->class_name, klass->number_of_methods, klass->fully_covered);
3922 if (coverage_outfile) {
3924 escaped_name = escape_string_for_xml (klass->class_name);
3926 fprintf (coverage_outfile, "\t\t<class name=\"%s\" method-count=\"%d\" full=\"%d\" partial=\"%d\"/>\n", escaped_name, klass->number_of_methods, klass->fully_covered, klass->partially_covered);
3927 g_free (escaped_name);
3933 for (guint i = 0; i < coverage_methods->len; i++) {
3934 CoverageMethod *method = (CoverageMethod *)coverage_methods->pdata [i];
3936 if (coverage_outfile) {
3937 char *escaped_assembly, *escaped_class, *escaped_method, *escaped_sig, *escaped_filename;
3939 escaped_assembly = escape_string_for_xml (method->assembly_name);
3940 escaped_class = escape_string_for_xml (method->class_name);
3941 escaped_method = escape_string_for_xml (method->method_name);
3942 escaped_sig = escape_string_for_xml (method->method_signature);
3943 escaped_filename = escape_string_for_xml (method->filename);
3945 fprintf (coverage_outfile, "\t<method assembly=\"%s\" class=\"%s\" name=\"%s (%s)\" filename=\"%s\" token=\"%d\">\n", escaped_assembly, escaped_class, escaped_method, escaped_sig, escaped_filename, method->token);
3947 g_free (escaped_assembly);
3948 g_free (escaped_class);
3949 g_free (escaped_method);
3950 g_free (escaped_sig);
3951 g_free (escaped_filename);
3953 for (guint j = 0; j < method->coverage->len; j++) {
3954 CoverageCoverage *coverage = (CoverageCoverage *)method->coverage->pdata [j];
3955 fprintf (coverage_outfile, "\t\t<statement offset=\"%d\" counter=\"%d\" line=\"%d\" column=\"%d\"/>\n", coverage->offset, coverage->count, coverage->line, coverage->column);
3957 fprintf (coverage_outfile, "\t</method>\n");
3961 if (coverage_outfile) {
3962 fprintf (coverage_outfile, "</coverage>\n");
3963 fclose (coverage_outfile);
3964 coverage_outfile = NULL;
3968 #define DUMP_EVENT_STAT(EVENT,SUBTYPE) dump_event (#EVENT, #SUBTYPE, EVENT, SUBTYPE);
3971 dump_event (const char *event_name, const char *subtype_name, int event, int subtype)
3973 int idx = event | subtype;
3974 EventStat evt = stats [idx];
3978 fprintf (outfile, "\t%16s\t%26s\tcount %6d\tmin %3d\tmax %6d\tbytes %d\n", event_name, subtype_name, evt.count, evt.min_size, evt.max_size, evt.bytes);
3984 fprintf (outfile, "\nMlpd statistics\n");
3985 fprintf (outfile, "\tBuffer count %d\toverhead %d (%d bytes per header)\n", buffer_count, buffer_count * BUFFER_HEADER_SIZE, BUFFER_HEADER_SIZE);
3986 fprintf (outfile, "\nEvent details:\n");
3988 DUMP_EVENT_STAT (TYPE_ALLOC, TYPE_ALLOC_NO_BT);
3989 DUMP_EVENT_STAT (TYPE_ALLOC, TYPE_ALLOC_BT);
3991 DUMP_EVENT_STAT (TYPE_GC, TYPE_GC_EVENT);
3992 DUMP_EVENT_STAT (TYPE_GC, TYPE_GC_RESIZE);
3993 DUMP_EVENT_STAT (TYPE_GC, TYPE_GC_MOVE);
3994 DUMP_EVENT_STAT (TYPE_GC, TYPE_GC_HANDLE_CREATED);
3995 DUMP_EVENT_STAT (TYPE_GC, TYPE_GC_HANDLE_DESTROYED);
3996 DUMP_EVENT_STAT (TYPE_GC, TYPE_GC_HANDLE_CREATED_BT);
3997 DUMP_EVENT_STAT (TYPE_GC, TYPE_GC_HANDLE_DESTROYED_BT);
3999 DUMP_EVENT_STAT (TYPE_METADATA, TYPE_END_LOAD);
4000 DUMP_EVENT_STAT (TYPE_METADATA, TYPE_END_UNLOAD);
4002 DUMP_EVENT_STAT (TYPE_METHOD, TYPE_LEAVE);
4003 DUMP_EVENT_STAT (TYPE_METHOD, TYPE_ENTER);
4004 DUMP_EVENT_STAT (TYPE_METHOD, TYPE_EXC_LEAVE);
4005 DUMP_EVENT_STAT (TYPE_METHOD, TYPE_JIT);
4007 DUMP_EVENT_STAT (TYPE_EXCEPTION, TYPE_THROW_NO_BT);
4008 DUMP_EVENT_STAT (TYPE_EXCEPTION, TYPE_THROW_BT);
4009 DUMP_EVENT_STAT (TYPE_EXCEPTION, TYPE_CLAUSE);
4011 DUMP_EVENT_STAT (TYPE_MONITOR, TYPE_MONITOR_NO_BT);
4012 DUMP_EVENT_STAT (TYPE_MONITOR, TYPE_MONITOR_BT);
4014 DUMP_EVENT_STAT (TYPE_HEAP, TYPE_HEAP_START);
4015 DUMP_EVENT_STAT (TYPE_HEAP, TYPE_HEAP_END);
4016 DUMP_EVENT_STAT (TYPE_HEAP, TYPE_HEAP_OBJECT);
4017 DUMP_EVENT_STAT (TYPE_HEAP, TYPE_HEAP_ROOT);
4019 DUMP_EVENT_STAT (TYPE_SAMPLE, TYPE_SAMPLE_HIT);
4020 DUMP_EVENT_STAT (TYPE_SAMPLE, TYPE_SAMPLE_USYM);
4021 DUMP_EVENT_STAT (TYPE_SAMPLE, TYPE_SAMPLE_UBIN);
4022 DUMP_EVENT_STAT (TYPE_SAMPLE, TYPE_SAMPLE_COUNTERS_DESC);
4023 DUMP_EVENT_STAT (TYPE_SAMPLE, TYPE_SAMPLE_COUNTERS);
4025 DUMP_EVENT_STAT (TYPE_RUNTIME, TYPE_JITHELPER);
4027 DUMP_EVENT_STAT (TYPE_COVERAGE, TYPE_COVERAGE_ASSEMBLY);
4028 DUMP_EVENT_STAT (TYPE_COVERAGE, TYPE_COVERAGE_METHOD);
4029 DUMP_EVENT_STAT (TYPE_COVERAGE, TYPE_COVERAGE_STATEMENT);
4030 DUMP_EVENT_STAT (TYPE_COVERAGE, TYPE_COVERAGE_CLASS);
4032 DUMP_EVENT_STAT (TYPE_META, TYPE_SYNC_POINT);
4038 flush_context (ProfContext *ctx)
4040 ThreadContext *thread;
4041 /* FIXME: sometimes there are leftovers: indagate */
4042 for (thread = ctx->threads; thread; thread = thread->next) {
4043 while (thread->stack_id) {
4045 fprintf (outfile, "thread %p has %d items on stack\n", (void*)thread->thread_id, thread->stack_id);
4046 pop_method (thread, thread->stack [thread->stack_id - 1], thread->last_time);
4051 static const char *reports = "header,jit,gc,sample,alloc,call,metadata,exception,monitor,thread,heapshot,counters,coverage";
4054 match_option (const char *p, const char *opt)
4056 int len = strlen (opt);
4057 if (strncmp (p, opt, len) == 0) {
4066 print_reports (ProfContext *ctx, const char *reps, int parse_only)
4070 for (p = reps; *p; p = opt) {
4071 if ((opt = match_option (p, "header")) != p) {
4076 if ((opt = match_option (p, "thread")) != p) {
4081 if ((opt = match_option (p, "domain")) != p) {
4086 if ((opt = match_option (p, "context")) != p) {
4091 if ((opt = match_option (p, "gc")) != p) {
4096 if ((opt = match_option (p, "jit")) != p) {
4101 if ((opt = match_option (p, "alloc")) != p) {
4103 dump_allocations ();
4106 if ((opt = match_option (p, "call")) != p) {
4111 if ((opt = match_option (p, "metadata")) != p) {
4116 if ((opt = match_option (p, "exception")) != p) {
4121 if ((opt = match_option (p, "monitor")) != p) {
4126 if ((opt = match_option (p, "heapshot")) != p) {
4131 if ((opt = match_option (p, "sample")) != p) {
4136 if ((opt = match_option (p, "counters")) != p) {
4141 if ((opt = match_option (p, "coverage")) != p) {
4146 if ((opt = match_option (p, "stats")) != p) {
4157 add_find_spec (const char *p)
4159 if (p [0] == 'S' && p [1] == ':') {
4161 find_size = strtoul (p + 2, &vale, 10);
4163 } else if (p [0] == 'T' && p [1] == ':') {
4173 printf ("Mono log profiler report version %d.%d\n", LOG_VERSION_MAJOR, LOG_VERSION_MINOR);
4174 printf ("Usage: mprof-report [OPTIONS] FILENAME\n");
4175 printf ("FILENAME can be '-' to read from standard input.\n");
4176 printf ("Options:\n");
4177 printf ("\t--help display this help\n");
4178 printf ("\t--out=FILE write to FILE instead of stdout\n");
4179 printf ("\t--traces collect and show backtraces\n");
4180 printf ("\t--maxframes=NUM limit backtraces to NUM entries\n");
4181 printf ("\t--reports=R1[,R2...] print the specified reports. Defaults are:\n");
4182 printf ("\t %s\n", reports);
4183 printf ("\t--method-sort=MODE sort methods according to MODE: total, self, calls\n");
4184 printf ("\t--alloc-sort=MODE sort allocations according to MODE: bytes, count\n");
4185 printf ("\t--counters-sort=MODE sort counters according to MODE: time, category\n");
4186 printf ("\t only accessible in verbose mode\n");
4187 printf ("\t--track=OB1[,OB2...] track what happens to objects OBJ1, O2 etc.\n");
4188 printf ("\t--find=FINDSPEC find and track objects matching FINFSPEC, where FINDSPEC is:\n");
4189 printf ("\t S:minimum_size or T:partial_name\n");
4190 printf ("\t--thread=THREADID consider just the data for thread THREADID\n");
4191 printf ("\t--time=FROM-TO consider data FROM seconds from startup up to TO seconds\n");
4192 printf ("\t--verbose increase verbosity level\n");
4193 printf ("\t--debug display decoding debug info for mprof-report devs\n");
4194 printf ("\t--coverage-out=FILE write the coverage info to FILE as XML\n");
4198 main (int argc, char *argv[])
4203 for (i = 1; i < argc; ++i) {
4204 if (strcmp ("--debug", argv [i]) == 0) {
4206 } else if (strcmp ("--help", argv [i]) == 0) {
4209 } else if (strncmp ("--alloc-sort=", argv [i], 13) == 0) {
4210 const char *val = argv [i] + 13;
4211 if (strcmp (val, "bytes") == 0) {
4212 alloc_sort_mode = ALLOC_SORT_BYTES;
4213 } else if (strcmp (val, "count") == 0) {
4214 alloc_sort_mode = ALLOC_SORT_COUNT;
4219 } else if (strncmp ("--method-sort=", argv [i], 14) == 0) {
4220 const char *val = argv [i] + 14;
4221 if (strcmp (val, "total") == 0) {
4222 method_sort_mode = METHOD_SORT_TOTAL;
4223 } else if (strcmp (val, "self") == 0) {
4224 method_sort_mode = METHOD_SORT_SELF;
4225 } else if (strcmp (val, "calls") == 0) {
4226 method_sort_mode = METHOD_SORT_CALLS;
4231 } else if (strncmp ("--counters-sort=", argv [i], 16) == 0) {
4232 const char *val = argv [i] + 16;
4233 if (strcmp (val, "time") == 0) {
4234 counters_sort_mode = COUNTERS_SORT_TIME;
4235 } else if (strcmp (val, "category") == 0) {
4236 counters_sort_mode = COUNTERS_SORT_CATEGORY;
4241 } else if (strncmp ("--reports=", argv [i], 10) == 0) {
4242 const char *val = argv [i] + 10;
4243 if (!print_reports (NULL, val, 1)) {
4248 } else if (strncmp ("--out=", argv [i], 6) == 0) {
4249 const char *val = argv [i] + 6;
4250 outfile = fopen (val, "w");
4252 printf ("Cannot open output file: %s\n", val);
4255 } else if (strncmp ("--maxframes=", argv [i], 12) == 0) {
4256 const char *val = argv [i] + 12;
4258 trace_max = strtoul (val, &vale, 10);
4259 } else if (strncmp ("--find=", argv [i], 7) == 0) {
4260 const char *val = argv [i] + 7;
4261 if (!add_find_spec (val)) {
4265 } else if (strncmp ("--track=", argv [i], 8) == 0) {
4266 const char *val = argv [i] + 8;
4269 uintptr_t tracked_obj;
4274 tracked_obj = strtoul (val, &vale, 0);
4275 found_object (tracked_obj);
4278 } else if (strncmp ("--thread=", argv [i], 9) == 0) {
4279 const char *val = argv [i] + 9;
4281 thread_filter = strtoul (val, &vale, 0);
4282 } else if (strncmp ("--time=", argv [i], 7) == 0) {
4283 char *val = pstrdup (argv [i] + 7);
4284 double from_secs, to_secs;
4285 char *top = strchr (val, '-');
4291 from_secs = atof (val);
4292 to_secs = atof (top);
4294 if (from_secs > to_secs) {
4298 time_from = from_secs * 1000000000;
4299 time_to = to_secs * 1000000000;
4300 use_time_filter = 1;
4301 } else if (strcmp ("--verbose", argv [i]) == 0) {
4303 } else if (strcmp ("--traces", argv [i]) == 0) {
4306 } else if (strncmp ("--coverage-out=", argv [i], 15) == 0) {
4307 const char *val = argv [i] + 15;
4308 coverage_outfile = fopen (val, "w");
4309 if (!coverage_outfile) {
4310 printf ("Cannot open output file: %s\n", val);
4321 ctx = load_file (argv [i]);
4323 printf ("Not a log profiler data file (or unsupported version).\n");
4326 while (decode_buffer (ctx));
4327 flush_context (ctx);
4328 if (num_tracked_objects)
4330 print_reports (ctx, reports, 0);