views:

933

answers:

4

Hi,

I'm new to log4net, so hopefully this is a really easy question for someone?!

I've got log4net working with the RollingLogFileAppender for my web application. I'm using logging to try and find where some performance issues are coming from. In order to do this, it'd be useful to include the ASP.NET SessionID in the log output so that I can make sure I'm looking at log entries for a specific user.

Is there any way I can do this through the conversionPattern setting for the appender? Is there a %property{??} setting I can use?

Thanks,

Chris

UPDATE: This question still hasn't been answered - does anybody have any ideas?

A: 

You can try:

<conversionPattern
    value="%date %-5level %logger ${COMPUTERNAME} [%property{SessionID}] - %message%newline" />

...in your Web.config, and in Global.asax.cs:

protected void Session_Start(object sender, EventArgs e)
{
    log4net.ThreadContext.Properties["SessionID"] = Session.SessionID;
    log4net.Config.XmlConfigurator.Configure();
}
Richard Ev
Hmm - this seems to work, but it looks like the session ID only appears for things that happen in the first postback. Subsequent trips back to the server have SessionID = null. Any other thoughts?
Chris Roberts
Not sure I'm afraid. We used this approach a while ago and had a suspicion that this setting would set a SessionID property for _everyone_ using the web app at that time (i.e. user1's SessionID propery value would get overridden by user2's). Sorry I can't offer any more real insight...
Richard Ev
I don't think this will work. Session_Start is only called on the first request that establishes a session. The properties assigned to that ThreadContext will die when the thread dies. So when the next request comes in, session is already established and no property will be set in ThreadContext. For this to work you should use the Application_BeginRequest event.
Peter Lillevold
A: 

Sorry, I'm not familiar with ASP.NET and thus is more an attempt than a real answer, but isn't there some hook that gets called everytime a request arrives?

If so, can't you grab the current SessionID there and put it in a "LogicalThreadContext" property (instead of just a ThreadContext property)?

Christian.K
+2  A: 

You should create Application_PostAcquireRequestState handler in Global.asax.cs (it is called in every request):

protected void Application_PostAcquireRequestState(object sender, EventArgs e)
{
    log4net.ThreadContext.Properties["SessionID"] = Session.SessionID;
}

And add [%property{SessionID}] to conversionPattern.

Alexander K.
+1  A: 

Someone corrects me if I am wrong, but one ASP.NET thread can handle multiple sessions, so you can not use Session_Start as it is called once when the session starts. What it means is that as soon as a different user accesss the web site, your log4net.ThreadContext might be overwritten by the new user information.

You can either put the below code in Application_AcquireRequestState, or create a HttpModule and do that in AcquireRequestState method. AcquireRequestState is called when ASP.NET runtime is ready to acquire the Session state of the current HTTP request. If you interested in getting username, you can do that in AuthenticateRequest which is raised when ASP.NET runtime is ready to authenticate the identity of the user (and before the AcquireRequestState).

    private void AcquireRequestState(Object source, EventArgs e)
    {
        HttpApplication application = (HttpApplication)source;
        HttpContext context = application.Context;
        log4net.ThreadContext.Properties["SessionId"] = context.Session.SessionID;
     }

After that you can set up your log4net.config (or in web.config) like this.

<appender name="rollingFile"
      type="log4net.Appender.RollingFileAppender,log4net" >
  <param name="AppendToFile" value="false" />
  <param name="RollingStyle" value="Date" />
  <param name="DatePattern" value="yyyy.MM.dd" />
  <param name="StaticLogFileName" value="true" />

  <param name="File" value="log.txt" />
  <layout type="log4net.Layout.PatternLayout,log4net">
    <param name="ConversionPattern"
      value="%property{SessionId} %d [%t] %-5p %c - %m%n" />
  </layout>
</appender>

Hope this helps!

kimsk