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 97 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)
    
  • Quan 2 posts 72 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.

  • Riadul Hoque 5 posts 97 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.

Please Sign in or register to post replies

Write your reply to:

Draft