Last night I got a question from one of the readers of the blog that went like this:
“We are facing a problem that i cannot understand, every now and than i see that my app domain is recycled (i have a log in the application_end), I check the IIS logs and i don't see a restart of IIS and i know that no one is changing configuration (web.config).
I wanted to know if you know of any way that i can pinpoint the reason for that app domain to die?
The application pool that i am using only have the recycle every 20 minutes of idle time enabled“
..and I thought that since I haven’t written for a while (due to a really nice and long vacationJ) and this is a pretty common scenario I would write a post on it…
Before we go into the details of how to figure out why it is recycling I want to bring up two things
What happens when an application domain is recycled?
In ASP.NET each individual asp.net application resides in its own application domain, so for example if you have the following website structure
…where each of the subwebs HrWeb, EmployeeServices etc. are set up as an application in the internet service manager, you will have the following application domains (appdomains) in your asp.net process
Apart from the first three domains (in italic) which are a bit special, each of the other ones contain the data pertinent to that application (Note: this is a bit simplified for readability), specifically they contain these things worth noting…
When the application domain is unloaded all of this goes away, which means that on the next request that comes in all assemblies need to be reloaded, the code has to be re-jitted and the cache including any in-proc session variables etc. are empty. This can be a pretty big perf-hit for the application so as you can imagine it is important to not have the application domain recycle too often.
Why does an application domain recycle?
An application domain will unload when any one of the following occurs:
There may be some reasons in 2.0 that I have missed but hopefully this should cover most scenarios.
I want to pay a bit more attention to a few of these, which seem to be especially popularJ
Unexpected config or bin directory changes
You swear on all that is holy that no-one is touching these, but still when we start logging (as I’ll show later) the reason for the app domain recycle is a config change… how the heck can that be?
Elementary, Dr. Watson… something else is touching them… and that something else is usually a virus scanning software or backup software or an indexing service. They don’t actually modify the contents of the files, but many virus scanners etc. will modify attributes of files which is enough for the file changes monitor to jump in and say “aha !, something changed, better recycle the appdomain to update the changes”.
If you have a virus scanner that does this, you should probably consider removing the content directories from the real-time scan, of course after carefully making sure that no-one can access and add any virus software to these directories.
Web site updates while the web server is under moderate to heavy load
Picture this scenario: You have an application with 10 assemblies in the bin directory a.dll, b.dll, c.dll etc. (all with the version number 1.00.00). Now you need to update some of the assemblies to your new and improved version 1.00.12, and you do so while the application is still under heavy load because we have this great feature allowing you to update assemblies on the go… well, think again...
Say you update 7 of the 10 assemblies and for simplicity lets say this takes about 7 seconds, and in those 7 seconds you have 3 requests come in… then you may have a situation that looks something like this…
Sec 1. a.dll and b.dll are update to v 1.00.12 - appdomain unload started (any pending requests will finish before it is completely unloaded)
Sec 2. Request1 comes in and loads a new appdomain with 2 out of 7 of the dlls updated
Sec 3. c.dll is updated - appdomain unload started (any pending requests will finish before it is completely unloaded)
Sec 4. d.dll is updated
Sec 5. Request2 comes in and loads a new appdomain, now with 4 out of 7 dlls updated
Sec 6. e.dll and f.dll is updated - appdomain unload started (any pending requests will finish before it is completely unloaded)
Sec 7. f.dll is updated
Sec 8. Request3 comes in and loads a new appdomain with all 7 dlls updated
So, many bad things happened here…
First off you had 3 application domain restarts while you probably thought you would only have one, because asp.net has no way of knowing when you are done. Secondly we got a situation where Request1 and Request2 were executing with partially updated dlls, which may generate a whole new set of exceptions if the dlls depend on updates in the other new dlls, I think you get the picture… And thirdly you may get exceptions like “Cannot access file AssemblyName because it is being used by another process” because the dlls are locked during shadow copying. http://support.microsoft.com/kb/810281
In other words, don’t batch update during load…
So, is this feature completely worthless? No… if you want to update one dll, none of the problems above occur… and if you update under low or no load you are not likely to run into any of the above issues, so in that case you save yourself an IIS restart… but if you want to update in bulk you should first take the application offline.
There is a way to get around it, if you absolutely, positively need to update under load, and it is outlined in the kb article mentioned above…
In 1.1 we introduced two new config settings called <httpRuntime waitChangeNotification= /> and <httpRuntime maxWaitChangeNotification= />.
The waitChangeNotification indicates how many seconds we should wait for a new change notification before the next request triggers an appdomain restart. I.e. if we have a dll updated at second 1, and then a new one at second 3, and our waitChangeNotification is set to 5… we would wait until second 8 (first 1+5, and then changed to 3+5) before a new request would get a new domain, so a request at second 2 would simply continue using the old domain. (The time is sliding so it is always 5 seconds from the last change)
The maxWaitChangeNotification indicates the maximum number of seconds to wait from the first request. If we set this to 10 in the case where we update at second 1 and 3, we would still get a new domain if a request came in at second 8 since the waitChangeNotification expired. If we set this to 6 however, we would get a new domain already if a request came in at second 7, since the maxWaitChangeNotification had then expired. So this is an absolute expiration rather than a sliding… and we will recycle at the earliest of the maxWaitChangeNotification and waitChangeNotification.
In the scenario at the beginning of this section we could have set the waitChangeNotification to 3 seconds and the maxWaitChangeNotification to 10 seconds for example to avoid the problems.
(I know this explanation might have been a bit confusion but I hope you catch the drift)
A few things are important if you fiddle with these settings
A common scenario here is that you have a set of aspx pages (containing some news items and what not) and you have a content editor that goes in periodically and updates the news with some new articles or other new content. Every time you update an aspx page it has to be recompiled, because again, asp.net has no way of knowing if it was a code update or just update of some static text… all it knows is that someone updated the files.
If you have followed some of my previous posts you know that assemblies can not be unloaded unless the application domain is unloaded, and since each recompile would generate a new assembly there is a limit to how many recompiles you can do, to avoid generation of too many assemblies (and thus limiting the memory usage for these). By default this limit is 15.
If the contents of the page is constantly updated I would recommend to dynamically get the content from a database or file rather than actually modifying the aspx pages. Or alternatively using frames with HTML pages for this content.
How do you determine that you have application recycles?
If you experience cache or session loss, it is probably a good bet, but to make sure you can look at the perfmon counter ASP.NET v…/Application Restarts.
How do you determine what caused an appdomain restart?
In ASP.NET 2.0 you can use the built in Health Monitoring Events to log application restarts along with the reason for the restart. To do this you change the master web.config file in the C:\WINDOWS\Microsoft.NET\Framework\v2.0.50727\CONFIG directory and add the following to the <healthMonitoring><rules> section
<add name="Application Lifetime Events Default" eventName="Application Lifetime Events"
provider="EventLogProvider" profile="Default" minInstances="1"
maxLimit="Infinite" minInterval="00:01:00" custom="" />
For a web.config change this generates an event like so:
Event Type: Information
Event Source: ASP.NET 2.0.50727.0
Event Category: Web Event
Event ID: 1305
Event code: 1002
Event message: Application is shutting down. Reason: Configuration changed.
Event time: 2006-08-02 13:33:19
Event time (UTC): 2006-08-02 11:33:19
Event ID: 6fc2b84de5b74b5ba65b21804d18b7bf
Event sequence: 8
Event occurrence: 1
Event detail code: 50004
Application domain: /LM/w3svc/1/ROOT/DebuggerSamples-9-127989919076505325
Trust level: Full
Application Virtual Path: /DebuggerSamples
Application Path: c:\inetpub\wwwroot\DebuggerSamples\
Machine name: PRATHER
Process ID: 4876
Process name: w3wp.exe
Account name: NT AUTHORITY\NETWORK SERVICE
Custom event details:
For more information, see Help and Support Center at http://go.microsoft.com/fwlink/events.asp.
There is a lot of nice events to capture and you can even write your own providers and events. To get more info about this and other events you can enable, you can check out this article: http://msdn2.microsoft.com/en-us/library/ms228103.aspx
For ASP.NET 1.1 you can make use of private reflection to get a hold of the shutdown message (this works in 2.0 as well btw, but I wanted to show you both ways).
If you are not interested in the details and just want to cut to the chase and log it, check out ScottGu’s blog http://weblogs.asp.net/scottgu/archive/2005/12/14/433194.aspx on how to do this (super nice with ready-to-go code samples that you just plug in to your app).
If you are like me and need to know every little detail of every little thing… here is how it’s doneJ
As I mentioned before, each domain has a HttpRuntime object…
0:014> !do 0x04f6f324
Size 116(0x74) bytes
GC Generation: 2
mdToken: 0x02000078 (c:\winnt\assembly\gac\system.web\1.0.5000.0__b03f5f7f11d50a3a\system.web.dll)
MT Field Offset Type Attr Value Name
0x00e39df4 0x4000680 0x4 CLASS instance 0x00000000 _namedPermissionSet
0x00e39df4 0x4000681 0x8 CLASS instance 0x01031904 _fcm
0x00e39df4 0x4000682 0xc CLASS instance 0x01031b64 _cache
0x00e39df4 0x4000683 0x54 System.Boolean instance 0 _isOnUNCShare
0x00e39df4 0x4000684 0x10 CLASS instance 0x01033c88 _profiler
0x00e39df4 0x4000685 0x14 CLASS instance 0x01033ca4 _timeoutManager
0x00e39df4 0x4000686 0x18 CLASS instance 0x0104ded4 _requestQueue
0x00e39df4 0x4000687 0x55 System.Boolean instance 0 _apartmentThreading
0x00e39df4 0x4000688 0x56 System.Boolean instance 0 _beforeFirstRequest
0x00e39df4 0x4000689 0x60 VALUETYPE instance start at 0x010318c8 _firstRequestStartTime
0x00e39df4 0x400068a 0x57 System.Boolean instance 1 _firstRequestCompleted
0x00e39df4 0x400068b 0x58 System.Boolean instance 0 _userForcedShutdown
0x00e39df4 0x400068c 0x59 System.Boolean instance 1 _configInited
0x00e39df4 0x400068d 0x50 System.Int32 instance 0 _activeRequestCount
0x00e39df4 0x400068e 0x5a System.Boolean instance 0 _someBatchCompilationStarted
0x00e39df4 0x400068f 0x5b System.Boolean instance 0 _shutdownInProgress
0x00e39df4 0x4000690 0x1c CLASS instance 0x00000000 _shutDownStack
0x00e39df4 0x4000691 0x20 CLASS instance 0x00000000 _shutDownMessage
0x00e39df4 0x4000692 0x68 VALUETYPE instance start at 0x010318d0 _lastShutdownAttemptTime
0x00e39df4 0x4000693 0x5c System.Boolean instance 1 _enableHeaderChecking
0x00e39df4 0x4000694 0x24 CLASS instance 0x01033e44 _handlerCompletionCallback
0x00e39df4 0x4000695 0x28 CLASS instance 0x01033e60 _asyncEndOfSendCallback
0x00e39df4 0x4000696 0x2c CLASS instance 0x01033e7c _appDomainUnloadallback
0x00e39df4 0x4000697 0x30 CLASS instance 0x00000000 _initializationError
0x00e39df4 0x4000698 0x34 CLASS instance 0x00000000 _appDomainShutdownTimer
0x00e39df4 0x4000699 0x38 CLASS instance 0x0104dc60 _codegenDir
0x00e39df4 0x400069a 0x3c CLASS instance 0x00fc3c48 _appDomainAppId
0x00e39df4 0x400069b 0x40 CLASS instance 0x00fc3ca4 _appDomainAppPath
0x00e39df4 0x400069c 0x44 CLASS instance 0x00fc3d8c _appDomainAppVPath
0x00e39df4 0x400069d 0x48 CLASS instance 0x00fc3d04 _appDomainId
0x00e39df4 0x400069e 0x4c CLASS instance 0x00000000 _resourceManager
0x00e39df4 0x400069f 0x5d System.Boolean instance 0 _debuggingEnabled
0x00e39df4 0x40006a0 0x5e System.Boolean instance 0 _vsDebugAttach
0x00e39df4 0x400067b 0 CLASS shared static _theRuntime
>> Domain:Value 0x0014af68:NotInit 0x0017cd60:0x04f6f324 0x002165d0:0x04fcb660 <<
0x00e39df4 0x400067c 0x4 CLASS shared static s_autogenKeys
>> Domain:Value 0x0014af68:NotInit 0x0017cd60:0x04f6ef28 0x002165d0:0x04fcb474 <<
0x00e39df4 0x400067d 0xc System.Boolean shared static s_initialized
>> Domain:Value 0x0014af68:NotInit 0x0017cd60:1 0x002165d0:1 <<
0x00e39df4 0x400067e 0x8 CLASS shared static s_installDirectory
>> Domain:Value 0x0014af68:NotInit 0x0017cd60:0x04f6f19c 0x002165d0:0x04fcb4d8 <<
0x00e39df4 0x400067f 0x10 System.Boolean shared static s_isapiLoaded
The HttpRuntime object is a static, and to get to the particular HttpRuntime object for our domain we can dump out any HttpRuntime object and look at the _theRuntime static member… static member variables look a little bit special when you dump with !do… instead of getting the address straight away you get a list like this:
This means that in domain 0x0014af68 we haven’t initialized this object yet, in domain 0x0017cd60 it is located at address 0x04f6f324, and in domain 0x002165d0 it is located at address 0x04fcb660.
You can get the address of your domain from !dumpdomain, for example this one is for Domain 3, which we can see is HrWeb
Domain 3: 0x2165d0
Now why am I bothering with this HttpRuntime? Well… there are a lot of goodies found in the HttpRuntime object, things like _debugginEnabled to see if debug=true, and the address of the cache object, but particularly interesting for this case, it also contains two member variables named _shutDownStack and _shutDownMessage which we make use of when logging the event.
So in our Application_End in global.asax we can put code like this to first get the _theRuntime object for our domain,
HttpRuntime runtime = (HttpRuntime) typeof(System.Web.HttpRuntime).InvokeMember("_theRuntime", BindingFlags.NonPublic | BindingFlags.Static | BindingFlags.GetField, null, null, null);
…and then get the contents of the shutDownMessage and shutDownStack like this…
string shutDownMessage = (string) runtime.GetType().InvokeMember("_shutDownMessage", BindingFlags.NonPublic | BindingFlags.Instance | BindingFlags.GetField, null, runtime, null);
string shutDownStack = (string) runtime.GetType().InvokeMember("_shutDownStack", BindingFlags.NonPublic | BindingFlags.Instance | BindingFlags.GetField, null, runtime, null);
And then it is just a matter of logging them to the event log or a log file or similar and wait for the recycle to happen.
Oh, just one more detail before I sign off… if you see config change notifications and you are not sure who is touching your files, I would recommend running filemon from http://www.sysinternals.com/ to log file access. Great tool for a lot of security and file related issues.