/* * trace.c: Tracing facilities for the Mono Runtime. * * Author: * Paolo Molaro (lupus@ximian.com) * Dietmar Maurer (dietmar@ximian.com) * * (C) 2002 Ximian, Inc. * Copyright 2011 Xamarin, Inc (http://www.xamarin.com) */ #include #ifdef HAVE_ALLOCA_H #include #endif #ifdef HAVE_UNISTD_H #include #endif #include #include "mini.h" #include #include #include #include #include "trace.h" #if defined (PLATFORM_ANDROID) || (defined (TARGET_IOS) && defined (TARGET_IOS)) # undef printf # define printf(...) g_log("mono", G_LOG_LEVEL_MESSAGE, __VA_ARGS__) # undef fprintf # define fprintf(__ignore, ...) g_log ("mono-gc", G_LOG_LEVEL_MESSAGE, __VA_ARGS__) #endif #ifdef __GNUC__ #define RETURN_ADDRESS_N(N) (__builtin_extract_return_addr (__builtin_return_address (N))) #define RETURN_ADDRESS() RETURN_ADDRESS_N(0) #elif defined(_MSC_VER) #include #pragma intrinsic(_ReturnAddress) #define RETURN_ADDRESS() _ReturnAddress() #define RETURN_ADDRESS_N(N) NULL #else #error "Missing return address intrinsics implementation" #endif static MonoTraceSpec trace_spec; static volatile gint32 output_lock = 0; gboolean mono_trace_eval_exception (MonoClass *klass) { int include = 0; int i; if (!klass) return FALSE; for (i = 0; i < trace_spec.len; i++) { MonoTraceOperation *op = &trace_spec.ops [i]; int inc = 0; switch (op->op){ case MONO_TRACEOP_EXCEPTION: if (strcmp ("", op->data) == 0 && strcmp ("all", op->data2) == 0) inc = 1; else if (strcmp ("", op->data) == 0 || strcmp (klass->name_space, op->data) == 0) if (strcmp (klass->name, op->data2) == 0) inc = 1; break; default: break; } if (op->exclude){ if (inc) include = 0; } else if (inc) include = 1; } return include; } gboolean mono_trace_eval (MonoMethod *method) { int include = 0; int i; for (i = 0; i < trace_spec.len; i++){ MonoTraceOperation *op = &trace_spec.ops [i]; int inc = 0; switch (op->op){ case MONO_TRACEOP_ALL: inc = 1; break; case MONO_TRACEOP_PROGRAM: if (trace_spec.assembly && (method->klass->image == mono_assembly_get_image (trace_spec.assembly))) inc = 1; break; case MONO_TRACEOP_WRAPPER: if ((method->wrapper_type == MONO_WRAPPER_NATIVE_TO_MANAGED) || (method->wrapper_type == MONO_WRAPPER_MANAGED_TO_NATIVE)) inc = 1; break; case MONO_TRACEOP_METHOD: if (mono_method_desc_full_match ((MonoMethodDesc *) op->data, method)) inc = 1; break; case MONO_TRACEOP_CLASS: if (strcmp (method->klass->name_space, op->data) == 0) if (strcmp (method->klass->name, op->data2) == 0) inc = 1; break; case MONO_TRACEOP_ASSEMBLY: if (strcmp (mono_image_get_name (method->klass->image), op->data) == 0) inc = 1; break; case MONO_TRACEOP_NAMESPACE: if (strcmp (method->klass->name_space, op->data) == 0) inc = 1; case MONO_TRACEOP_EXCEPTION: break; } if (op->exclude){ if (inc) include = 0; } else if (inc) include = 1; } return include; } static int is_filenamechar (char p) { if (p >= 'A' && p <= 'Z') return TRUE; if (p >= 'a' && p <= 'z') return TRUE; if (p >= '0' && p <= '9') return TRUE; if (p == '.' || p == ':' || p == '_' || p == '-' || p == '`') return TRUE; return FALSE; } static char *input; static char *value; static void get_string (void) { char *start = input; while (is_filenamechar (*input)){ input++; } if (value != NULL) g_free (value); value = (char *)g_malloc (input - start + 1); strncpy (value, start, input-start); value [input-start] = 0; } enum Token { TOKEN_METHOD, TOKEN_CLASS, TOKEN_ALL, TOKEN_PROGRAM, TOKEN_EXCEPTION, TOKEN_NAMESPACE, TOKEN_WRAPPER, TOKEN_STRING, TOKEN_EXCLUDE, TOKEN_DISABLED, TOKEN_SEPARATOR, TOKEN_END, TOKEN_ERROR }; static int get_token (void) { while (input [0] == '+') input++; if (input [0] == '\0') { return TOKEN_END; } if (input [0] == 'M' && input [1] == ':'){ input += 2; get_string (); return TOKEN_METHOD; } if (input [0] == 'N' && input [1] == ':'){ input += 2; get_string (); return TOKEN_NAMESPACE; } if (input [0] == 'T' && input [1] == ':'){ input += 2; get_string (); return TOKEN_CLASS; } if (input [0] == 'E' && input [1] == ':'){ input += 2; get_string (); return TOKEN_EXCEPTION; } if (*input == '-'){ input++; return TOKEN_EXCLUDE; } if (is_filenamechar (*input)){ get_string (); if (strcmp (value, "all") == 0) return TOKEN_ALL; if (strcmp (value, "program") == 0) return TOKEN_PROGRAM; if (strcmp (value, "wrapper") == 0) return TOKEN_WRAPPER; if (strcmp (value, "disabled") == 0) return TOKEN_DISABLED; return TOKEN_STRING; } if (*input == ','){ input++; return TOKEN_SEPARATOR; } fprintf (stderr, "Syntax error at or around '%s'\n", input); return TOKEN_ERROR; } static void cleanup (void) { if (value != NULL) g_free (value); } static int get_spec (int *last) { int token = get_token (); if (token == TOKEN_EXCLUDE){ token = get_spec (last); if (token == TOKEN_EXCLUDE){ fprintf (stderr, "Expecting an expression"); return TOKEN_ERROR; } if (token == TOKEN_ERROR) return token; trace_spec.ops [(*last)-1].exclude = 1; return TOKEN_SEPARATOR; } if (token == TOKEN_END || token == TOKEN_SEPARATOR || token == TOKEN_ERROR) return token; if (token == TOKEN_METHOD){ MonoMethodDesc *desc = mono_method_desc_new (value, TRUE); if (desc == NULL){ fprintf (stderr, "Invalid method name: %s\n", value); return TOKEN_ERROR; } trace_spec.ops [*last].op = MONO_TRACEOP_METHOD; trace_spec.ops [*last].data = desc; } else if (token == TOKEN_ALL) trace_spec.ops [*last].op = MONO_TRACEOP_ALL; else if (token == TOKEN_PROGRAM) trace_spec.ops [*last].op = MONO_TRACEOP_PROGRAM; else if (token == TOKEN_WRAPPER) trace_spec.ops [*last].op = MONO_TRACEOP_WRAPPER; else if (token == TOKEN_NAMESPACE){ trace_spec.ops [*last].op = MONO_TRACEOP_NAMESPACE; trace_spec.ops [*last].data = g_strdup (value); } else if (token == TOKEN_CLASS || token == TOKEN_EXCEPTION){ char *p = strrchr (value, '.'); if (p) { *p++ = 0; trace_spec.ops [*last].data = g_strdup (value); trace_spec.ops [*last].data2 = g_strdup (p); } else { trace_spec.ops [*last].data = g_strdup (""); trace_spec.ops [*last].data2 = g_strdup (value); } trace_spec.ops [*last].op = token == TOKEN_CLASS ? MONO_TRACEOP_CLASS : MONO_TRACEOP_EXCEPTION; } else if (token == TOKEN_STRING){ trace_spec.ops [*last].op = MONO_TRACEOP_ASSEMBLY; trace_spec.ops [*last].data = g_strdup (value); } else if (token == TOKEN_DISABLED) { trace_spec.enabled = FALSE; } else { fprintf (stderr, "Syntax error in trace option specification\n"); return TOKEN_ERROR; } (*last)++; return TOKEN_SEPARATOR; } MonoTraceSpec * mono_trace_parse_options (const char *options) { char *p = (char*)options; int size = 1; int last_used; int token; trace_spec.enabled = TRUE; if (*p == 0){ trace_spec.len = 1; trace_spec.ops = g_new0 (MonoTraceOperation, 1); trace_spec.ops [0].op = MONO_TRACEOP_ALL; return &trace_spec; } for (p = (char*)options; *p != 0; p++) if (*p == ',') size++; trace_spec.ops = g_new0 (MonoTraceOperation, size); input = (char*)options; last_used = 0; while ((token = (get_spec (&last_used))) != TOKEN_END){ if (token == TOKEN_ERROR) return NULL; if (token == TOKEN_SEPARATOR) continue; } trace_spec.len = last_used; cleanup (); return &trace_spec; } void mono_trace_set_assembly (MonoAssembly *assembly) { trace_spec.assembly = assembly; } static #ifdef HAVE_KW_THREAD __thread #endif int indent_level = 0; static guint64 start_time = 0; static double seconds_since_start (void) { guint64 diff = mono_100ns_ticks () - start_time; return diff/10000000.0; } static void indent (int diff) { if (diff < 0) indent_level += diff; if (start_time == 0) start_time = mono_100ns_ticks (); printf ("[%p: %.5f %d] ", (void*)mono_native_thread_id_get (), seconds_since_start (), indent_level); if (diff > 0) indent_level += diff; } static char * string_to_utf8 (MonoString *s) { char *as; GError *error = NULL; g_assert (s); if (!s->length) return g_strdup (""); as = g_utf16_to_utf8 (mono_string_chars (s), s->length, NULL, NULL, &error); if (error) { /* Happens with StringBuilders */ g_error_free (error); return g_strdup (""); } else return as; } /* * cpos (ebp + arg_info[n].offset) points to the beginning of the * stack slot for this argument. On little-endian systems, we can * simply dereference it. On big-endian systems, we need to adjust * cpos upward first if the datatype we're referencing is smaller than * a stack slot. Also - one can't assume that gpointer is also the * size of a stack slot - use SIZEOF_REGISTER instead. The following * helper macro tries to keep down the mess of all the pointer * calculations. */ #if (G_BYTE_ORDER == G_LITTLE_ENDIAN) #define arg_in_stack_slot(cpos, type) ((type *)(cpos)) #else #define arg_in_stack_slot(cpos, type) ((type *)((sizeof(type) < SIZEOF_REGISTER) ? (((gssize)(cpos)) + SIZEOF_REGISTER - sizeof(type)) : (gssize)(cpos))) #endif void mono_trace_enter_method (MonoMethod *method, char *ebp) { int i, j; MonoClass *klass; MonoObject *o; MonoJitArgumentInfo *arg_info; MonoMethodSignature *sig; char *fname; MonoGenericSharingContext *gsctx = NULL; if (!trace_spec.enabled) return; while (output_lock != 0 || InterlockedCompareExchange (&output_lock, 1, 0) != 0) mono_thread_info_yield (); fname = mono_method_full_name (method, TRUE); indent (1); printf ("ENTER: %s(", fname); g_free (fname); if (!ebp) { printf (") ip: %p\n", RETURN_ADDRESS_N (1)); goto unlock; } sig = mono_method_signature (method); arg_info = (MonoJitArgumentInfo *)alloca (sizeof (MonoJitArgumentInfo) * (sig->param_count + 1)); if (method->is_inflated) { /* FIXME: Might be better to pass the ji itself */ MonoJitInfo *ji = mini_jit_info_table_find (mono_domain_get (), (char *)RETURN_ADDRESS (), NULL); if (ji) { gsctx = mono_jit_info_get_generic_sharing_context (ji); if (gsctx && gsctx->is_gsharedvt) { /* Needs a ctx to get precise method */ printf (") \n"); goto unlock; } } } mono_arch_get_argument_info (sig, sig->param_count, arg_info); if (MONO_TYPE_ISSTRUCT (mono_method_signature (method)->ret)) { g_assert (!mono_method_signature (method)->ret->byref); printf ("VALUERET:%p, ", *((gpointer *)(ebp + 8))); } if (mono_method_signature (method)->hasthis) { gpointer *this_obj = (gpointer *)(ebp + arg_info [0].offset); if (method->klass->valuetype) { printf ("value:%p, ", *arg_in_stack_slot(this_obj, gpointer *)); } else { o = *arg_in_stack_slot(this_obj, MonoObject *); if (o) { klass = o->vtable->klass; if (klass == mono_defaults.string_class) { MonoString *s = (MonoString*)o; char *as = string_to_utf8 (s); printf ("this:[STRING:%p:%s], ", o, as); g_free (as); } else { printf ("this:%p[%s.%s %s], ", o, klass->name_space, klass->name, o->vtable->domain->friendly_name); } } else printf ("this:NULL, "); } } for (i = 0; i < mono_method_signature (method)->param_count; ++i) { gpointer *cpos = (gpointer *)(ebp + arg_info [i + 1].offset); int size = arg_info [i + 1].size; MonoType *type = mono_method_signature (method)->params [i]; if (type->byref) { printf ("[BYREF:%p], ", *arg_in_stack_slot(cpos, gpointer *)); } else switch (mini_get_underlying_type (type)->type) { case MONO_TYPE_I: case MONO_TYPE_U: printf ("%p, ", *arg_in_stack_slot(cpos, gpointer *)); break; case MONO_TYPE_BOOLEAN: case MONO_TYPE_CHAR: case MONO_TYPE_I1: case MONO_TYPE_U1: printf ("%d, ", *arg_in_stack_slot(cpos, gint8)); break; case MONO_TYPE_I2: case MONO_TYPE_U2: printf ("%d, ", *arg_in_stack_slot(cpos, gint16)); break; case MONO_TYPE_I4: case MONO_TYPE_U4: printf ("%d, ", *arg_in_stack_slot(cpos, int)); break; case MONO_TYPE_STRING: { MonoString *s = *arg_in_stack_slot(cpos, MonoString *); if (s) { char *as; g_assert (((MonoObject *)s)->vtable->klass == mono_defaults.string_class); as = string_to_utf8 (s); printf ("[STRING:%p:%s], ", s, as); g_free (as); } else printf ("[STRING:null], "); break; } case MONO_TYPE_CLASS: case MONO_TYPE_OBJECT: { o = *arg_in_stack_slot(cpos, MonoObject *); if (o) { klass = o->vtable->klass; if (klass == mono_defaults.string_class) { char *as = string_to_utf8 ((MonoString*)o); printf ("[STRING:%p:%s], ", o, as); g_free (as); } else if (klass == mono_defaults.int32_class) { printf ("[INT32:%p:%d], ", o, *(gint32 *)((char *)o + sizeof (MonoObject))); } else if (klass == mono_defaults.monotype_class) { printf ("[TYPE:%s], ", mono_type_full_name (((MonoReflectionType*)o)->type)); } else printf ("[%s.%s:%p], ", klass->name_space, klass->name, o); } else { printf ("%p, ", *arg_in_stack_slot(cpos, gpointer)); } break; } case MONO_TYPE_PTR: case MONO_TYPE_FNPTR: case MONO_TYPE_ARRAY: case MONO_TYPE_SZARRAY: printf ("%p, ", *arg_in_stack_slot(cpos, gpointer)); break; case MONO_TYPE_I8: case MONO_TYPE_U8: printf ("0x%016llx, ", (long long)*arg_in_stack_slot(cpos, gint64)); break; case MONO_TYPE_R4: printf ("%f, ", *arg_in_stack_slot(cpos, float)); break; case MONO_TYPE_R8: printf ("%f, ", *arg_in_stack_slot(cpos, double)); break; case MONO_TYPE_VALUETYPE: printf ("["); for (j = 0; j < size; j++) printf ("%02x,", *((guint8*)cpos +j)); printf ("], "); break; default: printf ("XX, "); } } printf (")\n"); fflush (stdout); unlock: mono_atomic_store_release (&output_lock, 0); } void mono_trace_leave_method (MonoMethod *method, ...) { MonoType *type; char *fname; va_list ap; MonoGenericSharingContext *gsctx; if (!trace_spec.enabled) return; while (output_lock != 0 || InterlockedCompareExchange (&output_lock, 1, 0) != 0) mono_thread_info_yield (); va_start(ap, method); fname = mono_method_full_name (method, TRUE); indent (-1); printf ("LEAVE: %s", fname); g_free (fname); if (method->is_inflated) { /* FIXME: Might be better to pass the ji itself */ MonoJitInfo *ji = mini_jit_info_table_find (mono_domain_get (), (char *)RETURN_ADDRESS (), NULL); if (ji) { gsctx = mono_jit_info_get_generic_sharing_context (ji); if (gsctx && gsctx->is_gsharedvt) { /* Needs a ctx to get precise method */ printf (") \n"); goto unlock; } } } type = mini_get_underlying_type (mono_method_signature (method)->ret); switch (type->type) { case MONO_TYPE_VOID: break; case MONO_TYPE_BOOLEAN: { int eax = va_arg (ap, int); if (eax) printf ("TRUE:%d", eax); else printf ("FALSE"); break; } case MONO_TYPE_CHAR: case MONO_TYPE_I1: case MONO_TYPE_U1: case MONO_TYPE_I2: case MONO_TYPE_U2: case MONO_TYPE_I4: case MONO_TYPE_U4: case MONO_TYPE_I: case MONO_TYPE_U: { int eax = va_arg (ap, int); printf ("result=%d", eax); break; } case MONO_TYPE_STRING: { MonoString *s = va_arg (ap, MonoString *); ; if (s) { char *as; g_assert (((MonoObject *)s)->vtable->klass == mono_defaults.string_class); as = string_to_utf8 (s); printf ("[STRING:%p:%s]", s, as); g_free (as); } else printf ("[STRING:null], "); break; } case MONO_TYPE_CLASS: case MONO_TYPE_OBJECT: { MonoObject *o = va_arg (ap, MonoObject *); if (o) { if (o->vtable->klass == mono_defaults.boolean_class) { printf ("[BOOLEAN:%p:%d]", o, *((guint8 *)o + sizeof (MonoObject))); } else if (o->vtable->klass == mono_defaults.int32_class) { printf ("[INT32:%p:%d]", o, *((gint32 *)((char *)o + sizeof (MonoObject)))); } else if (o->vtable->klass == mono_defaults.int64_class) { printf ("[INT64:%p:%lld]", o, (long long)*((gint64 *)((char *)o + sizeof (MonoObject)))); } else printf ("[%s.%s:%p]", o->vtable->klass->name_space, o->vtable->klass->name, o); } else printf ("[OBJECT:%p]", o); break; } case MONO_TYPE_PTR: case MONO_TYPE_FNPTR: case MONO_TYPE_ARRAY: case MONO_TYPE_SZARRAY: { gpointer p = va_arg (ap, gpointer); printf ("result=%p", p); break; } case MONO_TYPE_I8: { gint64 l = va_arg (ap, gint64); printf ("lresult=0x%16llx", (long long)l); break; } case MONO_TYPE_U8: { gint64 l = va_arg (ap, gint64); printf ("lresult=0x%16llx", (long long)l); break; } case MONO_TYPE_R4: case MONO_TYPE_R8: { double f = va_arg (ap, double); printf ("FP=%f", f); break; } case MONO_TYPE_VALUETYPE: { guint8 *p = (guint8 *)va_arg (ap, gpointer); int j, size, align; size = mono_type_size (type, &align); printf ("["); for (j = 0; p && j < size; j++) printf ("%02x,", p [j]); printf ("]"); break; } default: printf ("(unknown return type %x)", mono_method_signature (method)->ret->type); } //printf (" ip: %p\n", RETURN_ADDRESS_N (1)); printf ("\n"); fflush (stdout); unlock: mono_atomic_store_release (&output_lock, 0); } void mono_trace_enable (gboolean enable) { trace_spec.enabled = enable; } gboolean mono_trace_is_enabled () { return trace_spec.enabled; }