As part of my drive to be open about Morphfolia I thought I'd take a moment to discuss performance.

I'm using Tracers from the MS EntLibs to monitor performance, this is tied into the logging sub-system.  The MS EntLibs config allows you to control the level of logging - normally I have this set to log only informational, warnings and errors (priority 100, 500 and 1000, respectively).

<add 
   name="Priority Filter" 
   minimumPriority="100" 
   maximumPriority="1000" 
   type="Microsoft.Practices.EnterpriseLibrary.Logging.Filters.PriorityFilter,
   Microsoft.Practices.EnterpriseLibrary.Logging, Version=3.1.0.0, Culture=neutral,
   PublicKeyToken=b03f5f7f11d50a3a"></add>

I did an upgrade of this site on the 30th of November, and in order to get a fresh feeling for performance I lowered the minimumPriority level to 5 to catch the Tracer logs.  Below are the tracer logs for the first 143 odd requests since then; this covers the total time taken by Morphfolia to accept and respond to requests to the 'Default' HTTP Handler and various HTTP Handlers used by the Blogging sub-system.

As a general rule of thumb (for this data set) any elapsed time over 1 second will be because it was processing the first request since a DLL replacement (go the JIT compiler!).  I'll see if I can get some server specs' from the hosting company so you can qualify the times - stay tuned.

End Trace: Activity '00000000-0000-0000-0000-000000003210' in method 'Morphfolia.PublishingSystem.HttpHandlers.Atom10Feed.ProcessRequest' at 2592262012902306 ticks (elapsed time: 0.63 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003210' in method 'Morphfolia.PublishingSystem.HttpHandlers.Atom10Feed.ProcessRequest' at 2609517096325212 ticks (elapsed time: 0.395 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003210' in method 'Morphfolia.PublishingSystem.HttpHandlers.Atom10Feed.ProcessRequest' at 2626775897840274 ticks (elapsed time: 0.46 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003210' in method 'Morphfolia.PublishingSystem.HttpHandlers.Atom10Feed.ProcessRequest' at 2661278487599844 ticks (elapsed time: 0.511 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003210' in method 'Morphfolia.PublishingSystem.HttpHandlers.Atom10Feed.ProcessRequest' at 2713020283143810 ticks (elapsed time: 0.491 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003211' in method 'Morphfolia.PublishingSystem.HttpHandlers.RSS20Feed.ProcessRequest' at 2597076997921014 ticks (elapsed time: 0.573 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003211' in method 'Morphfolia.PublishingSystem.HttpHandlers.RSS20Feed.ProcessRequest' at 2614320465798210 ticks (elapsed time: 0.265 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003211' in method 'Morphfolia.PublishingSystem.HttpHandlers.RSS20Feed.ProcessRequest' at 2631587487913350 ticks (elapsed time: 0.471 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003211' in method 'Morphfolia.PublishingSystem.HttpHandlers.RSS20Feed.ProcessRequest' at 2648848156862838 ticks (elapsed time: 0.364 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003211' in method 'Morphfolia.PublishingSystem.HttpHandlers.RSS20Feed.ProcessRequest' at 2666096011202784 ticks (elapsed time: 0.441 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003211' in method 'Morphfolia.PublishingSystem.HttpHandlers.RSS20Feed.ProcessRequest' at 2683353054075192 ticks (elapsed time: 0.562 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003211' in method 'Morphfolia.PublishingSystem.HttpHandlers.RSS20Feed.ProcessRequest' at 2700617976315906 ticks (elapsed time: 0.778 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003211' in method 'Morphfolia.PublishingSystem.HttpHandlers.RSS20Feed.ProcessRequest' at 2717902973875386 ticks (elapsed time: 0.314 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2587753403672454 ticks (elapsed time: 0.475 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2588215719673356 ticks (elapsed time: 0.298 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2592691627368090 ticks (elapsed time: 0.527 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2593698897911244 ticks (elapsed time: 0.535 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2596040940879426 ticks (elapsed time: 0.891 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2600219786125986 ticks (elapsed time: 0.332 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2600885965490982 ticks (elapsed time: 0.816 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2604235543642902 ticks (elapsed time: 0.644 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2606220982621476 ticks (elapsed time: 0.352 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2612418763173606 ticks (elapsed time: 0.776 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2613313868993832 ticks (elapsed time: 0.266 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2624297898881190 ticks (elapsed time: 0.47 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2630837350241886 ticks (elapsed time: 0.346 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2632404444436206 ticks (elapsed time: 0.481 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2632449376281312 ticks (elapsed time: 0.731 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2632463339908626 ticks (elapsed time: 0.212 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2632532077610268 ticks (elapsed time: 0.214 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2632600091169294 ticks (elapsed time: 0.748 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2632652071462062 ticks (elapsed time: 0.372 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2636267799802572 ticks (elapsed time: 0.544 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2636311840898364 ticks (elapsed time: 0.174 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2641046810135772 ticks (elapsed time: 0.238 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2654748291650688 ticks (elapsed time: 0.346 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2663740259746446 ticks (elapsed time: 0.739 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2666181361512270 ticks (elapsed time: 0.456 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2667012784531080 ticks (elapsed time: 0.346 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2667844695520818 ticks (elapsed time: 0.171 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2668545451775154 ticks (elapsed time: 0.797 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2668866627449286 ticks (elapsed time: 0.671 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2668882408421784 ticks (elapsed time: 0.204 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2693081276326908 ticks (elapsed time: 0.377 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2717373429621852 ticks (elapsed time: 0.172 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2717480390952048 ticks (elapsed time: 0.177 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2720783796145938 ticks (elapsed time: 0.808 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2720992900982088 ticks (elapsed time: 0.505 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721007866164274 ticks (elapsed time: 0.46 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721054023781114 ticks (elapsed time: 0.312 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721083893847568 ticks (elapsed time: 0.172 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721147809124078 ticks (elapsed time: 0.138 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721154417280580 ticks (elapsed time: 0.236 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721386158381092 ticks (elapsed time: 0.43 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721404912245188 ticks (elapsed time: 0.132 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721479304042024 ticks (elapsed time: 0.253 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721508208752146 ticks (elapsed time: 0.146 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721541041462558 ticks (elapsed time: 0.715 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721546923646744 ticks (elapsed time: 0.225 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721593051466912 ticks (elapsed time: 0.187 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721598244206596 ticks (elapsed time: 0.383 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721626502707664 ticks (elapsed time: 0.332 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721639621696306 ticks (elapsed time: 0.367 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721656663101926 ticks (elapsed time: 0.224 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721662153590278 ticks (elapsed time: 0.136 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721756328102650 ticks (elapsed time: 0.204 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721768084762756 ticks (elapsed time: 0.914 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2721788122856826 ticks (elapsed time: 0.365 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2723488872792564 ticks (elapsed time: 0.661 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogByUrl.ProcessRequest' at 2588323581355518 ticks (elapsed time: 0.705 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogByUrl.ProcessRequest' at 2588363688140406 ticks (elapsed time: 0.156 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogByUrl.ProcessRequest' at 2588402706248634 ticks (elapsed time: 0.195 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogByUrl.ProcessRequest' at 2588631356598900 ticks (elapsed time: 0.178 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogByUrl.ProcessRequest' at 2600896176315378 ticks (elapsed time: 0.547 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogByUrl.ProcessRequest' at 2603944137515910 ticks (elapsed time: 0.27 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogByUrl.ProcessRequest' at 2680989747566706 ticks (elapsed time: 0.789 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostById.ProcessRequest' at 2613227343326892 ticks (elapsed time: 0.991 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostById.ProcessRequest' at 2613896913786480 ticks (elapsed time: 0.249 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostById.ProcessRequest' at 2628402035003340 ticks (elapsed time: 0.754 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostById.ProcessRequest' at 2671287951910968 ticks (elapsed time: 0.362 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostById.ProcessRequest' at 2721820824144282 ticks (elapsed time: 0.429 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588427283820514 ticks (elapsed time: 0.259 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588483172917628 ticks (elapsed time: 0.218 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588485962397524 ticks (elapsed time: 0.231 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588489304715428 ticks (elapsed time: 0.217 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588491624799952 ticks (elapsed time: 0.233 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588560396958196 ticks (elapsed time: 0.232 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588585447814666 ticks (elapsed time: 0.219 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588601013738998 ticks (elapsed time: 0.407 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588636414894166 ticks (elapsed time: 0.26 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2588646030180414 ticks (elapsed time: 0.29 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2600906868132852 ticks (elapsed time: 0.267 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2600918954584878 ticks (elapsed time: 0.225 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2600986459039158 ticks (elapsed time: 0.409 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2601002476791300 ticks (elapsed time: 0.334 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2641668495951426 ticks (elapsed time: 0.881 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2660860961193936 ticks (elapsed time: 0.896 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2660906967244902 ticks (elapsed time: 0.303 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2670629514530778 ticks (elapsed time: 0.516 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostByTitle.ProcessRequest' at 2723813960229480 ticks (elapsed time: 0.227 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostListByTag.ProcessRequest' at 2588873130618054 ticks (elapsed time: 0.969 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostListByTag.ProcessRequest' at 2590959787056192 ticks (elapsed time: 0.175 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostListByTag.ProcessRequest' at 2680537840282824 ticks (elapsed time: 0.683 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostListByTag.ProcessRequest' at 2687319052025520 ticks (elapsed time: 0.944 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000003522' in method 'Morphfolia.PublishingSystem.HttpHandlers.ViewBlogPostListByTag.ProcessRequest' at 2697918552282216 ticks (elapsed time: 3.786 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000004201' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2630888516593548 ticks (elapsed time: 0.88 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000004201' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2649751303695246 ticks (elapsed time: 0.768 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000004201' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2667512431227846 ticks (elapsed time: 0.671 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000004201' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2667682077725478 ticks (elapsed time: 0.909 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000004201' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2670737763101682 ticks (elapsed time: 0.733 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000004201' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2672908312000062 ticks (elapsed time: 0.874 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000004201' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2688077912548200 ticks (elapsed time: 0.853 seconds)
End Trace: Activity '00000000-0000-0000-0000-000000004201' in method 'Morphfolia.PublishingSystem.HttpHandlers.DefaultHandler.ProcessRequest' at 2691745918390740 ticks (elapsed time: 0.958 seconds)

What does "End Trace: Activity '00000000-0000-0000-0000-000000003522'" mean?  The number is a GUID which identifies the Tracer call.  Unfortunately this doesn't work quite as I expected - when Tracer calls are nested (even across different assembilies) it seems that the GUID you invoked the tracer with isn't always the one logged at the end of the processing traced - it seems to be the last one.  This isn't a big deal as you can still identify which calls were which, but I'll see if I can clean it up at some point.