The Return Of __FILE__ And __LINE__ In .NET 4.5

Posted by Alois Kraus on Geeks with Blogs See other posts from Geeks with Blogs or by Alois Kraus
Published on Mon, 09 Apr 2012 11:15:59 GMT Indexed on 2012/04/09 17:31 UTC
Read the original article Hit count: 378

Filed under:

Good things are hard to kill. One of the most useful predefined compiler macros in C/C++ were __FILE__ and __LINE__ which do expand to the compilation units file name and line number where this value is encountered by the compiler. After 4.5 versions of .NET we are on par with C/C++ again. It is of course not a simple compiler expandable macro it is an attribute but it does serve exactly the same purpose.

Now we do get

 

The most important one is CallerMemberNameAttribute which is very useful to implement the INotifyPropertyChanged interface without the need to hard code the name of the property anymore. Now you can simply decorate your change method with the new CallerMemberName attribute and you get the property name as string directly inserted by the C# compiler at compile time.

 

public string UserName
{     
      get {  return _userName;     }     
      set {  _userName=value;        
             RaisePropertyChanged();  // no more RaisePropertyChanged(“UserName”)!   
          }
}

protected void RaisePropertyChanged([CallerMemberName] string member = "")
{   
    var copy = PropertyChanged; 
    if(copy != null)  
    {       
       copy(new PropertyChangedEventArgs(this, member));     
    }
}

Nice and handy. This was obviously the prime reason to implement this feature in the C# 5.0 compiler. You can repurpose this feature for tracing to get your hands on the method name of your caller along other stuff very fast now. All infos are added during compile time which is much faster than other approaches like walking the stack.

The example on MSDN shows the usage of this attribute with an example

public static void TraceMessage(string message, [CallerMemberName] string memberName = "", 
                               [CallerFilePath] string sourceFilePath = "", [CallerLineNumber] int sourceLineNumber = 0) 
{ 
    Console.WriteLine("Hi {0} {1} {2}({3})", message, memberName, sourceFilePath, sourceLineNumber); 
}

 

When I do think of tracing I do usually want to have a API which allows me to

  • Trace method enter and leave
  • Trace messages with a severity like Info, Warning, Error

When I do print a trace message it is very useful to print out method and type name as well. So your API must either be able to pass the method and type name as strings or extract it automatically via walking back one Stackframe and fetch the infos from there. The first glaring deficiency is that there is no CallerTypeAttribute yet because the C# compiler team was not satisfied with its performance.

 

A usable Trace Api might therefore look like

 

    enum TraceTypes
    {
        None = 0,
        EnterLeave = 1 << 0,
        Info = 1 << 1,
        Warn = 1 << 2,
        Error = 1 << 3
    }

    class Tracer : IDisposable
    {
        string Type;
        string Method;
            
        public Tracer(string type, string method)
        {
            Type = type;
            Method = method;
            if (IsEnabled(TraceTypes.EnterLeave,Type, Method))
            {
            }
        }

        private bool IsEnabled(TraceTypes traceTypes, string Type, string Method)
        {
           // Do checking here if tracing is enabled
            return false;
        }

        public void Info(string fmt, params object[] args)
        {
        }

        public void Warn(string fmt, params object[] args)
        {
        }

        public void Error(string fmt, params object[] args)
        {
        }

        public static void Info(string type, string method, string fmt, params object[] args)
        {
        }

        public static void Warn(string type, string method, string fmt, params object[] args)
        {
        }

        public static void Error(string type, string method, string fmt, params object[] args)
        {
        }

        public void Dispose()
        {
            // trace method leave
        }
    }

This minimal trace API is very fast but hard to maintain since you need to pass in the type and method name as hard coded strings which can change from time to time. But now we have at least CallerMemberName to rid of the explicit method parameter right? Not really. Since any acceptable usable trace Api should have a method signature like Tracexxx(… string fmt, params [] object args) we not able to add additional optional parameters after the args array. If we would put it before the format string we would need to make it optional as well which would mean the compiler would need to figure out what our trace message and arguments are (not likely) or we would need to specify everything explicitly just like before .

There are ways around this by providing a myriad of overloads which in the end are routed to the very same method but that is ugly. I am not sure if nobody inside MS agrees that the above API is reasonable to have or (more likely) that the whole talk about you can use this feature for diagnostic purposes was not a core feature at all but a simple byproduct of making the life of INotifyPropertyChanged implementers easier.

A way around this would be to allow for variable argument arrays after the params keyword another set of optional arguments which are always filled by the compiler but I do not know if this is an easy one.

The thing I am missing much more is the not provided CallerType attribute. But not in the way you would think of. In the API above I did add some filtering based on method and type to stay as fast as possible for types where tracing is not enabled at all. It should be no more expensive than an additional method call and a bool variable check if tracing for this type is enabled at all. The data is tightly bound to the calling type and method and should therefore become part of the static type instance. Since extending the CLR type system for tracing is not something I do expect to happen I have come up with an alternative approach which allows me basically to attach run time data to any existing type object in super fast way. The key to success is the usage of generics.

 

    class Tracer<T> : IDisposable
    {
        string Method;

        public Tracer(string method)
        {
            if (TraceData<T>.Instance.Enabled.HasFlag(TraceTypes.EnterLeave))
            {
            }
        }

        public void Dispose()
        {
            if (TraceData<T>.Instance.Enabled.HasFlag(TraceTypes.EnterLeave))
            {
            }
        }

        public static void Info(string fmt, params object[] args)
        {
        }

        /// <summary>
        /// Every type gets its own instance with a fresh set of variables to describe the 
        /// current filter status.
        /// </summary>
        /// <typeparam name="T"></typeparam>
        internal class TraceData<UsingType>
        {
            internal static TraceData<UsingType> Instance = new TraceData<UsingType>();

            public bool IsInitialized = false; // flag if we need to reinit the trace data in case of reconfigured trace settings at runtime
            public TraceTypes Enabled = TraceTypes.None; // Enabled trace levels for this type
        }
    }

We do not need to pass the type as string or Type object to the trace Api. Instead we define a generic Api that accepts the using type as generic parameter. Then we can create a TraceData static instance which is due to the nature of generics a fresh instance for every new type parameter. My tests on my home machine have shown that this approach is as fast as a simple bool flag check. If you have an application with many types using tracing you do not want to bring the app down by simply enabling tracing for one special rarely used type. The trace filter performance for the types which are not enabled must be therefore the fasted code path. This approach has the nice side effect that if you store the TraceData instances in one global list you can reconfigure tracing at runtime safely by simply setting the IsInitialized flag to false. A similar effect can be achieved with a global static Dictionary<Type,TraceData> object but big hash tables have random memory access semantics which is bad for cache locality and you always need to pay for the lookup which involves hash code generation, equality check and an indexed array access.

The generic version is wicked fast and allows you to add more features to your tracing Api with minimal perf overhead. But it is cumbersome to write the generic type argument always explicitly and worse if you do refactor code and move parts of it to other classes it might be that you cannot configure tracing correctly. I would like therefore to decorate my type with an attribute

    [CallerType]
    class Tracer<T> : IDisposable
    

to tell the compiler to fill in the generic type argument automatically.

    class Program
    {
        static void Main(string[] args)
        {
            using (var t = new Tracer()) // equivalent to new Tracer<Program>()
            {

That would be really useful and super fast since you do not need to pass any type object around but you do have full type infos at hand. This change would be breaking if another non generic type exists in the same namespace where now the generic counterpart would be preferred. But this is an acceptable risk in my opinion since you can today already get conflicts if two generic types of the same name are defined in different namespaces. This would be only a variation of this issue.

When you do think about this further you can add more features like to trace the exception in your Dispose method if the method is left with an exception with that little trick I did write some time ago. You can think of tracing as a super fast and configurable switch to write data to an output destination or to execute alternative actions. With such an infrastructure you can e.g.

  • Reconfigure tracing at run time.
  • Take a memory dump when a specific method is left with a specific exception.
  • Throw an exception when a specific trace statement is hit (useful for testing error conditions).
  • Execute a passed delegate which e.g. dumps additional state when enabled.
  • Write data to an in memory ring buffer and dump it when specific events do occur (e.g. method is left with an exception, triggered from outside).
  • Write data to an output device.
  • ….

This stuff is really useful to have when your code is in production on a mission critical server and you need to find the root cause of sporadic crashes of your application. It could be a buggy graphics card driver which throws access violations into your application (ok with .NET 4 not anymore except if you enable a compatibility flag) where you would like to have a minidump or you have reached after two weeks of operation a state where you need a full memory dump at a specific point in time in the middle of an transaction.

At my older machine I do get with this super fast approach 50 million traces/s when tracing is disabled. When I do know that tracing is enabled for this type I can walk the stack by using StackFrameHelper.GetStackFramesInternal to check further if a specific action or output device is configured for this method which is about 2-3 times faster than the regular StackTrace class. Even with one String.Format I am down to 3 million traces/s so performance is not so important anymore since I do want to do something now.

The CallerMemberName feature of the C# 5 compiler is nice but I would have preferred to get direct access to the MethodHandle and not to the stringified version of it. But I really would like to see a CallerType attribute implemented to fill in the generic type argument of the call site to augment the static CLR type data with run time data.

 

 

© Geeks with Blogs or respective owner