[ColdBox-4.1.0] Slow start-up time

[Nick]

It takes about 50 seconds to start the SuperSimple app (from ApplicationTemplates). Is that normal?

I'm on CF11.

[Brad Wood]

Is report execution times turned off under debugging in the administrator?

Happy Connecting. Sent from my Sprint Samsung Galaxy S® 5

--
--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/f2de703d-2265-44f6-bd16-7d0ab5d604b5%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.

[Nick]

On Wednesday, 22 July 2015 14:49:52 UTC+12, Brad Wood wrote:

Is report execution times turned off under debugging in the administrator?

Nope, it's turned on. 

[br...@bradwood.com]

Turn it off and leave it off :)

Thanks!

~Brad

ColdBox Platform Evangelist
Ortus Solutions, Corp

E-mail: br...@coldbox.org
ColdBox Platform: http://www.coldbox.org
Blog: http://www.codersrevolution.com 

 

 

--------- Original Message ---------

--

--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/ff8d0922-f973-41c3-97e4-49ae522fe48f%40googlegroups.com.

[Nick]

On Wednesday, 22 July 2015 17:00:32 UTC+12, Brad Wood wrote:

Turn it off and leave it off :)

Tried that, didn't make any difference :)

I tried CFWheels and their startup time isn't that much better (~35 secs).

I think it's just my machine not having enough grunt, as the cpu is Core 2 Duo, which is ancient.

[br...@bradwood.com]

Use Fusion Reactor and pull some stack traces while it's loading to pinpoint what the JVM 

 

Thanks!

~Brad

ColdBox Platform Evangelist
Ortus Solutions, Corp

E-mail: br...@coldbox.org
ColdBox Platform: http://www.coldbox.org
Blog: http://www.codersrevolution.com 

 

 

--------- Original Message ---------

Subject: Re: Re: [coldbox:24711] [ColdBox-4.1.0] Slow start-up time
From: "Nick" <ni...@devx.co.nz>
Date: 7/22/15 5:37 pm
To: "ColdBox Platform" <col...@googlegroups.com>

--

--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/aa8dcdb1-83fe-4bb9-9336-69acf3eb5668%40googlegroups.com.

[Nick]

On Thursday, 23 July 2015 15:06:32 UTC+12, Brad Wood wrote:

Use Fusion Reactor and pull some stack traces while it's loading to pinpoint what the JVM 

Before I try to delve further into this, can you please give me an idea of how far off the mark is a startup time of 50 seconds on my machine comparing to a typical CB set up on machines with similar spec (Core2Duo, Windows 7 Pro)?

Note that the 50-second is only for the very first time (after a CF server restart) the app loads up. All subsequent page loads (without fwreinit) are quick (< 2 seconds).

Thanks.

[br...@bradwood.com]

I have a quad core I5.  When I spin up a CommandBox server and a new app skeleton the initial load take around 3 seconds and every request after is pretty much instant.

Thanks!

~Brad

ColdBox Platform Evangelist
Ortus Solutions, Corp

E-mail: br...@coldbox.org
ColdBox Platform: http://www.coldbox.org
Blog: http://www.codersrevolution.com 

 

 

--------- Original Message ---------

Subject: Re: Re: Re: [coldbox:24713] [ColdBox-4.1.0] Slow start-up time
From: "Nick" <ni...@devx.co.nz>
Date: 7/23/15 12:33 am
To: "ColdBox Platform" <col...@googlegroups.com>
Cc: br...@bradwood.com

--

--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/b1dbb00a-7265-4ee2-b0c9-25dd47d3d223%40googlegroups.com.

[Nick]

Damn, that's too big a difference to be put down to the cpu spec. I'll do some more digging and report back what I find.

[Nick]

Brad, are you using Railo?

I've just tried Railo and I'm getting similar startup time as you (< 5 seconds).

I guess the gap between Railo and ColdFusion just got bigger in terms of performance? I don't remember it being this bad.

[br...@bradwood.com]

I used the Lucee server built into CommandBox.  (Just CD into the web root and type "start" ).

 

Adobe CF still shouldn't be that slow.Have you installed Fusion reactor to see what it's doing?

Thanks!

~Brad

ColdBox Platform Evangelist
Ortus Solutions, Corp

E-mail: br...@coldbox.org
ColdBox Platform: http://www.coldbox.org
Blog: http://www.codersrevolution.com 

 

 

--------- Original Message ---------

Subject: Re: Re: Re: Re: [coldbox:24713] [ColdBox-4.1.0] Slow start-up time
From: "Nick" <ni...@devx.co.nz>
Date: 7/28/15 12:12 am
To: "ColdBox Platform" <col...@googlegroups.com>
Cc: br...@bradwood.com

[Nick]

This is the execution times from CF's debug output:

Total Time Avg Time Count Template

32599 ms 32599 ms 1 CFC[ C:/test/Application.cfc | onApplicationStart() ] from C:/test/Application.cfc

28419 ms 28419 ms 1 CFC[ C:/test/coldbox/system/Bootstrap.cfc | loadColdbox() ] from C:/test/coldbox/system/Bootstrap.cfc

13303 ms 13303 ms 1 CFC[ C:/test/coldbox/system/web/Controller.cfc | INIT(C:/test/, cbController) ] from C:/test/coldbox/system/web/Controller.cfc

11986 ms 11986 ms 1 CFC[ C:/test/coldbox/system/web/services/LoaderService.cfc | loadApplication() ] from C:/test/coldbox/system/web/services/LoaderService.cfc

8302 ms 8302 ms 1 CFC[ C:/test/Application.cfc | onRequestStart(/index.cfm) ] from C:/test/Application.cfc

8301 ms 8301 ms 1 CFC[ C:/test/coldbox/system/Bootstrap.cfc | onRequestStart(/index.cfm) ] from C:/test/coldbox/system/Bootstrap.cfc

5355 ms 1785 ms 3 CFC[ C:/test/coldbox/system/ioc/config/Mapping.cfc | process(binder = CFC: coldbox.system.ioc.config.DefaultBinder, injector = CFC: coldbox.system.ioc.Injector) ] from C:/test/coldbox/system/ioc/config/Mapping.cfc

4916 ms 4916 ms 1 CFC[ C:/test/coldbox/system/web/Controller.cfc | getRenderer() ] from C:/test/coldbox/system/web/Controller.cfc

4915 ms 4915 ms 1 CFC[ C:/test/coldbox/system/ioc/Injector.cfc | getInstance(Renderer@coldbox) ] from C:/test/coldbox/system/ioc/Injector.cfc

4479 ms 4479 ms 1 CFC[ C:/test/coldbox/system/ioc/Injector.cfc | init([complex value], CFC: coldbox.system.web.Controller) ] from C:/test/coldbox/system/ioc/Injector.cfc

4159 ms 2080 ms 2 CFC[ C:/test/coldbox/system/ioc/scopes/NoScope.cfc | getFromScope(CFC: coldbox.system.ioc.config.Mapping, [complex value]) ] from C:/test/coldbox/system/ioc/scopes/NoScope.cfc

3971 ms 3971 ms 1 CFC[ C:/test/coldbox/system/ioc/Injector.cfc | autowire(target = CFC: coldbox.system.web.Renderer, mapping = CFC: coldbox.system.ioc.config.Mapping) ] from C:/test/coldbox/system/ioc/Injector.cfc

3932 ms 1966 ms 2 CFC[ C:/test/coldbox/system/ioc/Builder.cfc | buildDSLDependency(definition = [complex value], targetID = Renderer@coldbox, targetObject = CFC: coldbox.system.web.Renderer) ] from C:/test/coldbox/system/ioc/Builder.cfc

3920 ms 3920 ms 1 CFC[ C:/test/coldbox/system/ioc/Injector.cfc | getInstance(HTMLHelper@coldbox) ] from C:/test/coldbox/system/ioc/Injector.cfc

3826 ms 3826 ms 1 CFC[ C:/test/coldbox/system/web/services/LoaderService.cfc | createDefaultLogBox() ] from C:/test/coldbox/system/web/services/LoaderService.cfc

3607 ms 3607 ms 1 CFC[ C:/test/coldbox/system/core/util/Util.cfc | getInheritedMetaData(coldbox.system.core.dynamic.HTMLHelper, [complex value]) ] from C:/test/coldbox/system/core/util/Util.cfc

3464 ms 3464 ms 1 CFC[ C:/test/coldbox/system/cache/CacheFactory.cfc | init(CFC: coldbox.system.cache.config.CacheBoxConfig, CFC: coldbox.system.web.Controller) ] from C:/test/coldbox/system/cache/CacheFactory.cfc

1677 ms 1677 ms 1 CFC[ C:/test/coldbox/system/logging/LogBox.cfc | INIT(CFC: coldbox.system.logging.config.LogBoxConfig, CFC: coldbox.system.web.Controller) ] from C:/test/coldbox/system/logging/LogBox.cfc

1617 ms 1617 ms 1 CFC[ C:/test/coldbox/system/web/Controller.cfc | runEvent(defaultEvent = true) ] from C:/test/coldbox/system/web/Controller.cfc

1499 ms 1499 ms 1 CFC[ C:/test/coldbox/system/web/services/HandlerService.cfc | getHandler(CFC: coldbox.system.web.context.EventHandlerBean, CFC: coldbox.system.web.context.RequestContext) ] from C:/test/coldbox/system/web/services/HandlerService.cfc

1315 ms 1315 ms 1 CFC[ C:/test/coldbox/system/ioc/config/DefaultBinder.cfc | map(HTMLHelper@coldbox) ] from C:/test/coldbox/system/ioc/config/DefaultBinder.cfc

1104 ms 1104 ms 1 CFC[ C:/test/coldbox/system/web/services/RequestService.cfc | requestCapture() ] from C:/test/coldbox/system/web/services/RequestService.cfc

1053 ms 527 ms 2 CFC[ C:/test/coldbox/system/cache/providers/CacheBoxColdBoxProvider.cfc | configure() ] from C:/test/coldbox/system/cache/providers/CacheBoxColdBoxProvider.cfc

1032 ms 1032 ms 1 CFC[ C:/test/coldbox/system/web/services/RequestService.cfc | onConfigurationLoad() ] from C:/test/coldbox/system/web/services/RequestService.cfc

989 ms 989 ms 1 CFC[ C:/test/coldbox/system/ioc/Injector.cfc | getInstance(handlers.Main) ] from C:/test/coldbox/system/ioc/Injector.cfc

851 ms 851 ms 1 CFC[ C:/test/coldbox/system/core/util/Util.cfc | getInheritedMetaData(coldbox.system.web.Renderer, [complex value]) ] from C:/test/coldbox/system/core/util/Util.cfc

838 ms 838 ms 1 CFC[ C:/test/coldbox/system/core/util/Util.cfc | getInheritedMetaData(handlers.Main, [complex value]) ] from C:/test/coldbox/system/core/util/Util.cfc

781 ms 781 ms 1 CFC[ C:/test/coldbox/system/ioc/Builder.cfc | init(CFC: coldbox.system.ioc.Injector) ] from C:/test/coldbox/system/ioc/Builder.cfc

607 ms 607 ms 1 CFC[ C:/test/coldbox/system/web/Renderer.cfc | renderLayout(module = , viewModule = ) ] from C:/test/coldbox/system/web/Renderer.cfc

595 ms 595 ms 1 CFC[ C:/test/coldbox/system/logging/config/LogBoxConfig.cfc | INIT(CFCConfigPath = coldbox.system.web.config.LogBox) ] from C:/test/coldbox/system/logging/config/LogBoxConfig.cfc

522 ms 75 ms 7 CFC[ C:/test/coldbox/system/core/util/Util.cfc | getMixerUtil() ] from C:/test/coldbox/system/core/util/Util.cfc

434 ms 434 ms 1 CFC[ C:/test/coldbox/system/web/services/InterceptorService.cfc | registerInterceptionPoint(HandlerService, afterInstanceAutowire, CFC: coldbox.system.web.services.HandlerService) ] from C:/test/coldbox/system/web/services/InterceptorService.cfc

360 ms 360 ms 1 CFC[ C:/test/coldbox/system/web/services/InterceptorService.cfc | INIT(CFC: coldbox.system.web.Controller) ] from C:/test/coldbox/system/web/services/InterceptorService.cfc

335 ms 112 ms 3 CFC[ C:/test/coldbox/system/ioc/Injector.cfc | buildInstance(CFC: coldbox.system.ioc.config.Mapping, [complex value]) ] from C:/test/coldbox/system/ioc/Injector.cfc

303 ms 303 ms 1 C:/test/layouts/Main.cfm

264 ms 264 ms 1 CFC[ C:/test/coldbox/system/ioc/scopes/Singleton.cfc | getFromScope(CFC: coldbox.system.ioc.config.Mapping, [complex value]) ] from C:/test/coldbox/system/ioc/scopes/Singleton.cfc

256 ms 256 ms 1 CFC[ C:/test/coldbox/system/logging/LogBox.cfc | getLogger(CFC: coldbox.system.cache.CacheFactory) ] from C:/test/coldbox/system/logging/LogBox.cfc

231 ms 116 ms 2 CFC[ C:/test/coldbox/system/cache/providers/CacheBoxColdBoxProvider.cfc | init() ] from C:/test/coldbox/system/cache/providers/CacheBoxColdBoxProvider.cfc

231 ms 231 ms 1 CFC[ C:/test/coldbox/system/ioc/config/DefaultBinder.cfc | loadDataDSL() ] from C:/test/coldbox/system/ioc/config/DefaultBinder.cfc

146 ms 146 ms 1 CFC[ C:/test/coldbox/system/web/services/HandlerService.cfc | onConfigurationLoad() ] from C:/test/coldbox/system/web/services/HandlerService.cfc

125 ms 63 ms 2 CFC[ C:/test/coldbox/system/cache/policies/LRU.cfc | init(CFC: coldbox.system.cache.providers.CacheBoxColdBoxProvider) ] from C:/test/coldbox/system/cache/policies/LRU.cfc

121 ms 121 ms 1 CFC[ C:/test/coldbox/system/web/config/ApplicationLoader.cfc | loadConfiguration() ] from C:/test/coldbox/system/web/config/ApplicationLoader.cfc

116 ms 39 ms 3 CFC[ C:/test/coldbox/system/ioc/Builder.cfc | buildCFC(CFC: coldbox.system.ioc.config.Mapping, [complex value]) ] from C:/test/coldbox/system/ioc/Builder.cfc

114 ms 57 ms 2 CFC[ C:/test/coldbox/system/logging/LogBox.cfc | getLogger(CFC: coldbox.system.cache.policies.LRU) ] from C:/test/coldbox/system/logging/LogBox.cfc

113 ms 113 ms 1 CFC[ C:/test/coldbox/system/cache/store/ConcurrentStore.cfc | init(CFC: coldbox.system.cache.providers.CacheBoxColdBoxProvider) ] from C:/test/coldbox/system/cache/store/ConcurrentStore.cfc

93 ms 93 ms 1 CFC[ C:/test/coldbox/system/web/services/HandlerService.cfc | getRegisteredHandler(main.index) ] from C:/test/coldbox/system/web/services/HandlerService.cfc

I can't see much from FusionReactor other than total time, memory used etc. Do I need to turn anything on to get it to give me more info?

[Jon Clausen]

First I would suggest turning off "Report Execution Times".  It is a performance killer.  

[Note: Typo assistance courtesy of iPhone]

--
--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/b02b9167-36a4-4eef-800b-a20fd1f10072%40googlegroups.com.

[br...@bradwood.com]

I suggested that in my first reply Jon but he said it didn't make any difference.  I don't really believe it though-- report execution times is always a horrible performance killer and I would always recommend turning it off.

 

Sadly, I put in a ticket for Adobe to improve the performance of that setting but they couldn't reproduce it.

https://bugbase.adobe.com/index.cfm?event=bug&id=3822982

Hmm, upon examining the ticket again, i see there was an additional reply and a "speculative fix".  I guess we'll find out when CF12 ships :)

 

Nick, you need to use Fusion Reactor to pull some stack traces of the thread while it's running to see what it's doing.  

Thanks!

~Brad

ColdBox Platform Evangelist
Ortus Solutions, Corp

E-mail: br...@coldbox.org
ColdBox Platform: http://www.coldbox.org
Blog: http://www.codersrevolution.com 

 

 

--------- Original Message ---------

Subject: Re: [coldbox:24750] [ColdBox-4.1.0] Slow start-up time
From: "Jon Clausen" <jon_c...@silowebworks.com>
Date: 7/29/15 5:19 pm
To: "col...@googlegroups.com" <col...@googlegroups.com>

First I would suggest turning off "Report Execution Times".  It is a performance killer.  

[Note: Typo assistance courtesy of iPhone]

On Jul 29, 2015, at 6:14 PM, Nick <ni...@devx.co.nz> wrote:

This is the execution times from CF's debug output:

 

Total TimeAvg TimeCountTemplate

32599 ms32599 ms1CFC[ C:/test/Application.cfc | onApplicationStart() ] from C:/test/Application.cfc

28419 ms28419 ms1CFC[ C:/test/coldbox/system/Bootstrap.cfc | loadColdbox() ] from C:/test/coldbox/system/Bootstrap.cfc

13303 ms13303 ms1CFC[ C:/test/coldbox/system/web/Controller.cfc | INIT(C:/test/, cbController) ] from C:/test/coldbox/system/web/Controller.cfc

11986 ms11986 ms1CFC[ C:/test/coldbox/system/web/services/LoaderService.cfc | loadApplication() ] from C:/test/coldbox/system/web/services/LoaderService.cfc

8302 ms8302 ms1CFC[ C:/test/Application.cfc | onRequestStart(/index.cfm) ] from C:/test/Application.cfc

8301 ms8301 ms1CFC[ C:/test/coldbox/system/Bootstrap.cfc | onRequestStart(/index.cfm) ] from C:/test/coldbox/system/Bootstrap.cfc

5355 ms1785 ms3CFC[ C:/test/coldbox/system/ioc/config/Mapping.cfc | process(binder = CFC: coldbox.system.ioc.config.DefaultBinder, injector = CFC: coldbox.system.ioc.Injector) ] from C:/test/coldbox/system/ioc/config/Mapping.cfc

4916 ms4916 ms1CFC[ C:/test/coldbox/system/web/Controller.cfc | getRenderer() ] from C:/test/coldbox/system/web/Controller.cfc

4915 ms4915 ms1CFC[ C:/test/coldbox/system/ioc/Injector.cfc | getInstance(Renderer@coldbox) ] from C:/test/coldbox/system/ioc/Injector.cfc

4479 ms4479 ms1CFC[ C:/test/coldbox/system/ioc/Injector.cfc | init([complex value], CFC: coldbox.system.web.Controller) ] from C:/test/coldbox/system/ioc/Injector.cfc

4159 ms2080 ms2CFC[ C:/test/coldbox/system/ioc/scopes/NoScope.cfc | getFromScope(CFC: coldbox.system.ioc.config.Mapping, [complex value]) ] from C:/test/coldbox/system/ioc/scopes/NoScope.cfc

3971 ms3971 ms1CFC[ C:/test/coldbox/system/ioc/Injector.cfc | autowire(target = CFC: coldbox.system.web.Renderer, mapping = CFC: coldbox.system.ioc.config.Mapping) ] from C:/test/coldbox/system/ioc/Injector.cfc

3932 ms1966 ms2CFC[ C:/test/coldbox/system/ioc/Builder.cfc | buildDSLDependency(definition = [complex value], targetID = Renderer@coldbox, targetObject = CFC: coldbox.system.web.Renderer) ] from C:/test/coldbox/system/ioc/Builder.cfc

3920 ms3920 ms1CFC[ C:/test/coldbox/system/ioc/Injector.cfc | getInstance(HTMLHelper@coldbox) ] from C:/test/coldbox/system/ioc/Injector.cfc

3826 ms3826 ms1CFC[ C:/test/coldbox/system/web/services/LoaderService.cfc | createDefaultLogBox() ] from C:/test/coldbox/system/web/services/LoaderService.cfc

3607 ms3607 ms1CFC[ C:/test/coldbox/system/core/util/Util.cfc | getInheritedMetaData(coldbox.system.core.dynamic.HTMLHelper, [complex value]) ] from C:/test/coldbox/system/core/util/Util.cfc

3464 ms3464 ms1CFC[ C:/test/coldbox/system/cache/CacheFactory.cfc | init(CFC: coldbox.system.cache.config.CacheBoxConfig, CFC: coldbox.system.web.Controller) ] from C:/test/coldbox/system/cache/CacheFactory.cfc

1677 ms1677 ms1CFC[ C:/test/coldbox/system/logging/LogBox.cfc | INIT(CFC: coldbox.system.logging.config.LogBoxConfig, CFC: coldbox.system.web.Controller) ] from C:/test/coldbox/system/logging/LogBox.cfc

1617 ms1617 ms1CFC[ C:/test/coldbox/system/web/Controller.cfc | runEvent(defaultEvent = true) ] from C:/test/coldbox/system/web/Controller.cfc

1499 ms1499 ms1CFC[ C:/test/coldbox/system/web/services/HandlerService.cfc | getHandler(CFC: coldbox.system.web.context.EventHandlerBean, CFC: coldbox.system.web.context.RequestContext) ] from C:/test/coldbox/system/web/services/HandlerService.cfc

1315 ms1315 ms1CFC[ C:/test/coldbox/system/ioc/config/DefaultBinder.cfc | map(HTMLHelper@coldbox) ] from C:/test/coldbox/system/ioc/config/DefaultBinder.cfc

1104 ms1104 ms1CFC[ C:/test/coldbox/system/web/services/RequestService.cfc | requestCapture() ] from C:/test/coldbox/system/web/services/RequestService.cfc

1053 ms527 ms2CFC[ C:/test/coldbox/system/cache/providers/CacheBoxColdBoxProvider.cfc | configure() ] from C:/test/coldbox/system/cache/providers/CacheBoxColdBoxProvider.cfc

1032 ms1032 ms1CFC[ C:/test/coldbox/system/web/services/RequestService.cfc | onConfigurationLoad() ] from C:/test/coldbox/system/web/services/RequestService.cfc

989 ms989 ms1CFC[ C:/test/coldbox/system/ioc/Injector.cfc | getInstance(handlers.Main) ] from C:/test/coldbox/system/ioc/Injector.cfc

851 ms851 ms1CFC[ C:/test/coldbox/system/core/util/Util.cfc | getInheritedMetaData(coldbox.system.web.Renderer, [complex value]) ] from C:/test/coldbox/system/core/util/Util.cfc

838 ms838 ms1CFC[ C:/test/coldbox/system/core/util/Util.cfc | getInheritedMetaData(handlers.Main, [complex value]) ] from C:/test/coldbox/system/core/util/Util.cfc

781 ms781 ms1CFC[ C:/test/coldbox/system/ioc/Builder.cfc | init(CFC: coldbox.system.ioc.Injector) ] from C:/test/coldbox/system/ioc/Builder.cfc

607 ms607 ms1CFC[ C:/test/coldbox/system/web/Renderer.cfc | renderLayout(module = , viewModule = ) ] from C:/test/coldbox/system/web/Renderer.cfc

595 ms595 ms1CFC[ C:/test/coldbox/system/logging/config/LogBoxConfig.cfc | INIT(CFCConfigPath = coldbox.system.web.config.LogBox) ] from C:/test/coldbox/system/logging/config/LogBoxConfig.cfc

522 ms75 ms7CFC[ C:/test/coldbox/system/core/util/Util.cfc | getMixerUtil() ] from C:/test/coldbox/system/core/util/Util.cfc

434 ms434 ms1CFC[ C:/test/coldbox/system/web/services/InterceptorService.cfc | registerInterceptionPoint(HandlerService, afterInstanceAutowire, CFC: coldbox.system.web.services.HandlerService) ] from C:/test/coldbox/system/web/services/InterceptorService.cfc

360 ms360 ms1CFC[ C:/test/coldbox/system/web/services/InterceptorService.cfc | INIT(CFC: coldbox.system.web.Controller) ] from C:/test/coldbox/system/web/services/InterceptorService.cfc

335 ms112 ms3CFC[ C:/test/coldbox/system/ioc/Injector.cfc | buildInstance(CFC: coldbox.system.ioc.config.Mapping, [complex value]) ] from C:/test/coldbox/system/ioc/Injector.cfc

303 ms303 ms1C:/test/layouts/Main.cfm

264 ms264 ms1CFC[ C:/test/coldbox/system/ioc/scopes/Singleton.cfc | getFromScope(CFC: coldbox.system.ioc.config.Mapping, [complex value]) ] from C:/test/coldbox/system/ioc/scopes/Singleton.cfc

256 ms256 ms1CFC[ C:/test/coldbox/system/logging/LogBox.cfc | getLogger(CFC: coldbox.system.cache.CacheFactory) ] from C:/test/coldbox/system/logging/LogBox.cfc

231 ms116 ms2CFC[ C:/test/coldbox/system/cache/providers/CacheBoxColdBoxProvider.cfc | init() ] from C:/test/coldbox/system/cache/providers/CacheBoxColdBoxProvider.cfc

231 ms231 ms1CFC[ C:/test/coldbox/system/ioc/config/DefaultBinder.cfc | loadDataDSL() ] from C:/test/coldbox/system/ioc/config/DefaultBinder.cfc

146 ms146 ms1CFC[ C:/test/coldbox/system/web/services/HandlerService.cfc | onConfigurationLoad() ] from C:/test/coldbox/system/web/services/HandlerService.cfc

125 ms63 ms2CFC[ C:/test/coldbox/system/cache/policies/LRU.cfc | init(CFC: coldbox.system.cache.providers.CacheBoxColdBoxProvider) ] from C:/test/coldbox/system/cache/policies/LRU.cfc

121 ms121 ms1CFC[ C:/test/coldbox/system/web/config/ApplicationLoader.cfc | loadConfiguration() ] from C:/test/coldbox/system/web/config/ApplicationLoader.cfc

116 ms39 ms3CFC[ C:/test/coldbox/system/ioc/Builder.cfc | buildCFC(CFC: coldbox.system.ioc.config.Mapping, [complex value]) ] from C:/test/coldbox/system/ioc/Builder.cfc

114 ms57 ms2CFC[ C:/test/coldbox/system/logging/LogBox.cfc | getLogger(CFC: coldbox.system.cache.policies.LRU) ] from C:/test/coldbox/system/logging/LogBox.cfc

113 ms113 ms1CFC[ C:/test/coldbox/system/cache/store/ConcurrentStore.cfc | init(CFC: coldbox.system.cache.providers.CacheBoxColdBoxProvider) ] from C:/test/coldbox/system/cache/store/ConcurrentStore.cfc

93 ms93 ms1CFC[ C:/test/coldbox/system/web/services/HandlerService.cfc | getRegisteredHandler(main.index) ] from C:/test/coldbox/system/web/services/HandlerService.cfc

 

I can't see much from FusionReactor other than total time, memory used etc. Do I need to turn anything on to get it to give me more info?

 

 

 

--
--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/b02b9167-36a4-4eef-800b-a20fd1f10072%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.

 

--
--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/8562F0D8-1B00-43AB-8621-16731FEC1AE1%40silowebworks.com.

[Jon Clausen]

Actually, it may not have made a difference if the server wasn't cycled after the setting was changed.  

When you start up an app with execution times enabled, the CFC class files are compiled to byte code with the timers in them (or at least they were with CF9). 

The only way to blow those out completely is to either cycle CF and let it recompile or manually wipe them out in CFAdmin.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/20150729153427.a7243acce6934569d3743d1abfb94fec.88acc4882c.mailapi%40email01.secureserver.net.

[Nick]

I can absolutely confirm that unchecking "Report Execution Times" made no difference. I even tried unchecking all boxes in the "Debug Output Settings" page, cleared all caches, restarted CF server. Same result.

When I try to do stack tracing in FusionReactor, the stacks completely changes every time I refresh (about once every second), which means no single line of code as the smoking gun? Is there a way to dump the entire stack trace in FS?

[br...@bradwood.com]

Yes, you can do all threads under the "resources" section.  Even though the stack may be different, look for patterns.  For instance, is it always on a query, or on file system access, etc.

Thanks!

~Brad

ColdBox Platform Evangelist
Ortus Solutions, Corp

E-mail: br...@coldbox.org
ColdBox Platform: http://www.coldbox.org
Blog: http://www.codersrevolution.com 

 

 

--------- Original Message ---------

Subject: Re: [coldbox:24753] [ColdBox-4.1.0] Slow start-up time
From: "Nick" <ni...@devx.co.nz>
Date: 7/29/15 6:42 pm
To: "ColdBox Platform" <col...@googlegroups.com>

--

--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/93db4e1f-46fd-44bb-8b68-607aed85278e%40googlegroups.com.

[Nick]

Ok I tried my best to grab the stack traces for the request, but I'm not really sure what to look for.

I've attached the file (stacktrace.log).

[br...@bradwood.com]

Almost every single one of those threads was compiling CFML.  What are you template caching settings set to?  Is this a VM?  What's the CPU load during this time?  

 

It's as though CF isn't caching any of the templates.  Did you take these stacks immediately after clearing the template cache?  

Thanks!

~Brad

ColdBox Platform Evangelist
Ortus Solutions, Corp

E-mail: br...@coldbox.org
ColdBox Platform: http://www.coldbox.org
Blog: http://www.codersrevolution.com 

 

 

--------- Original Message ---------

Subject: Re: Re: [coldbox:24756] [ColdBox-4.1.0] Slow start-up time
From: "Nick" <ni...@devx.co.nz>
Date: 7/29/15 7:30 pm
To: "ColdBox Platform" <col...@googlegroups.com>

--

--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/999263e7-a7b8-4737-bc2f-c84a6c8b93e8%40googlegroups.com.

[Nick]

This is the first request after a CF restart, i.e. the first time the app loads up. I did mention this in one of my earlier replies :)

No it's not a VM. The CPU loading is around 50% for the entire request.

[br...@bradwood.com]

is the site just as slow on subsequent requests?  Are you saving templates to disk?

Thanks!

~Brad

ColdBox Platform Evangelist
Ortus Solutions, Corp

E-mail: br...@coldbox.org
ColdBox Platform: http://www.coldbox.org
Blog: http://www.codersrevolution.com 

 

 

--------- Original Message ---------

Subject: Re: Re: Re: [coldbox:24758] [ColdBox-4.1.0] Slow start-up time
From: "Nick" <ni...@devx.co.nz>
Date: 7/29/15 8:30 pm
To: "ColdBox Platform" <col...@googlegroups.com>
Cc: br...@bradwood.com

This is the first request after a CF restart, i.e. the first time the app loads up. I did mention this in one of my earlier replies :)

 

No it's not a VM. The CPU loading is around 50% for the entire request.

 

--

--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/ab32a0c1-7238-41e8-a5ad-7ca852286850%40googlegroups.com.

[Nick]

Nope, all subsequent requests are almost instantaneous.

All boxes on the Caching page is unticked.

[br...@bradwood.com]

Enable "SAVE CLASS FILES (DISK)".  and that should help on restarts. 

Thanks!

~Brad

ColdBox Platform Evangelist
Ortus Solutions, Corp

E-mail: br...@coldbox.org
ColdBox Platform: http://www.coldbox.org
Blog: http://www.codersrevolution.com 

 

 

--------- Original Message ---------

Subject: Re: Re: Re: Re: [coldbox:24760] [ColdBox-4.1.0] Slow start-up time
From: "Nick" <ni...@devx.co.nz>
Date: 7/29/15 8:37 pm
To: "ColdBox Platform" <col...@googlegroups.com>
Cc: br...@bradwood.com

Nope, all subsequent requests are almost instantaneous.

 

All boxes on the Caching page is unticked.

 

--

--
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
For News, visit http://blog.coldbox.org
For Documentation, visit http://wiki.coldbox.org
For Bug Reports, visit https://ortussolutions.atlassian.net/browse/COLDBOX
---
You received this message because you are subscribed to the Google Groups "ColdBox Platform" group.
To unsubscribe from this group and stop receiving emails from it, send an email to coldbox+u...@googlegroups.com.
To post to this group, send email to col...@googlegroups.com.

To view this discussion on the web visit https://groups.google.com/d/msgid/coldbox/a85e7da3-d4ca-4853-b08e-d117945faddf%40googlegroups.com.

[Nick]

Cool, ticking that box makes the app starting up 3x faster! Thanks for the suggestion.