Sign in Register
Go to solution
Copied to clipboard

Flag this post as spam?

This post will be reported to the moderators as potential spam to be looked at


  • Riadul Hoque 5 posts 98 karma points
    Jul 24, 2019 @ 20:35
    Riadul Hoque
    0

    Umbraco seems to sleep at Umbraco.Core.DatabaseContext for 3 minutes without logging results to slow start up

    Here's the log file on a production server. Any idea on what's happening? 

     2019-07-24 16:26:29,456 [P2756/D2/T1] INFO  Umbraco.Core.CoreBootManager - Umbraco 7.12.3 application starting on WEB2
 2019-07-24 16:26:29,532 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Determining hash of code files on disk
 2019-07-24 16:26:29,549 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Hash determined (took 17ms)
 2019-07-24 16:26:29,629 [P2756/D2/T1] INFO  Umbraco.Core.MainDom - Acquiring MainDom...
 2019-07-24 16:26:29,636 [P2756/D2/T1] INFO  Umbraco.Core.MainDom - Acquired MainDom.
 2019-07-24 16:26:29,638 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving umbraco.interfaces.IDiscoverable
 2019-07-24 16:26:29,692 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved umbraco.interfaces.IDiscoverable (took 53ms)
 2019-07-24 16:26:29,692 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving umbraco.interfaces.IApplicationStartupHandler
 2019-07-24 16:26:29,694 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved umbraco.interfaces.IApplicationStartupHandler (took 1ms)
 2019-07-24 16:26:29,723 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving umbraco.interfaces.IDiscoverable
 2019-07-24 16:26:29,724 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved umbraco.interfaces.IDiscoverable (took 0ms)
 2019-07-24 16:26:29,724 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving Umbraco.Core.PropertyEditors.IPropertyEditorValueConverter
 2019-07-24 16:26:29,724 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved Umbraco.Core.PropertyEditors.IPropertyEditorValueConverter (took 0ms)
 2019-07-24 16:26:29,725 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving umbraco.interfaces.IDiscoverable
 2019-07-24 16:26:29,725 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved umbraco.interfaces.IDiscoverable (took 0ms)
 2019-07-24 16:26:29,725 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving Umbraco.Core.PropertyEditors.IPropertyValueConverter
 2019-07-24 16:26:29,726 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved Umbraco.Core.PropertyEditors.IPropertyValueConverter (took 1ms)
 2019-07-24 16:26:29,746 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving umbraco.interfaces.IDiscoverable
 2019-07-24 16:26:29,747 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved umbraco.interfaces.IDiscoverable (took 0ms)
 2019-07-24 16:26:29,747 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving Umbraco.Web.Mvc.SurfaceController
 2019-07-24 16:26:29,747 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved Umbraco.Web.Mvc.SurfaceController (took 0ms)
 2019-07-24 16:26:29,748 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving umbraco.interfaces.IDiscoverable
 2019-07-24 16:26:29,748 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved umbraco.interfaces.IDiscoverable (took 0ms)
 2019-07-24 16:26:29,748 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving Umbraco.Web.WebApi.UmbracoApiController
 2019-07-24 16:26:29,749 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved Umbraco.Web.WebApi.UmbracoApiController (took 1ms)
 2019-07-24 16:26:36,822 [P2756/D2/T1] INFO  Umbraco.Core.DatabaseContext - CanConnect = True
 2019-07-24 16:29:45,737 [P2756/D2/T1] INFO  Umbraco.Core.DatabaseContext - CanConnect = True
 2019-07-24 16:29:45,754 [P2756/D2/T1] INFO  Umbraco.Web.Cache.CacheRefresherEventHandler - Initializing Umbraco internal event handlers for cache refreshing
 2019-07-24 16:29:45,830 [P2756/D2/T1] INFO  Umbraco.Web.Search.ExamineEvents - Initializing Examine and binding to business logic events
 2019-07-24 16:29:45,974 [P2756/D2/T1] INFO  Umbraco.Web.Search.ExamineEvents - Adding examine event handlers for index providers: 4
 2019-07-24 16:29:46,171 [P2756/D2/T1] INFO  umbraco.content - Load Xml from file...
 2019-07-24 16:29:48,670 [P2756/D2/T1] INFO  umbraco.content - Loaded Xml from file.
 2019-07-24 16:29:48,880 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving umbraco.interfaces.IDiscoverable
 2019-07-24 16:29:48,881 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved umbraco.interfaces.IDiscoverable (took 0ms)
 2019-07-24 16:29:48,882 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolving [Umbraco.Core.Persistence.Mappers.MapperForAttribute]Umbraco.Core.Persistence.Mappers.BaseMapper
 2019-07-24 16:29:48,893 [P2756/D2/T1] INFO  Umbraco.Core.PluginManager - Resolved [Umbraco.Core.Persistence.Mappers.MapperForAttribute]Umbraco.Core.Persistence.Mappers.BaseMapper (took 11ms)
 2019-07-24 16:29:49,215 [P2756/D2/T1] INFO  Umbraco.Core.CoreBootManager - Umbraco application startup complete (took 199940ms)
    Copy Link
  • Quan 4 posts 74 karma points
    Jul 31, 2019 @ 14:35
    Quan
    0

    Same here (even approximately three minutes as well): 

    2019-07-31 16:15:36,869 [P9488/D11/T12] INFO  Umbraco.Core.PluginManager - Resolving umbraco.interfaces.IDiscoverable
2019-07-31 16:15:36,870 [P9488/D11/T12] INFO  Umbraco.Core.PluginManager - Resolved umbraco.interfaces.IDiscoverable (took 1ms)
2019-07-31 16:15:36,871 [P9488/D11/T12] INFO  Umbraco.Core.PluginManager - Resolving [Umbraco.Core.Persistence.Mappers.MapperForAttribute]Umbraco.Core.Persistence.Mappers.BaseMapper
2019-07-31 16:15:36,884 [P9488/D11/T12] INFO  Umbraco.Core.PluginManager - Resolved [Umbraco.Core.Persistence.Mappers.MapperForAttribute]Umbraco.Core.Persistence.Mappers.BaseMapper (took 13ms)
2019-07-31 16:18:12,279 [P9488/D11/T12] INFO  Umbraco.Web.Search.ExamineEvents - Initializing Examine and binding to business logic events
2019-07-31 16:18:12,280 [P9488/D11/T12] INFO  Umbraco.Web.Search.ExamineEvents - Adding examine event handlers for index providers: 4
2019-07-31 16:18:12,289 [P9488/D11/T12] INFO  Umbraco.Core.PluginManager - Resolving umbraco.interfaces.IDiscoverable
2019-07-31 16:18:12,290 [P9488/D11/T12] INFO  Umbraco.Core.PluginManager - Resolved umbraco.interfaces.IDiscoverable (took 0ms)
2019-07-31 16:18:12,290 [P9488/D11/T12] INFO  Umbraco.Core.PluginManager - Resolving [umbraco.businesslogic.ApplicationAttribute]umbraco.interfaces.IApplication
2019-07-31 16:18:12,297 [P9488/D11/T12] INFO  Umbraco.Core.PluginManager - Resolved [umbraco.businesslogic.ApplicationAttribute]umbraco.interfaces.IApplication (took 7ms)
[...]

    Running in debug mode from Visual Studio. I recently installed Full Text Search, so it could be related to that, although IIRC the long startup times started way before that.

    If anyone has any idea on how to solve this I'm very interested as well.

    Copy Link
  • Riadul Hoque 5 posts 98 karma points
    Dec 09, 2019 @ 22:30
    Riadul Hoque
    101

    I am gonna answer my own question, hopefully others will/may find it helpful.

    In our deploy script, we were copying dlls and config files in batches. When we copied the dlls, the application started and then when the script triggered the config files, the application started again.

    I merged the copying dlls and config files together so they were one single copy, which resulted in faster application starup.

    I brought our release time down from ~20mins to ~8mins!

    So, bottom line, don't copy files over to the pipeline in batches.

    Copy Link
Please Sign in or register to post replies

Write your reply to:

Draft