EMPTY TEMPLATE and stll 5 seconds to load?

Any one can tell me why it takes Time: 5.6007 (5.9452) after TPL when there is none to load ?





This is from an empty index.tpl

index.tpl

 <br />
{* $Id: index.tpl 11903 2011-02-24 09:18:16Z aelita $ *}<br />
<!DOCTYPE html PUBLIC "-//W3C//DTD XHTML 1.0 Transitional//EN" "[url="http://www.w3.org/TR/xhtml1/DTD/xhtml1-transitional.dtd"]http://www.w3.org/TR/xhtml1/DTD/xhtml1-transitional.dtd[/url]"><br />
<html xmlns="[url="http://www.w3.org/1999/xhtml"]http://www.w3.org/1999/xhtml[/url]" lang="{$smarty.const.CART_LANGUAGE|lower}"><br />
<head><br />
{strip}<br />
{/strip}<br />

```<br />
<br />
<br />
<br />
- Queries time: 0.5341<br />
<br />
[b]After init[/b]<br />
- Memory: 7,422,584<br />
- Files: 53<br />
- Queries: 12<br />
<br />
[b]Before TPL[/b]<br />
[b]- Memory: 1,409,192 (8,831,776)[/b]<br />
- Files: 33 (86)<br />
- Queries: 12 (24)<br />
- Time: 0.3445 (0.3445)<br />
<br />
[b]After TPL[/b]<br />
[b]- Memory: 1,089,856 (9,921,632)[/b]<br />
- Files: 17 (103)<br />
- Queries: 0 (24)<br />
-[b] Time: 5.6007 (5.9452)[/b]

Actually if i hack the profiler class to show files it loads… the culprit is these files…which seems to be the main core TEMPLATING / CACHING… so at the base its a nice 6 seconds load for a BLANK page…something is WRONG



[79] => /www/sites/www.somesite.com/controllers/customer/index.php

[80] => /www/sites/www.somesite.com/lib/templater/internals/core.get_microtime.php

[81] => /www/sites/www.somesite.com/lib/templater/internals/core.read_cache_file.php

[82] => /www/sites/www.somesite.com/lib/templater/internals/core.write_cache_file.php

[83] => /www/sites/www.somesite.com/lib/templater/internals/core.write_file.php

[84] => /www/sites/www.somesite.com/lib/templater/internals/core.create_dir_structure.php

[85] => /www/sites/www.somesite.com/lib/templater/internals/core.process_cached_inserts.php

[86] => /www/sites/www.somesite.com/lib/templater/internals/core.display_debug_console.php

[87] => /www/sites/www.somesite.com/lib/templater/internals/core.assemble_plugin_filepath.php

[88] => /www/sites/www.somesite.com/lib/templater/plugins/function.assign_debug_info.php

[89] => /www/sites/www.somesite.com/core/templater_plugins/function.cycle.php

[90] => /www/sites/www.somesite.com/core/templater_plugins/compiler.assign.php

[91] => /www/sites/www.somesite.com/lib/templater/internals/core.write_compiled_resource.php

[92] => /www/sites/www.somesite.com/var/compiled/customer/%%84^84D^84D2724B%%debug.tpl.php

[93] => /www/sites/www.somesite.com/lib/templater/internals/core.load_plugins.php

[94] => /www/sites/www.somesite.com/core/templater_plugins/modifier.escape.php

[95] => /www/sites/www.somesite.com/lib/templater/plugins/modifier.string_format.php

[96] => /www/sites/www.somesite.com/lib/templater/plugins/modifier.debug_print_var.php













- Queries time: 0.9313

After init
- Memory: 7,262,464
- Files: 50
- F: Array

(

[0] => /www/sites/www.somesite.com/index.php

[1] => /www/sites/www.somesite.com/prepare.php

[2] => /www/sites/www.somesite.com/init.php

[3] => /www/sites/www.somesite.com/config.php

[4] => /www/sites/www.somesite.com/config.local.php

[5] => /www/sites/www.somesite.com/core/db/mysql.php

[6] => /www/sites/www.somesite.com/core/fn.database.php

[7] => /www/sites/www.somesite.com/core/fn.users.php

[8] => /www/sites/www.somesite.com/core/fn.catalog.php

[9] => /www/sites/www.somesite.com/core/fn.cms.php

[10] => /www/sites/www.somesite.com/core/fn.cart.php

[11] => /www/sites/www.somesite.com/core/fn.locations.php

[12] => /www/sites/www.somesite.com/core/fn.common.php

[13] => /www/sites/www.somesite.com/core/fn.fs.php

[14] => /www/sites/www.somesite.com/core/fn.requests.php

[15] => /www/sites/www.somesite.com/core/fn.images.php

[16] => /www/sites/www.somesite.com/core/fn.init.php

[17] => /www/sites/www.somesite.com/core/fn.control.php

[18] => /www/sites/www.somesite.com/core/fn.search.php

[19] => /www/sites/www.somesite.com/core/fn.promotions.php

[20] => /www/sites/www.somesite.com/core/fn.log.php

[21] => /www/sites/www.somesite.com/core/fn.companies.php

[22] => /www/sites/www.somesite.com/core/class.profiler.php

[23] => /www/sites/www.somesite.com/core/class.registry.php

[24] => /www/sites/www.somesite.com/core/class.session.php

[25] => /www/sites/www.somesite.com/core/cache/class.cache_backend_file.php

[26] => /www/sites/www.somesite.com/addons/buy_together/init.php

[27] => /www/sites/www.somesite.com/addons/local/init.php

[28] => /www/sites/www.somesite.com/addons/preauth/init.php

[29] => /www/sites/www.somesite.com/addons/live_help/init.php

[30] => /www/sites/www.somesite.com/addons/shop_by_features/init.php

[31] => /www/sites/www.somesite.com/addons/data_feeds/init.php

[32] => /www/sites/www.somesite.com/addons/customers_also_bought/init.php

[33] => /www/sites/www.somesite.com/addons/gift_certificates/init.php

[34] => /www/sites/www.somesite.com/addons/store_locator/init.php

[35] => /www/sites/www.somesite.com/addons/seo/init.php

[36] => /www/sites/www.somesite.com/addons/google_analytics/init.php

[37] => /www/sites/www.somesite.com/addons/bestsellers/init.php

[38] => /www/sites/www.somesite.com/addons/form_builder/init.php

[39] => /www/sites/www.somesite.com/addons/polls/init.php

[40] => /www/sites/www.somesite.com/addons/banners/init.php

[41] => /www/sites/www.somesite.com/addons/wishlist/init.php

[42] => /www/sites/www.somesite.com/addons/hot_deals_block/init.php

[43] => /www/sites/www.somesite.com/addons/google_sitemap/init.php

[44] => /www/sites/www.somesite.com/addons/required_products/init.php

[45] => /www/sites/www.somesite.com/addons/seo/func.php

[46] => /www/sites/www.somesite.com/core/class.templater.php

[47] => /www/sites/www.somesite.com/lib/templater/Smarty.class.php

[48] => /www/sites/www.somesite.com/lib/templater/Smarty_Compiler.class.php

[49] => /www/sites/www.somesite.com/addons/wishlist/func.php

)


- Queries: 12

Before TPL
- Memory: 1,415,216 (8,677,680)
- Files: 30 (80)
- F: Array

(

[0] => /www/sites/www.somesite.com/index.php

[1] => /www/sites/www.somesite.com/prepare.php

[2] => /www/sites/www.somesite.com/init.php

[3] => /www/sites/www.somesite.com/config.php

[4] => /www/sites/www.somesite.com/config.local.php

[5] => /www/sites/www.somesite.com/core/db/mysql.php

[6] => /www/sites/www.somesite.com/core/fn.database.php

[7] => /www/sites/www.somesite.com/core/fn.users.php

[8] => /www/sites/www.somesite.com/core/fn.catalog.php

[9] => /www/sites/www.somesite.com/core/fn.cms.php

[10] => /www/sites/www.somesite.com/core/fn.cart.php

[11] => /www/sites/www.somesite.com/core/fn.locations.php

[12] => /www/sites/www.somesite.com/core/fn.common.php

[13] => /www/sites/www.somesite.com/core/fn.fs.php

[14] => /www/sites/www.somesite.com/core/fn.requests.php

[15] => /www/sites/www.somesite.com/core/fn.images.php

[16] => /www/sites/www.somesite.com/core/fn.init.php

[17] => /www/sites/www.somesite.com/core/fn.control.php

[18] => /www/sites/www.somesite.com/core/fn.search.php

[19] => /www/sites/www.somesite.com/core/fn.promotions.php

[20] => /www/sites/www.somesite.com/core/fn.log.php

[21] => /www/sites/www.somesite.com/core/fn.companies.php

[22] => /www/sites/www.somesite.com/core/class.profiler.php

[23] => /www/sites/www.somesite.com/core/class.registry.php

[24] => /www/sites/www.somesite.com/core/class.session.php

[25] => /www/sites/www.somesite.com/core/cache/class.cache_backend_file.php

[26] => /www/sites/www.somesite.com/addons/buy_together/init.php

[27] => /www/sites/www.somesite.com/addons/local/init.php

[28] => /www/sites/www.somesite.com/addons/preauth/init.php

[29] => /www/sites/www.somesite.com/addons/live_help/init.php

[30] => /www/sites/www.somesite.com/addons/shop_by_features/init.php

[31] => /www/sites/www.somesite.com/addons/data_feeds/init.php

[32] => /www/sites/www.somesite.com/addons/customers_also_bought/init.php

[33] => /www/sites/www.somesite.com/addons/gift_certificates/init.php

[34] => /www/sites/www.somesite.com/addons/store_locator/init.php

[35] => /www/sites/www.somesite.com/addons/seo/init.php

[36] => /www/sites/www.somesite.com/addons/google_analytics/init.php

[37] => /www/sites/www.somesite.com/addons/bestsellers/init.php

[38] => /www/sites/www.somesite.com/addons/form_builder/init.php

[39] => /www/sites/www.somesite.com/addons/polls/init.php

[40] => /www/sites/www.somesite.com/addons/banners/init.php

[41] => /www/sites/www.somesite.com/addons/wishlist/init.php

[42] => /www/sites/www.somesite.com/addons/hot_deals_block/init.php

[43] => /www/sites/www.somesite.com/addons/google_sitemap/init.php

[44] => /www/sites/www.somesite.com/addons/required_products/init.php

[45] => /www/sites/www.somesite.com/addons/seo/func.php

[46] => /www/sites/www.somesite.com/core/class.templater.php

[47] => /www/sites/www.somesite.com/lib/templater/Smarty.class.php

[48] => /www/sites/www.somesite.com/lib/templater/Smarty_Compiler.class.php

[49] => /www/sites/www.somesite.com/addons/wishlist/func.php

[50] => /www/sites/www.somesite.com/addons/live_help/func.php

[51] => /www/sites/www.somesite.com/addons/live_help/config.php

[52] => /www/sites/www.somesite.com/addons/gift_certificates/func.php

[53] => /www/sites/www.somesite.com/addons/gift_certificates/config.php

[54] => /www/sites/www.somesite.com/addons/form_builder/func.php

[55] => /www/sites/www.somesite.com/addons/buy_together/func.php

[56] => /www/sites/www.somesite.com/addons/local/func.php

[57] => /www/sites/www.somesite.com/addons/local/config.php

[58] => /www/sites/www.somesite.com/addons/preauth/func.php

[59] => /www/sites/www.somesite.com/addons/shop_by_features/func.php

[60] => /www/sites/www.somesite.com/addons/data_feeds/func.php

[61] => /www/sites/www.somesite.com/addons/customers_also_bought/func.php

[62] => /www/sites/www.somesite.com/addons/store_locator/func.php

[63] => /www/sites/www.somesite.com/addons/store_locator/config.php

[64] => /www/sites/www.somesite.com/addons/google_analytics/func.php

[65] => /www/sites/www.somesite.com/addons/bestsellers/func.php

[66] => /www/sites/www.somesite.com/addons/barcode/func.php

[67] => /www/sites/www.somesite.com/addons/barcode/config.php

[68] => /www/sites/www.somesite.com/addons/polls/func.php

[69] => /www/sites/www.somesite.com/addons/polls/config.php

[70] => /www/sites/www.somesite.com/addons/banners/func.php

[71] => /www/sites/www.somesite.com/addons/hot_deals_block/func.php

[72] => /www/sites/www.somesite.com/addons/google_sitemap/func.php

[73] => /www/sites/www.somesite.com/addons/required_products/func.php

[74] => /www/sites/www.somesite.com/addons/required_products/config.php

[75] => /www/sites/www.somesite.com/lib/templater/internals/core.get_include_path.php

[76] => /www/sites/www.somesite.com/controllers/customer/init.php

[77] => /www/sites/www.somesite.com/addons/wg_dd_mmenu/controllers/customer/init.post.php

[78] => /www/sites/www.somesite.com/addons/seo/controllers/customer/init.post.php

[79] => /www/sites/www.somesite.com/controllers/customer/index.php

)


- Queries: 10 (22)
- Time: 0.4968 (0.4968)

After TPL
- Memory: 1,136,736 (9,814,416)
- Files: 17 (97)
- F: Array

(

[0] => /www/sites/www.somesite.com/index.php

[1] => /www/sites/www.somesite.com/prepare.php

[2] => /www/sites/www.somesite.com/init.php

[3] => /www/sites/www.somesite.com/config.php

[4] => /www/sites/www.somesite.com/config.local.php

[5] => /www/sites/www.somesite.com/core/db/mysql.php

[6] => /www/sites/www.somesite.com/core/fn.database.php

[7] => /www/sites/www.somesite.com/core/fn.users.php

[8] => /www/sites/www.somesite.com/core/fn.catalog.php

[9] => /www/sites/www.somesite.com/core/fn.cms.php

[10] => /www/sites/www.somesite.com/core/fn.cart.php

[11] => /www/sites/www.somesite.com/core/fn.locations.php

[12] => /www/sites/www.somesite.com/core/fn.common.php

[13] => /www/sites/www.somesite.com/core/fn.fs.php

[14] => /www/sites/www.somesite.com/core/fn.requests.php

[15] => /www/sites/www.somesite.com/core/fn.images.php

[16] => /www/sites/www.somesite.com/core/fn.init.php

[17] => /www/sites/www.somesite.com/core/fn.control.php

[18] => /www/sites/www.somesite.com/core/fn.search.php

[19] => /www/sites/www.somesite.com/core/fn.promotions.php

[20] => /www/sites/www.somesite.com/core/fn.log.php

[21] => /www/sites/www.somesite.com/core/fn.companies.php

[22] => /www/sites/www.somesite.com/core/class.profiler.php

[23] => /www/sites/www.somesite.com/core/class.registry.php

[24] => /www/sites/www.somesite.com/core/class.session.php

[25] => /www/sites/www.somesite.com/core/cache/class.cache_backend_file.php

[26] => /www/sites/www.somesite.com/addons/buy_together/init.php

[27] => /www/sites/www.somesite.com/addons/local/init.php

[28] => /www/sites/www.somesite.com/addons/preauth/init.php

[29] => /www/sites/www.somesite.com/addons/live_help/init.php

[30] => /www/sites/www.somesite.com/addons/shop_by_features/init.php

[31] => /www/sites/www.somesite.com/addons/data_feeds/init.php

[32] => /www/sites/www.somesite.com/addons/customers_also_bought/init.php

[33] => /www/sites/www.somesite.com/addons/gift_certificates/init.php

[34] => /www/sites/www.somesite.com/addons/store_locator/init.php

[35] => /www/sites/www.somesite.com/addons/seo/init.php

[36] => /www/sites/www.somesite.com/addons/google_analytics/init.php

[37] => /www/sites/www.somesite.com/addons/bestsellers/init.php

[38] => /www/sites/www.somesite.com/addons/form_builder/init.php

[39] => /www/sites/www.somesite.com/addons/polls/init.php

[40] => /www/sites/www.somesite.com/addons/banners/init.php

[41] => /www/sites/www.somesite.com/addons/wishlist/init.php

[42] => /www/sites/www.somesite.com/addons/hot_deals_block/init.php

[43] => /www/sites/www.somesite.com/addons/google_sitemap/init.php

[44] => /www/sites/www.somesite.com/addons/required_products/init.php

[45] => /www/sites/www.somesite.com/addons/seo/func.php

[46] => /www/sites/www.somesite.com/core/class.templater.php

[47] => /www/sites/www.somesite.com/lib/templater/Smarty.class.php

[48] => /www/sites/www.somesite.com/lib/templater/Smarty_Compiler.class.php

[49] => /www/sites/www.somesite.com/addons/wishlist/func.php

[50] => /www/sites/www.somesite.com/addons/live_help/func.php

[51] => /www/sites/www.somesite.com/addons/live_help/config.php

[52] => /www/sites/www.somesite.com/addons/gift_certificates/func.php

[53] => /www/sites/www.somesite.com/addons/gift_certificates/config.php

[54] => /www/sites/www.somesite.com/addons/form_builder/func.php

[55] => /www/sites/www.somesite.com/addons/buy_together/func.php

[56] => /www/sites/www.somesite.com/addons/local/func.php

[57] => /www/sites/www.somesite.com/addons/local/config.php

[58] => /www/sites/www.somesite.com/addons/preauth/func.php

[59] => /www/sites/www.somesite.com/addons/shop_by_features/func.php

[60] => /www/sites/www.somesite.com/addons/data_feeds/func.php

[61] => /www/sites/www.somesite.com/addons/customers_also_bought/func.php

[62] => /www/sites/www.somesite.com/addons/store_locator/func.php

[63] => /www/sites/www.somesite.com/addons/store_locator/config.php

[64] => /www/sites/www.somesite.com/addons/google_analytics/func.php

[65] => /www/sites/www.somesite.com/addons/bestsellers/func.php

[66] => /www/sites/www.somesite.com/addons/barcode/func.php

[67] => /www/sites/www.somesite.com/addons/barcode/config.php

[68] => /www/sites/www.somesite.com/addons/polls/func.php

[69] => /www/sites/www.somesite.com/addons/polls/config.php

[70] => /www/sites/www.somesite.com/addons/banners/func.php

[71] => /www/sites/www.somesite.com/addons/hot_deals_block/func.php

[72] => /www/sites/www.somesite.com/addons/google_sitemap/func.php

[73] => /www/sites/www.somesite.com/addons/required_products/func.php

[74] => /www/sites/www.somesite.com/addons/required_products/config.php

[75] => /www/sites/www.somesite.com/lib/templater/internals/core.get_include_path.php

[76] => /www/sites/www.somesite.com/controllers/customer/init.php

[77] => /www/sites/www.somesite.com/addons/wg_dd_mmenu/controllers/customer/init.post.php

[78] => /www/sites/www.somesite.com/addons/seo/controllers/customer/init.post.php

[79] => /www/sites/www.somesite.com/controllers/customer/index.php

[80] => /www/sites/www.somesite.com/lib/templater/internals/core.get_microtime.php

[81] => /www/sites/www.somesite.com/lib/templater/internals/core.read_cache_file.php

[82] => /www/sites/www.somesite.com/lib/templater/internals/core.write_cache_file.php

[83] => /www/sites/www.somesite.com/lib/templater/internals/core.write_file.php

[84] => /www/sites/www.somesite.com/lib/templater/internals/core.create_dir_structure.php

[85] => /www/sites/www.somesite.com/lib/templater/internals/core.process_cached_inserts.php

[86] => /www/sites/www.somesite.com/lib/templater/internals/core.display_debug_console.php

[87] => /www/sites/www.somesite.com/lib/templater/internals/core.assemble_plugin_filepath.php

[88] => /www/sites/www.somesite.com/lib/templater/plugins/function.assign_debug_info.php

[89] => /www/sites/www.somesite.com/core/templater_plugins/function.cycle.php

[90] => /www/sites/www.somesite.com/core/templater_plugins/compiler.assign.php

[91] => /www/sites/www.somesite.com/lib/templater/internals/core.write_compiled_resource.php

[92] => /www/sites/www.somesite.com/var/compiled/customer/%%84^84D^84D2724B%%debug.tpl.php

[93] => /www/sites/www.somesite.com/lib/templater/internals/core.load_plugins.php

[94] => /www/sites/www.somesite.com/core/templater_plugins/modifier.escape.php

[95] => /www/sites/www.somesite.com/lib/templater/plugins/modifier.string_format.php

[96] => /www/sites/www.somesite.com/lib/templater/plugins/modifier.debug_print_var.php

)


- Queries: 0 (22)
- Time: 5.5495 (6.0463)


AND now the culprit !!!

lib/templater/plugins/modifier.debug_print_var.php



adding return 0 to the function.right after start … i get normal times !!! something is bad its like it's looping trough all vars even on non

debug ?

still debugging other slow issues…

Before TPL

  • Memory: 750,216 (8,064,232)
  • Files: 12 (48)


  • Queries: 247 (268)
  • Time: 7.6595 (7.6595)

So is this with version 2.2.4? Where did you change it to zero in the file you mentioned?

dont worry Most of the SLOWISNESS is DB based… get a FAST DB server and make it close to you… you need 0.0000019 per query… i had 0.02

How did you check your db speed?

hmm easy enough, simple check the debugger ? profiler in the config

I think that when you turn on profiling that you are turning off caching. Much of what you see would normally be in the cache.

Anytime template debugging is turned on in any form (via admin or via a debug tag) it's going to slow things down a lot becuase it needs to create a nice readable format of the internal data.