Next Generation CMS :: Форум поддержки
Вы не зашли.
- Темы: Активные | Без ответов
Объявление
#101 2011-12-27 12:26:07
Re: Помогите разобратся что так грузит сайт
Раз ты не хочешь выяснять что это за плагин у тебя такой странный (жрущий ресурсы, причём - не входящий в базовую поставку системы), то вариант только один - увеличить производительность сервака.
Разве нет?
я какраз и хочу вияснить, список плагинов я показал, football_stats - я отключал конешно, если он и напрягает то это в пределал милисекунд, а мне интересно что напрягает в десятки секунд, если у меня иногда генерация странички бывает 56 секунд например...
На счет сервака я писал что гдето неделю назал я удвоил ресурсы, именно по этому сайт впринцыпе работает, до того он еле откривался, счас у меня
Virtual CPU 2048Mhz x 2, 2048Mb memory, 204800Mb disk, я думаю это более чем достаточно , но всеравно сервак идет на гране 80% нагрузки
Изменено legenda (2011-12-27 12:27:01)
Не в сети
#102 2011-12-27 13:09:52
Re: Помогите разобратся что так грузит сайт
56 секунд 
жесть, все логи, которые ты сюда сбрасываешь показывают, что выполнение сайта хоть и велико, бывает и 3 секунды, но это все не то, championat.com у меня тоже грузится по две-три секунды, а ведь там работает целая команда 
Меня смущает самый первый лог в первом сообщении
43.852 0.000 Config file is loaded
43.887 0.035 Core files are includedТо есть даже до момента работы всех плагинов, на загрузку config файла уже потратилось 43 секунды, потом все плагины за 1 секунду отработали. Все это происходит еще до момента соединения с БД.
Можно начать мониторить такие события.
Открой /engine/includes/classes/timer.class.php
Найди
class microTimer {Замени на
class microTimer {
private $flag = false;Найди
// REGISTER measurment
function registerEvent($eventName, $eventParams = ''){
$current_time = $this->stop(4);
$delta = $current_time - $this->last_event;
if ($delta < 0) $delta = 0;
array_push($this->events, array( $current_time, sprintf('%7.3f', $delta), $eventName, $eventParams ));
$this->last_event = $current_time;
}замени на
// REGISTER measurment
function registerEvent($eventName, $eventParams = ''){
$current_time = $this->stop(4);
if($current_time > 10) $this->flag = true;
$delta = $current_time - $this->last_event;
if ($delta < 0) $delta = 0;
array_push($this->events, array( $current_time, sprintf('%7.3f', $delta), $eventName, $eventParams ));
$this->last_event = $current_time;
}Найди
// Print events
function printEvents($html = 0){
$out = ($html)?"<table class='timeProfiler'>\n<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>\n":'';
foreach ($this->events as $v) {
$out .= ($html)?('<tr><td>'.sprintf('%7.3f', $v[0]).'</td><td>'.$v[1].'</td><td>'.$v[2].'</td><td>'.$v[3]."</td></tr>\n"):$v[0]."\t".$v[1]."\t".$v[2]."\t".$v[3]."\n";
}
$out .= (($html)?"</table>":'')."\n";
return $out;
}Замени на
// Print events
function printEvents($html = 0){
$out = ($html)?"<table class='timeProfiler'>\n<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>\n":'';
foreach ($this->events as $v) {
$out .= ($html)?('<tr><td>'.sprintf('%7.3f', $v[0]).'</td><td>'.$v[1].'</td><td>'.$v[2].'</td><td>'.$v[3]."</td></tr>\n"):$v[0]."\t".$v[1]."\t".$v[2]."\t".$v[3]."\n";
}
$out .= (($html)?"</table>":'')."\n";
if($this->flag) file_put_contents('log.txt', $out, FILE_APPEND);
return $out;
}Позже надо будет посмотреть содержимое файла log.txt в корне и увидим как часто это происходит. Потом надо будет смотреть на какие операции тратилось 40 секунд.
Не в сети
#103 2011-12-27 13:19:32
Re: Помогите разобратся что так грузит сайт
Wolverine, я не о загрузке а именно о генерации странички, я ж писал 11 секунд при 300 онлайн посетителях, бывает и больше если людей 500-1000 онлайн то и 40 и 50, но это счас практически нету так как специфика сайта футбольная, уже все турниры закончились, но когда идут топ матчи то на сайте до 2-3 тис. онлайн бывает...
счас сделаю то что ти написал...
Не в сети
#104 2011-12-27 13:26:49
#105 2011-12-28 03:27:20
Re: Помогите разобратся что так грузит сайт
вот лог за сегодня
<table class='timeProfiler'>
<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>
<tr><td> 0.002</td><td> 0.000</td><td>Config file is loaded</td><td></td></tr>
<tr><td> 0.016</td><td> 0.014</td><td>Core files are included</td><td></td></tr>
<tr><td> 0.025</td><td> 0.010</td><td>Template engine is activated</td><td></td></tr>
<tr><td> 0.029</td><td> 0.004</td><td>DB connection established</td><td></td></tr>
<tr><td> 0.031</td><td> 0.002</td><td>DB category list is loaded</td><td></td></tr>
<tr><td> 0.031</td><td> 0.000</td><td>Ready to load auth plugins</td><td></td></tr>
<tr><td> 0.034</td><td> 0.003</td><td>loadActionHandlers(auth): preloaded file "auth_basic/auth_basic.php" for 0.0021 sec</td><td></td></tr>
<tr><td> 0.034</td><td> 0.000</td><td>Auth plugins are loaded</td><td></td></tr>
<tr><td> 0.034</td><td> 0.000</td><td>Auth procedure is finished</td><td></td></tr>
<tr><td> 0.034</td><td> 0.000</td><td>ALL core-related plugins are loaded</td><td></td></tr>
<tr><td> 0.034</td><td> 0.000</td><td>exec EXEC_ACTS (core)</td><td></td></tr>
<tr><td> 0.040</td><td> 0.005</td><td>loadPluginLibrary: uprofile [lib] for 0.0003 sec</td><td></td></tr>
<tr><td> 0.040</td><td> 0.000</td><td>loadActionHandlers(core): preloaded file "xfields/xfields.php" for 0.005 sec</td><td></td></tr>
<tr><td> 0.040</td><td> 0.000</td><td>ALL core-related plugins are executed</td><td></td></tr>
<tr><td> 0.041</td><td> 0.001</td><td>* CORE.PHP is complete</td><td></td></tr>
<tr><td> 0.041</td><td> 0.000</td><td>exec EXEC_ACTS (index_pre)</td><td></td></tr>
<tr><td> 0.041</td><td> 0.001</td><td>loadActionHandlers(index_pre): preloaded file "multi_main/multi_main.php" for 0.0004 sec</td><td></td></tr>
<tr><td> 0.041</td><td> 0.000</td><td>Search route for URL "/gallery-fotofootball/edgar-davids-ta-iogo-podruzhka-v-sonyachnomu-mayami-foto972.html"</td><td></td></tr>
<tr><td> 0.045</td><td> 0.004</td><td>loadActionHandlers(news): preloaded file "ads/ads.php" for 0.0005 sec</td><td></td></tr>
<tr><td> 0.047</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "tags/tags.php" for 0.0019 sec</td><td></td></tr>
<tr><td> 0.051</td><td> 0.004</td><td>loadActionHandlers(news): preloaded file "comments/comments.php" for 0.0033 sec</td><td></td></tr>
<tr><td> 0.051</td><td> 0.001</td><td>loadActionHandlers(news): preloaded file "cat_description/cat_description.php" for 0.0004 sec</td><td></td></tr>
<tr><td> 0.053</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "similar/similar.php" for 0.0015 sec</td><td></td></tr>
<tr><td> 0.056</td><td> 0.003</td><td>call showNewsPre() for [ 0 ] sec</td><td></td></tr>
<tr><td> 0.056</td><td> 0.000</td><td>call newsFillVariables() for [ 0.0027 ] sec</td><td></td></tr>
<tr><td> 0.057</td><td> 0.000</td><td>exec EXEC_ACTS (news_full)</td><td></td></tr>
<tr><td> 0.057</td><td> 0.000</td><td>func EXEC_ACTS (news_full): call function "plugin_multi_main_full" [3 params] for 0.0001 sec</td><td></td></tr>
<tr><td> 0.057</td><td> 0.000</td><td>exec showNews // xfields</td><td></td></tr>
<tr><td> 0.058</td><td> 0.001</td><td>exec showNews // ads</td><td></td></tr>
<tr><td> 0.058</td><td> 0.000</td><td>exec showNews // tags</td><td></td></tr>
<tr><td> 0.058</td><td> 0.001</td><td>exec showNews // comments</td><td></td></tr>
<tr><td> 0.078</td><td> 0.019</td><td>exec EXEC_ACTS (comments_form)</td><td></td></tr>
<tr><td> 0.079</td><td> 0.002</td><td>exec showNews // cat_description</td><td></td></tr>
<tr><td> 0.079</td><td> 0.000</td><td>exec showNews // similar</td><td></td></tr>
<tr><td> 10.613</td><td> 10.533</td><td>call showNews() for [ 10.5561 ] sec</td><td></td></tr>
<tr><td> 10.616</td><td> 0.003</td><td>exec EXEC_ACTS (index)</td><td></td></tr>
<tr><td> 10.617</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "elka/elka.php" for 0.00050000000000061 sec</td><td></td></tr>
<tr><td> 10.617</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "favorites/favorites.php" for 0.00030000000000108 sec</td><td></td></tr>
<tr><td> 10.618</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "top_news/top_news.php" for 0.00079999999999991 sec</td><td></td></tr>
<tr><td> 10.623</td><td> 0.005</td><td>loadActionHandlers(index): preloaded file "football_stats/football_stats.php" for 0.0047999999999995 sec</td><td></td></tr>
<tr><td> 10.623</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_ads" [1 params] for 0.00019999999999953 sec</td><td></td></tr>
<tr><td> 10.625</td><td> 0.002</td><td>func EXEC_ACTS (index): call function "plugin_tags_cloudblock" [1 params] for 0.0015000000000001 sec</td><td></td></tr>
<tr><td> 10.625</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "elka" [1 params] for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 10.626</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_favorites" [1 params] for 0.00030000000000108 sec</td><td></td></tr>
<tr><td> 10.632</td><td> 0.006</td><td>func EXEC_ACTS (index): call function "top_news" [1 params] for 0.0062999999999995 sec</td><td></td></tr>
<tr><td> 10.659</td><td> 0.027</td><td>func EXEC_ACTS (index): call function "showTablesMain" [1 params] for 0.0268 sec</td><td></td></tr>
<tr><td> 10.659</td><td> 0.000</td><td>Master activity finished</td><td></td></tr>
<tr><td> 10.663</td><td> 0.004</td><td>Category menu created</td><td></td></tr>
<tr><td> 10.664</td><td> 0.001</td><td>exec EXEC_ACTS (usermenu)</td><td></td></tr>
<tr><td> 10.665</td><td> 0.001</td><td>exec EXEC_ACTS (index_post)</td><td></td></tr>
<tr><td> 10.666</td><td> 0.001</td><td>loadActionHandlers(index_post): preloaded file "simple_title_ng/simple_title.php" for 0.00050000000000061 sec</td><td></td></tr>
<tr><td> 10.668</td><td> 0.002</td><td>loadActionHandlers(index_post): preloaded file "ads_sape/ads_sape.php" for 0.0020000000000007 sec</td><td></td></tr>
<tr><td> 10.668</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "plugin_multi_main" [1 params] for 0 sec</td><td></td></tr>
<tr><td> 10.668</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "simple_tags" [1 params] for 0 sec</td><td></td></tr>
<tr><td> 10.671</td><td> 0.002</td><td>func EXEC_ACTS (index_post): call function "plugin_ads_sape" [1 params] for 0.0022000000000002 sec</td><td></td></tr>
<tr><td> 10.671</td><td> 0.000</td><td>Templates generation time: 0.0263512134552 (65 times called)</td><td></td></tr>
<tr><td> 10.671</td><td> 0.000</td><td>Generate DEBUG output</td><td></td></tr>
</table>
<table class='timeProfiler'>
<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>
<tr><td> 0.001</td><td> 0.000</td><td>Config file is loaded</td><td></td></tr>
<tr><td> 0.016</td><td> 0.015</td><td>Core files are included</td><td></td></tr>
<tr><td> 0.025</td><td> 0.008</td><td>Template engine is activated</td><td></td></tr>
<tr><td> 5.833</td><td> 5.808</td><td>DB connection established</td><td></td></tr>
<tr><td> 6.053</td><td> 0.220</td><td>DB category list is loaded</td><td></td></tr>
<tr><td> 6.053</td><td> 0.000</td><td>Ready to load auth plugins</td><td></td></tr>
<tr><td> 6.056</td><td> 0.003</td><td>loadActionHandlers(auth): preloaded file "auth_basic/auth_basic.php" for 0.0019999999999998 sec</td><td></td></tr>
<tr><td> 6.056</td><td> 0.000</td><td>Auth plugins are loaded</td><td></td></tr>
<tr><td> 6.252</td><td> 0.196</td><td>Auth procedure is finished</td><td></td></tr>
<tr><td> 6.252</td><td> 0.000</td><td>ALL core-related plugins are loaded</td><td></td></tr>
<tr><td> 6.252</td><td> 0.000</td><td>exec EXEC_ACTS (core)</td><td></td></tr>
<tr><td> 6.257</td><td> 0.005</td><td>loadPluginLibrary: uprofile [lib] for 0.00019999999999953 sec</td><td></td></tr>
<tr><td> 6.257</td><td> 0.000</td><td>loadActionHandlers(core): preloaded file "xfields/xfields.php" for 0.0046999999999997 sec</td><td></td></tr>
<tr><td> 6.257</td><td> 0.000</td><td>ALL core-related plugins are executed</td><td></td></tr>
<tr><td> 6.258</td><td> 0.001</td><td>* CORE.PHP is complete</td><td></td></tr>
<tr><td> 6.258</td><td> 0.000</td><td>exec EXEC_ACTS (index_pre)</td><td></td></tr>
<tr><td> 6.258</td><td> 0.000</td><td>loadActionHandlers(index_pre): preloaded file "multi_main/multi_main.php" for 0.00039999999999996 sec</td><td></td></tr>
<tr><td> 6.258</td><td> 0.000</td><td>Search route for URL "/spain.html"</td><td></td></tr>
<tr><td> 6.262</td><td> 0.004</td><td>loadActionHandlers(news): preloaded file "ads/ads.php" for 0.00049999999999972 sec</td><td></td></tr>
<tr><td> 6.264</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "tags/tags.php" for 0.0017999999999994 sec</td><td></td></tr>
<tr><td> 6.267</td><td> 0.003</td><td>loadActionHandlers(news): preloaded file "comments/comments.php" for 0.0030000000000001 sec</td><td></td></tr>
<tr><td> 6.268</td><td> 0.000</td><td>loadActionHandlers(news): preloaded file "cat_description/cat_description.php" for 0.00030000000000019 sec</td><td></td></tr>
<tr><td> 6.269</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "similar/similar.php" for 0.0014000000000003 sec</td><td></td></tr>
<tr><td> 14.390</td><td> 8.121</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.394</td><td> 0.004</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.397</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.400</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.403</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.406</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.409</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.412</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.415</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.419</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.422</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.425</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.428</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.431</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.435</td><td> 0.004</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.593</td><td> 0.158</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.596</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.599</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.602</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.714</td><td> 0.112</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.717</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.720</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.723</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.726</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.729</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.734</td><td> 0.005</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.737</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.740</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.743</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.939</td><td> 0.195</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.942</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.945</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 14.950</td><td> 0.005</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 15.247</td><td> 0.297</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 15.495</td><td> 0.248</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 15.549</td><td> 0.054</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 15.678</td><td> 0.129</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 15.812</td><td> 0.134</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 15.925</td><td> 0.114</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.175</td><td> 0.249</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.178</td><td> 0.004</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.183</td><td> 0.004</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.186</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 18.591</td><td> 2.405</td><td>exec EXEC_ACTS (index)</td><td></td></tr>
<tr><td> 18.592</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "elka/elka.php" for 0.00049999999999883 sec</td><td></td></tr>
<tr><td> 18.592</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "favorites/favorites.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 18.593</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "top_news/top_news.php" for 0.00069999999999837 sec</td><td></td></tr>
<tr><td> 18.598</td><td> 0.005</td><td>loadActionHandlers(index): preloaded file "football_stats/football_stats.php" for 0.0045999999999999 sec</td><td></td></tr>
<tr><td> 18.598</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_ads" [1 params] for 0.00019999999999953 sec</td><td></td></tr>
<tr><td> 18.600</td><td> 0.002</td><td>func EXEC_ACTS (index): call function "plugin_tags_cloudblock" [1 params] for 0.0015999999999998 sec</td><td></td></tr>
<tr><td> 18.600</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "elka" [1 params] for 0.00040000000000262 sec</td><td></td></tr>
<tr><td> 18.601</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_favorites" [1 params] for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 18.607</td><td> 0.006</td><td>func EXEC_ACTS (index): call function "top_news" [1 params] for 0.0061 sec</td><td></td></tr>
<tr><td> 18.633</td><td> 0.026</td><td>func EXEC_ACTS (index): call function "showTablesMain" [1 params] for 0.026199999999999 sec</td><td></td></tr>
<tr><td> 18.633</td><td> 0.000</td><td>Master activity finished</td><td></td></tr>
<tr><td> 18.637</td><td> 0.004</td><td>Category menu created</td><td></td></tr>
<tr><td> 18.638</td><td> 0.001</td><td>exec EXEC_ACTS (usermenu)</td><td></td></tr>
<tr><td> 18.639</td><td> 0.001</td><td>exec EXEC_ACTS (index_post)</td><td></td></tr>
<tr><td> 18.640</td><td> 0.001</td><td>loadActionHandlers(index_post): preloaded file "simple_title_ng/simple_title.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 18.642</td><td> 0.002</td><td>loadActionHandlers(index_post): preloaded file "ads_sape/ads_sape.php" for 0.0019999999999989 sec</td><td></td></tr>
<tr><td> 18.642</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "plugin_multi_main" [1 params] for 9.9999999999767E-5 sec</td><td></td></tr>
<tr><td> 18.642</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "simple_tags" [1 params] for 9.9999999999767E-5 sec</td><td></td></tr>
<tr><td> 18.645</td><td> 0.002</td><td>func EXEC_ACTS (index_post): call function "plugin_ads_sape" [1 params] for 0.0023000000000017 sec</td><td></td></tr>
<tr><td> 18.645</td><td> 0.000</td><td>Templates generation time: 0.092736482620239 (81 times called)</td><td></td></tr>
<tr><td> 18.645</td><td> 0.000</td><td>Generate DEBUG output</td><td></td></tr>
</table>
<table class='timeProfiler'>
<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>
<tr><td> 1.970</td><td> 0.000</td><td>Config file is loaded</td><td></td></tr>
<tr><td> 1.984</td><td> 0.014</td><td>Core files are included</td><td></td></tr>
<tr><td> 1.992</td><td> 0.008</td><td>Template engine is activated</td><td></td></tr>
<tr><td> 4.211</td><td> 2.218</td><td>DB connection established</td><td></td></tr>
<tr><td> 5.879</td><td> 1.668</td><td>DB category list is loaded</td><td></td></tr>
<tr><td> 5.879</td><td> 0.000</td><td>Ready to load auth plugins</td><td></td></tr>
<tr><td> 5.936</td><td> 0.057</td><td>loadActionHandlers(auth): preloaded file "auth_basic/auth_basic.php" for 0.0521 sec</td><td></td></tr>
<tr><td> 5.936</td><td> 0.000</td><td>Auth plugins are loaded</td><td></td></tr>
<tr><td> 6.688</td><td> 0.752</td><td>Auth procedure is finished</td><td></td></tr>
<tr><td> 6.688</td><td> 0.000</td><td>ALL core-related plugins are loaded</td><td></td></tr>
<tr><td> 6.688</td><td> 0.000</td><td>exec EXEC_ACTS (core)</td><td></td></tr>
<tr><td> 6.878</td><td> 0.190</td><td>loadPluginLibrary: uprofile [lib] for 0.00020000000000042 sec</td><td></td></tr>
<tr><td> 6.878</td><td> 0.000</td><td>loadActionHandlers(core): preloaded file "xfields/xfields.php" for 0.1899 sec</td><td></td></tr>
<tr><td> 6.878</td><td> 0.000</td><td>ALL core-related plugins are executed</td><td></td></tr>
<tr><td> 6.879</td><td> 0.001</td><td>* CORE.PHP is complete</td><td></td></tr>
<tr><td> 6.879</td><td> 0.000</td><td>exec EXEC_ACTS (index_pre)</td><td></td></tr>
<tr><td> 6.879</td><td> 0.000</td><td>loadActionHandlers(index_pre): preloaded file "multi_main/multi_main.php" for 0.00039999999999996 sec</td><td></td></tr>
<tr><td> 6.879</td><td> 0.000</td><td>Search route for URL "/online.html"</td><td></td></tr>
<tr><td> 6.883</td><td> 0.004</td><td>loadActionHandlers(news): preloaded file "ads/ads.php" for 0.00050000000000061 sec</td><td></td></tr>
<tr><td> 6.885</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "tags/tags.php" for 0.0017000000000005 sec</td><td></td></tr>
<tr><td> 6.888</td><td> 0.003</td><td>loadActionHandlers(news): preloaded file "comments/comments.php" for 0.0030000000000001 sec</td><td></td></tr>
<tr><td> 6.888</td><td> 0.000</td><td>loadActionHandlers(news): preloaded file "cat_description/cat_description.php" for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 6.890</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "similar/similar.php" for 0.0012999999999996 sec</td><td></td></tr>
<tr><td> 15.737</td><td> 8.847</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 19.276</td><td> 3.539</td><td>exec EXEC_ACTS (index)</td><td></td></tr>
<tr><td> 19.277</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "elka/elka.php" for 0.00060000000000215 sec</td><td></td></tr>
<tr><td> 19.277</td><td> 0.000</td><td>loadActionHandlers(index): preloaded file "favorites/favorites.php" for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 19.278</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "top_news/top_news.php" for 0.00080000000000169 sec</td><td></td></tr>
<tr><td> 19.283</td><td> 0.005</td><td>loadActionHandlers(index): preloaded file "football_stats/football_stats.php" for 0.0048999999999992 sec</td><td></td></tr>
<tr><td> 19.284</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_ads" [1 params] for 0.00019999999999953 sec</td><td></td></tr>
<tr><td> 19.285</td><td> 0.002</td><td>func EXEC_ACTS (index): call function "plugin_tags_cloudblock" [1 params] for 0.0015000000000001 sec</td><td></td></tr>
<tr><td> 19.286</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "elka" [1 params] for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 19.286</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_favorites" [1 params] for 0.00030000000000285 sec</td><td></td></tr>
<tr><td> 21.343</td><td> 2.057</td><td>func EXEC_ACTS (index): call function "top_news" [1 params] for 2.0568 sec</td><td></td></tr>
<tr><td> 21.369</td><td> 0.026</td><td>func EXEC_ACTS (index): call function "showTablesMain" [1 params] for 0.026299999999999 sec</td><td></td></tr>
<tr><td> 21.369</td><td> 0.000</td><td>Master activity finished</td><td></td></tr>
<tr><td> 21.373</td><td> 0.004</td><td>Category menu created</td><td></td></tr>
<tr><td> 21.374</td><td> 0.001</td><td>exec EXEC_ACTS (usermenu)</td><td></td></tr>
<tr><td> 21.375</td><td> 0.001</td><td>exec EXEC_ACTS (index_post)</td><td></td></tr>
<tr><td> 21.376</td><td> 0.001</td><td>loadActionHandlers(index_post): preloaded file "simple_title_ng/simple_title.php" for 0.00040000000000262 sec</td><td></td></tr>
<tr><td> 21.378</td><td> 0.002</td><td>loadActionHandlers(index_post): preloaded file "ads_sape/ads_sape.php" for 0.0019999999999989 sec</td><td></td></tr>
<tr><td> 21.378</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "plugin_multi_main" [1 params] for 9.9999999999767E-5 sec</td><td></td></tr>
<tr><td> 21.378</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "simple_tags" [1 params] for 9.9999999999767E-5 sec</td><td></td></tr>
<tr><td> 21.381</td><td> 0.002</td><td>func EXEC_ACTS (index_post): call function "plugin_ads_sape" [1 params] for 0.002200000000002 sec</td><td></td></tr>
<tr><td> 21.381</td><td> 0.000</td><td>Templates generation time: 0.0068879127502441 (41 times called)</td><td></td></tr>
<tr><td> 21.381</td><td> 0.000</td><td>Generate DEBUG output</td><td></td></tr>
</table>
<table class='timeProfiler'>
<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>
<tr><td> 19.032</td><td> 0.000</td><td>Config file is loaded</td><td></td></tr>
<tr><td> 19.069</td><td> 0.037</td><td>Core files are included</td><td></td></tr>
<tr><td> 19.078</td><td> 0.009</td><td>Template engine is activated</td><td></td></tr>
<tr><td> 19.095</td><td> 0.017</td><td>DB connection established</td><td></td></tr>
<tr><td> 19.097</td><td> 0.002</td><td>DB category list is loaded</td><td></td></tr>
<tr><td> 19.097</td><td> 0.000</td><td>Ready to load auth plugins</td><td></td></tr>
<tr><td> 19.100</td><td> 0.003</td><td>loadActionHandlers(auth): preloaded file "auth_basic/auth_basic.php" for 0.0021000000000022 sec</td><td></td></tr>
<tr><td> 19.100</td><td> 0.000</td><td>Auth plugins are loaded</td><td></td></tr>
<tr><td> 19.137</td><td> 0.036</td><td>Auth procedure is finished</td><td></td></tr>
<tr><td> 19.137</td><td> 0.000</td><td>ALL core-related plugins are loaded</td><td></td></tr>
<tr><td> 19.137</td><td> 0.000</td><td>exec EXEC_ACTS (core)</td><td></td></tr>
<tr><td> 19.141</td><td> 0.005</td><td>loadPluginLibrary: uprofile [lib] for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 19.142</td><td> 0.000</td><td>loadActionHandlers(core): preloaded file "xfields/xfields.php" for 0.0046999999999997 sec</td><td></td></tr>
<tr><td> 19.142</td><td> 0.000</td><td>ALL core-related plugins are executed</td><td></td></tr>
<tr><td> 19.142</td><td> 0.001</td><td>* CORE.PHP is complete</td><td></td></tr>
<tr><td> 19.142</td><td> 0.000</td><td>exec EXEC_ACTS (index_pre)</td><td></td></tr>
<tr><td> 19.143</td><td> 0.001</td><td>loadActionHandlers(index_pre): preloaded file "multi_main/multi_main.php" for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 19.143</td><td> 0.000</td><td>Search route for URL "/engine/plugins/football_stats/channel_logo/{channel}"</td><td></td></tr>
<tr><td> 19.144</td><td> 0.001</td><td>exec EXEC_ACTS (index)</td><td></td></tr>
<tr><td> 19.145</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "ads/ads.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 19.146</td><td> 0.002</td><td>loadActionHandlers(index): preloaded file "tags/tags.php" for 0.0017999999999994 sec</td><td></td></tr>
<tr><td> 19.147</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "elka/elka.php" for 0.00050000000000239 sec</td><td></td></tr>
<tr><td> 19.148</td><td> 0.000</td><td>loadActionHandlers(index): preloaded file "favorites/favorites.php" for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 19.149</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "top_news/top_news.php" for 0.00080000000000169 sec</td><td></td></tr>
<tr><td> 19.150</td><td> 0.002</td><td>loadActionHandlers(index): preloaded file "similar/similar.php" for 0.0014000000000003 sec</td><td></td></tr>
<tr><td> 19.157</td><td> 0.007</td><td>loadActionHandlers(index): preloaded file "football_stats/football_stats.php" for 0.0064999999999991 sec</td><td></td></tr>
<tr><td> 19.157</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_ads" [1 params] for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 19.161</td><td> 0.004</td><td>func EXEC_ACTS (index): call function "plugin_tags_cloudblock" [1 params] for 0.003700000000002 sec</td><td></td></tr>
<tr><td> 19.162</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "elka" [1 params] for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 19.162</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_favorites" [1 params] for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 19.169</td><td> 0.007</td><td>func EXEC_ACTS (index): call function "top_news" [1 params] for 0.0073000000000008 sec</td><td></td></tr>
<tr><td> 19.209</td><td> 0.040</td><td>func EXEC_ACTS (index): call function "showTablesMain" [1 params] for 0.040199999999999 sec</td><td></td></tr>
<tr><td> 19.209</td><td> 0.000</td><td>Master activity finished</td><td></td></tr>
<tr><td> 19.214</td><td> 0.004</td><td>Category menu created</td><td></td></tr>
<tr><td> 19.215</td><td> 0.001</td><td>exec EXEC_ACTS (usermenu)</td><td></td></tr>
<tr><td> 19.216</td><td> 0.001</td><td>exec EXEC_ACTS (index_post)</td><td></td></tr>
<tr><td> 19.217</td><td> 0.001</td><td>loadActionHandlers(index_post): preloaded file "simple_title_ng/simple_title.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 19.219</td><td> 0.003</td><td>loadActionHandlers(index_post): preloaded file "ads_sape/ads_sape.php" for 0.002200000000002 sec</td><td></td></tr>
<tr><td> 19.219</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "plugin_multi_main" [1 params] for 0 sec</td><td></td></tr>
<tr><td> 19.219</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "simple_tags" [1 params] for 0 sec</td><td></td></tr>
<tr><td> 19.221</td><td> 0.002</td><td>func EXEC_ACTS (index_post): call function "plugin_ads_sape" [1 params] for 0.0021999999999984 sec</td><td></td></tr>
<tr><td> 19.222</td><td> 0.000</td><td>Templates generation time: 0.0040285587310791 (39 times called)</td><td></td></tr>
<tr><td> 19.222</td><td> 0.000</td><td>Generate DEBUG output</td><td></td></tr>
</table>
<table class='timeProfiler'>
<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>
<tr><td> 11.047</td><td> 0.000</td><td>Config file is loaded</td><td></td></tr>
<tr><td> 11.077</td><td> 0.030</td><td>Core files are included</td><td></td></tr>
<tr><td> 11.105</td><td> 0.028</td><td>Template engine is activated</td><td></td></tr>
<tr><td> 11.198</td><td> 0.093</td><td>DB connection established</td><td></td></tr>
<tr><td> 11.213</td><td> 0.015</td><td>DB category list is loaded</td><td></td></tr>
<tr><td> 11.213</td><td> 0.000</td><td>Ready to load auth plugins</td><td></td></tr>
<tr><td> 11.221</td><td> 0.008</td><td>loadActionHandlers(auth): preloaded file "auth_basic/auth_basic.php" for 0.0074000000000005 sec</td><td></td></tr>
<tr><td> 11.221</td><td> 0.000</td><td>Auth plugins are loaded</td><td></td></tr>
<tr><td> 11.224</td><td> 0.003</td><td>Auth procedure is finished</td><td></td></tr>
<tr><td> 11.224</td><td> 0.000</td><td>ALL core-related plugins are loaded</td><td></td></tr>
<tr><td> 11.224</td><td> 0.000</td><td>exec EXEC_ACTS (core)</td><td></td></tr>
<tr><td> 11.232</td><td> 0.008</td><td>loadPluginLibrary: uprofile [lib] for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 11.233</td><td> 0.000</td><td>loadActionHandlers(core): preloaded file "xfields/xfields.php" for 0.0052000000000003 sec</td><td></td></tr>
<tr><td> 11.233</td><td> 0.000</td><td>ALL core-related plugins are executed</td><td></td></tr>
<tr><td> 11.233</td><td> 0.001</td><td>* CORE.PHP is complete</td><td></td></tr>
<tr><td> 11.233</td><td> 0.000</td><td>exec EXEC_ACTS (index_pre)</td><td></td></tr>
<tr><td> 11.234</td><td> 0.001</td><td>loadActionHandlers(index_pre): preloaded file "multi_main/multi_main.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 11.234</td><td> 0.000</td><td>Search route for URL "/online.html"</td><td></td></tr>
<tr><td> 11.238</td><td> 0.004</td><td>loadActionHandlers(news): preloaded file "ads/ads.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 11.240</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "tags/tags.php" for 0.0018000000000011 sec</td><td></td></tr>
<tr><td> 11.246</td><td> 0.006</td><td>loadActionHandlers(news): preloaded file "comments/comments.php" for 0.0055999999999994 sec</td><td></td></tr>
<tr><td> 11.246</td><td> 0.001</td><td>loadActionHandlers(news): preloaded file "cat_description/cat_description.php" for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 11.248</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "similar/similar.php" for 0.0020000000000007 sec</td><td></td></tr>
<tr><td> 13.218</td><td> 1.969</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.355</td><td> 3.138</td><td>exec EXEC_ACTS (index)</td><td></td></tr>
<tr><td> 16.356</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "elka/elka.php" for 0.0005999999999986 sec</td><td></td></tr>
<tr><td> 16.356</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "favorites/favorites.php" for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 16.357</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "top_news/top_news.php" for 0.00079999999999814 sec</td><td></td></tr>
<tr><td> 16.363</td><td> 0.006</td><td>loadActionHandlers(index): preloaded file "football_stats/football_stats.php" for 0.0052999999999983 sec</td><td></td></tr>
<tr><td> 16.363</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_ads" [1 params] for 0.00019999999999953 sec</td><td></td></tr>
<tr><td> 16.365</td><td> 0.002</td><td>func EXEC_ACTS (index): call function "plugin_tags_cloudblock" [1 params] for 0.0015000000000001 sec</td><td></td></tr>
<tr><td> 16.365</td><td> 0.001</td><td>func EXEC_ACTS (index): call function "elka" [1 params] for 0.00040000000000262 sec</td><td></td></tr>
<tr><td> 16.366</td><td> 0.001</td><td>func EXEC_ACTS (index): call function "plugin_favorites" [1 params] for 0.0005999999999986 sec</td><td></td></tr>
<tr><td> 17.721</td><td> 1.355</td><td>func EXEC_ACTS (index): call function "top_news" [1 params] for 1.3549 sec</td><td></td></tr>
<tr><td> 17.747</td><td> 0.026</td><td>func EXEC_ACTS (index): call function "showTablesMain" [1 params] for 0.026399999999999 sec</td><td></td></tr>
<tr><td> 17.747</td><td> 0.000</td><td>Master activity finished</td><td></td></tr>
<tr><td> 17.751</td><td> 0.004</td><td>Category menu created</td><td></td></tr>
<tr><td> 17.753</td><td> 0.001</td><td>exec EXEC_ACTS (usermenu)</td><td></td></tr>
<tr><td> 17.754</td><td> 0.001</td><td>exec EXEC_ACTS (index_post)</td><td></td></tr>
<tr><td> 17.754</td><td> 0.001</td><td>loadActionHandlers(index_post): preloaded file "simple_title_ng/simple_title.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 17.757</td><td> 0.002</td><td>loadActionHandlers(index_post): preloaded file "ads_sape/ads_sape.php" for 0.0019999999999989 sec</td><td></td></tr>
<tr><td> 17.757</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "plugin_multi_main" [1 params] for 0 sec</td><td></td></tr>
<tr><td> 17.757</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "simple_tags" [1 params] for 9.9999999999767E-5 sec</td><td></td></tr>
<tr><td> 17.759</td><td> 0.002</td><td>func EXEC_ACTS (index_post): call function "plugin_ads_sape" [1 params] for 0.002200000000002 sec</td><td></td></tr>
<tr><td> 17.759</td><td> 0.000</td><td>Templates generation time: 0.0068566799163818 (41 times called)</td><td></td></tr>
<tr><td> 17.759</td><td> 0.000</td><td>Generate DEBUG output</td><td></td></tr>
</table>
<table class='timeProfiler'>
<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>
<tr><td> 17.988</td><td> 0.000</td><td>Config file is loaded</td><td></td></tr>
<tr><td> 18.005</td><td> 0.017</td><td>Core files are included</td><td></td></tr>
<tr><td> 18.014</td><td> 0.009</td><td>Template engine is activated</td><td></td></tr>
<tr><td> 18.111</td><td> 0.097</td><td>DB connection established</td><td></td></tr>
<tr><td> 18.114</td><td> 0.003</td><td>DB category list is loaded</td><td></td></tr>
<tr><td> 18.114</td><td> 0.000</td><td>Ready to load auth plugins</td><td></td></tr>
<tr><td> 18.118</td><td> 0.005</td><td>loadActionHandlers(auth): preloaded file "auth_basic/auth_basic.php" for 0.0038000000000018 sec</td><td></td></tr>
<tr><td> 18.118</td><td> 0.000</td><td>Auth plugins are loaded</td><td></td></tr>
<tr><td> 18.119</td><td> 0.001</td><td>Auth procedure is finished</td><td></td></tr>
<tr><td> 18.119</td><td> 0.000</td><td>ALL core-related plugins are loaded</td><td></td></tr>
<tr><td> 18.119</td><td> 0.000</td><td>exec EXEC_ACTS (core)</td><td></td></tr>
<tr><td> 18.129</td><td> 0.010</td><td>loadPluginLibrary: uprofile [lib] for 0.00030000000000285 sec</td><td></td></tr>
<tr><td> 18.129</td><td> 0.000</td><td>loadActionHandlers(core): preloaded file "xfields/xfields.php" for 0.009800000000002 sec</td><td></td></tr>
<tr><td> 18.129</td><td> 0.000</td><td>ALL core-related plugins are executed</td><td></td></tr>
<tr><td> 18.130</td><td> 0.001</td><td>* CORE.PHP is complete</td><td></td></tr>
<tr><td> 18.130</td><td> 0.000</td><td>exec EXEC_ACTS (index_pre)</td><td></td></tr>
<tr><td> 18.131</td><td> 0.001</td><td>loadActionHandlers(index_pre): preloaded file "multi_main/multi_main.php" for 0.00030000000000285 sec</td><td></td></tr>
<tr><td> 18.131</td><td> 0.000</td><td>Search route for URL "/engine/plugins/football_stats/channel_logo/{channel}"</td><td></td></tr>
<tr><td> 18.131</td><td> 0.001</td><td>exec EXEC_ACTS (index)</td><td></td></tr>
<tr><td> 18.132</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "ads/ads.php" for 0.00050000000000239 sec</td><td></td></tr>
<tr><td> 18.135</td><td> 0.003</td><td>loadActionHandlers(index): preloaded file "tags/tags.php" for 0.0028000000000006 sec</td><td></td></tr>
<tr><td> 18.136</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "elka/elka.php" for 0.00049999999999883 sec</td><td></td></tr>
<tr><td> 18.136</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "favorites/favorites.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 18.137</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "top_news/top_news.php" for 0.00079999999999814 sec</td><td></td></tr>
<tr><td> 18.139</td><td> 0.002</td><td>loadActionHandlers(index): preloaded file "similar/similar.php" for 0.0013000000000005 sec</td><td></td></tr>
<tr><td> 18.144</td><td> 0.005</td><td>loadActionHandlers(index): preloaded file "football_stats/football_stats.php" for 0.004999999999999 sec</td><td></td></tr>
<tr><td> 18.144</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_ads" [1 params] for 0.00030000000000285 sec</td><td></td></tr>
<tr><td> 18.146</td><td> 0.002</td><td>func EXEC_ACTS (index): call function "plugin_tags_cloudblock" [1 params] for 0.0015000000000001 sec</td><td></td></tr>
<tr><td> 18.146</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "elka" [1 params] for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 18.147</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_favorites" [1 params] for 0.0002999999999993 sec</td><td></td></tr>
<tr><td> 18.152</td><td> 0.005</td><td>func EXEC_ACTS (index): call function "top_news" [1 params] for 0.0053000000000019 sec</td><td></td></tr>
<tr><td> 18.178</td><td> 0.026</td><td>func EXEC_ACTS (index): call function "showTablesMain" [1 params] for 0.0259 sec</td><td></td></tr>
<tr><td> 18.178</td><td> 0.000</td><td>Master activity finished</td><td></td></tr>
<tr><td> 18.182</td><td> 0.004</td><td>Category menu created</td><td></td></tr>
<tr><td> 18.183</td><td> 0.001</td><td>exec EXEC_ACTS (usermenu)</td><td></td></tr>
<tr><td> 18.185</td><td> 0.001</td><td>exec EXEC_ACTS (index_post)</td><td></td></tr>
<tr><td> 18.185</td><td> 0.001</td><td>loadActionHandlers(index_post): preloaded file "simple_title_ng/simple_title.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 18.187</td><td> 0.002</td><td>loadActionHandlers(index_post): preloaded file "ads_sape/ads_sape.php" for 0.0019999999999989 sec</td><td></td></tr>
<tr><td> 18.188</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "plugin_multi_main" [1 params] for 0 sec</td><td></td></tr>
<tr><td> 18.188</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "simple_tags" [1 params] for 9.9999999999767E-5 sec</td><td></td></tr>
<tr><td> 18.190</td><td> 0.002</td><td>func EXEC_ACTS (index_post): call function "plugin_ads_sape" [1 params] for 0.002200000000002 sec</td><td></td></tr>
<tr><td> 18.190</td><td> 0.000</td><td>Templates generation time: 0.0040600299835205 (39 times called)</td><td></td></tr>
<tr><td> 18.190</td><td> 0.000</td><td>Generate DEBUG output</td><td></td></tr>
</table>
<table class='timeProfiler'>
<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>
<tr><td> 0.001</td><td> 0.000</td><td>Config file is loaded</td><td></td></tr>
<tr><td> 0.015</td><td> 0.014</td><td>Core files are included</td><td></td></tr>
<tr><td> 0.024</td><td> 0.009</td><td>Template engine is activated</td><td></td></tr>
<tr><td> 0.030</td><td> 0.006</td><td>DB connection established</td><td></td></tr>
<tr><td> 0.031</td><td> 0.002</td><td>DB category list is loaded</td><td></td></tr>
<tr><td> 0.031</td><td> 0.000</td><td>Ready to load auth plugins</td><td></td></tr>
<tr><td> 0.034</td><td> 0.003</td><td>loadActionHandlers(auth): preloaded file "auth_basic/auth_basic.php" for 0.002 sec</td><td></td></tr>
<tr><td> 0.034</td><td> 0.000</td><td>Auth plugins are loaded</td><td></td></tr>
<tr><td> 0.034</td><td> 0.000</td><td>Auth procedure is finished</td><td></td></tr>
<tr><td> 0.034</td><td> 0.000</td><td>ALL core-related plugins are loaded</td><td></td></tr>
<tr><td> 0.034</td><td> 0.000</td><td>exec EXEC_ACTS (core)</td><td></td></tr>
<tr><td> 0.039</td><td> 0.005</td><td>loadPluginLibrary: uprofile [lib] for 0.00029999999999999 sec</td><td></td></tr>
<tr><td> 0.039</td><td> 0.000</td><td>loadActionHandlers(core): preloaded file "xfields/xfields.php" for 0.0046 sec</td><td></td></tr>
<tr><td> 0.039</td><td> 0.000</td><td>ALL core-related plugins are executed</td><td></td></tr>
<tr><td> 0.040</td><td> 0.001</td><td>* CORE.PHP is complete</td><td></td></tr>
<tr><td> 0.040</td><td> 0.000</td><td>exec EXEC_ACTS (index_pre)</td><td></td></tr>
<tr><td> 0.040</td><td> 0.001</td><td>loadActionHandlers(index_pre): preloaded file "multi_main/multi_main.php" for 0.0003 sec</td><td></td></tr>
<tr><td> 0.040</td><td> 0.000</td><td>Search route for URL "/box-novyny/klichki-svyatkuvatimut-novii-rik-na-beregah-atlantiki166.html"</td><td></td></tr>
<tr><td> 0.044</td><td> 0.004</td><td>loadActionHandlers(news): preloaded file "ads/ads.php" for 0.0005 sec</td><td></td></tr>
<tr><td> 0.046</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "tags/tags.php" for 0.0018 sec</td><td></td></tr>
<tr><td> 0.049</td><td> 0.003</td><td>loadActionHandlers(news): preloaded file "comments/comments.php" for 0.003 sec</td><td></td></tr>
<tr><td> 0.050</td><td> 0.001</td><td>loadActionHandlers(news): preloaded file "cat_description/cat_description.php" for 0.0004 sec</td><td></td></tr>
<tr><td> 0.051</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "similar/similar.php" for 0.0014 sec</td><td></td></tr>
<tr><td> 0.054</td><td> 0.003</td><td>call showNewsPre() for [ 0 ] sec</td><td></td></tr>
<tr><td> 0.054</td><td> 0.000</td><td>call newsFillVariables() for [ 0.002 ] sec</td><td></td></tr>
<tr><td> 0.055</td><td> 0.000</td><td>exec EXEC_ACTS (news_full)</td><td></td></tr>
<tr><td> 0.055</td><td> 0.000</td><td>func EXEC_ACTS (news_full): call function "plugin_multi_main_full" [3 params] for 0 sec</td><td></td></tr>
<tr><td> 0.055</td><td> 0.000</td><td>exec showNews // xfields</td><td></td></tr>
<tr><td> 0.055</td><td> 0.001</td><td>exec showNews // ads</td><td></td></tr>
<tr><td> 0.056</td><td> 0.000</td><td>exec showNews // tags</td><td></td></tr>
<tr><td> 0.056</td><td> 0.001</td><td>exec showNews // comments</td><td></td></tr>
<tr><td> 0.074</td><td> 0.018</td><td>exec EXEC_ACTS (comments_form)</td><td></td></tr>
<tr><td> 0.075</td><td> 0.002</td><td>exec showNews // cat_description</td><td></td></tr>
<tr><td> 0.075</td><td> 0.000</td><td>exec showNews // similar</td><td></td></tr>
<tr><td> 11.394</td><td> 11.318</td><td>call showNews() for [ 11.3389 ] sec</td><td></td></tr>
<tr><td> 11.396</td><td> 0.003</td><td>exec EXEC_ACTS (index)</td><td></td></tr>
<tr><td> 11.397</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "elka/elka.php" for 0.00050000000000061 sec</td><td></td></tr>
<tr><td> 11.398</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "favorites/favorites.php" for 0.00040000000000084 sec</td><td></td></tr>
<tr><td> 11.399</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "top_news/top_news.php" for 0.00070000000000014 sec</td><td></td></tr>
<tr><td> 11.404</td><td> 0.005</td><td>loadActionHandlers(index): preloaded file "football_stats/football_stats.php" for 0.004900000000001 sec</td><td></td></tr>
<tr><td> 11.406</td><td> 0.003</td><td>loadActionHandlers(index): preloaded file "boxers/boxers.php" for 0.0025999999999993 sec</td><td></td></tr>
<tr><td> 11.407</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_ads" [1 params] for 0.00030000000000108 sec</td><td></td></tr>
<tr><td> 11.408</td><td> 0.002</td><td>func EXEC_ACTS (index): call function "plugin_tags_cloudblock" [1 params] for 0.0016999999999996 sec</td><td></td></tr>
<tr><td> 11.409</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "elka" [1 params] for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 11.409</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_favorites" [1 params] for 0.00040000000000084 sec</td><td></td></tr>
<tr><td> 11.415</td><td> 0.006</td><td>func EXEC_ACTS (index): call function "top_news" [1 params] for 0.0062999999999995 sec</td><td></td></tr>
<tr><td> 11.442</td><td> 0.027</td><td>func EXEC_ACTS (index): call function "showTablesMain" [1 params] for 0.026899999999999 sec</td><td></td></tr>
<tr><td> 11.444</td><td> 0.001</td><td>func EXEC_ACTS (index): call function "showRatings" [1 params] for 0.0012000000000008 sec</td><td></td></tr>
<tr><td> 11.444</td><td> 0.000</td><td>Master activity finished</td><td></td></tr>
<tr><td> 11.450</td><td> 0.007</td><td>Category menu created</td><td></td></tr>
<tr><td> 11.451</td><td> 0.001</td><td>exec EXEC_ACTS (usermenu)</td><td></td></tr>
<tr><td> 11.453</td><td> 0.001</td><td>exec EXEC_ACTS (index_post)</td><td></td></tr>
<tr><td> 11.453</td><td> 0.001</td><td>loadActionHandlers(index_post): preloaded file "simple_title_ng/simple_title.php" for 0.00050000000000061 sec</td><td></td></tr>
<tr><td> 11.456</td><td> 0.002</td><td>loadActionHandlers(index_post): preloaded file "ads_sape/ads_sape.php" for 0.0021000000000004 sec</td><td></td></tr>
<tr><td> 11.456</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "plugin_multi_main" [1 params] for 0 sec</td><td></td></tr>
<tr><td> 11.456</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "simple_tags" [1 params] for 0 sec</td><td></td></tr>
<tr><td> 11.458</td><td> 0.002</td><td>func EXEC_ACTS (index_post): call function "plugin_ads_sape" [1 params] for 0.0021000000000004 sec</td><td></td></tr>
<tr><td> 11.458</td><td> 0.000</td><td>Templates generation time: 0.025873899459839 (66 times called)</td><td></td></tr>
<tr><td> 11.458</td><td> 0.000</td><td>Generate DEBUG output</td><td></td></tr>
</table>
<table class='timeProfiler'>
<tr><td><b>Time</b></td><td><b>Delta</b></td><td><b>Event</b></td><td><b>Desc</b></td></tr>
<tr><td> 0.001</td><td> 0.000</td><td>Config file is loaded</td><td></td></tr>
<tr><td> 0.132</td><td> 0.130</td><td>Core files are included</td><td></td></tr>
<tr><td> 0.177</td><td> 0.046</td><td>Template engine is activated</td><td></td></tr>
<tr><td> 0.184</td><td> 0.006</td><td>DB connection established</td><td></td></tr>
<tr><td> 0.185</td><td> 0.002</td><td>DB category list is loaded</td><td></td></tr>
<tr><td> 0.185</td><td> 0.000</td><td>Ready to load auth plugins</td><td></td></tr>
<tr><td> 0.188</td><td> 0.003</td><td>loadActionHandlers(auth): preloaded file "auth_basic/auth_basic.php" for 0.0023 sec</td><td></td></tr>
<tr><td> 0.189</td><td> 0.000</td><td>Auth plugins are loaded</td><td></td></tr>
<tr><td> 0.191</td><td> 0.003</td><td>Auth procedure is finished</td><td></td></tr>
<tr><td> 0.191</td><td> 0.000</td><td>ALL core-related plugins are loaded</td><td></td></tr>
<tr><td> 0.191</td><td> 0.000</td><td>exec EXEC_ACTS (core)</td><td></td></tr>
<tr><td> 0.197</td><td> 0.006</td><td>loadPluginLibrary: uprofile [lib] for 0.00020000000000001 sec</td><td></td></tr>
<tr><td> 0.197</td><td> 0.000</td><td>loadActionHandlers(core): preloaded file "xfields/xfields.php" for 0.0053 sec</td><td></td></tr>
<tr><td> 0.197</td><td> 0.000</td><td>ALL core-related plugins are executed</td><td></td></tr>
<tr><td> 0.198</td><td> 0.001</td><td>* CORE.PHP is complete</td><td></td></tr>
<tr><td> 0.198</td><td> 0.000</td><td>exec EXEC_ACTS (index_pre)</td><td></td></tr>
<tr><td> 0.198</td><td> 0.001</td><td>loadActionHandlers(index_pre): preloaded file "multi_main/multi_main.php" for 0.00040000000000001 sec</td><td></td></tr>
<tr><td> 0.198</td><td> 0.000</td><td>Search route for URL "/search/"</td><td></td></tr>
<tr><td> 0.558</td><td> 0.360</td><td>loadActionHandlers(news): preloaded file "ads/ads.php" for 0.00049999999999994 sec</td><td></td></tr>
<tr><td> 0.560</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "tags/tags.php" for 0.0018 sec</td><td></td></tr>
<tr><td> 0.563</td><td> 0.003</td><td>loadActionHandlers(news): preloaded file "comments/comments.php" for 0.003 sec</td><td></td></tr>
<tr><td> 0.563</td><td> 0.001</td><td>loadActionHandlers(news): preloaded file "cat_description/cat_description.php" for 0.00040000000000007 sec</td><td></td></tr>
<tr><td> 0.565</td><td> 0.002</td><td>loadActionHandlers(news): preloaded file "similar/similar.php" for 0.0016 sec</td><td></td></tr>
<tr><td> 16.108</td><td> 15.543</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.113</td><td> 0.005</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.205</td><td> 0.092</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.210</td><td> 0.005</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.214</td><td> 0.004</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.216</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.223</td><td> 0.006</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.226</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.231</td><td> 0.005</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.234</td><td> 0.002</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.236</td><td> 0.002</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.238</td><td> 0.002</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.241</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.243</td><td> 0.002</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.246</td><td> 0.003</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.248</td><td> 0.002</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.279</td><td> 0.031</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.287</td><td> 0.008</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.292</td><td> 0.005</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.294</td><td> 0.002</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.297</td><td> 0.002</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.303</td><td> 0.007</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.311</td><td> 0.007</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.322</td><td> 0.012</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.331</td><td> 0.009</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 16.342</td><td> 0.011</td><td>exec EXEC_ACTS (news_short)</td><td></td></tr>
<tr><td> 80.361</td><td> 64.018</td><td>exec EXEC_ACTS (index)</td><td></td></tr>
<tr><td> 80.367</td><td> 0.007</td><td>loadActionHandlers(index): preloaded file "elka/elka.php" for 0.0005999999999915 sec</td><td></td></tr>
<tr><td> 80.370</td><td> 0.003</td><td>loadActionHandlers(index): preloaded file "favorites/favorites.php" for 0.00039999999999907 sec</td><td></td></tr>
<tr><td> 80.371</td><td> 0.001</td><td>loadActionHandlers(index): preloaded file "top_news/top_news.php" for 0.00079999999999814 sec</td><td></td></tr>
<tr><td> 80.380</td><td> 0.009</td><td>loadActionHandlers(index): preloaded file "football_stats/football_stats.php" for 0.0089000000000112 sec</td><td></td></tr>
<tr><td> 82.836</td><td> 2.456</td><td>loadActionHandlers(index): preloaded file "boxers/boxers.php" for 2.4507 sec</td><td></td></tr>
<tr><td> 82.837</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_ads" [1 params] for 0.00029999999999575 sec</td><td></td></tr>
<tr><td> 82.842</td><td> 0.005</td><td>func EXEC_ACTS (index): call function "plugin_tags_cloudblock" [1 params] for 0.0054999999999978 sec</td><td></td></tr>
<tr><td> 82.842</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "elka" [1 params] for 0.00029999999999575 sec</td><td></td></tr>
<tr><td> 82.843</td><td> 0.000</td><td>func EXEC_ACTS (index): call function "plugin_favorites" [1 params] for 0.00029999999999575 sec</td><td></td></tr>
<tr><td> 82.870</td><td> 0.027</td><td>func EXEC_ACTS (index): call function "top_news" [1 params] for 0.027300000000011 sec</td><td></td></tr>
<tr><td> 82.998</td><td> 0.128</td><td>func EXEC_ACTS (index): call function "showTablesMain" [1 params] for 0.1279 sec</td><td></td></tr>
<tr><td> 83.007</td><td> 0.009</td><td>func EXEC_ACTS (index): call function "showRatings" [1 params] for 0.0090999999999894 sec</td><td></td></tr>
<tr><td> 83.007</td><td> 0.000</td><td>Master activity finished</td><td></td></tr>
<tr><td> 83.011</td><td> 0.004</td><td>Category menu created</td><td></td></tr>
<tr><td> 83.014</td><td> 0.003</td><td>exec EXEC_ACTS (usermenu)</td><td></td></tr>
<tr><td> 83.023</td><td> 0.008</td><td>exec EXEC_ACTS (index_post)</td><td></td></tr>
<tr><td> 83.023</td><td> 0.001</td><td>loadActionHandlers(index_post): preloaded file "simple_title_ng/simple_title.php" for 0.00050000000000239 sec</td><td></td></tr>
<tr><td> 83.025</td><td> 0.002</td><td>loadActionHandlers(index_post): preloaded file "ads_sape/ads_sape.php" for 0.0019999999999953 sec</td><td></td></tr>
<tr><td> 83.026</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "plugin_multi_main" [1 params] for 0.00010000000000332 sec</td><td></td></tr>
<tr><td> 83.026</td><td> 0.000</td><td>func EXEC_ACTS (index_post): call function "simple_tags" [1 params] for 0.00010000000000332 sec</td><td></td></tr>
<tr><td> 83.029</td><td> 0.003</td><td>func EXEC_ACTS (index_post): call function "plugin_ads_sape" [1 params] for 0.0031000000000034 sec</td><td></td></tr>
<tr><td> 83.029</td><td> 0.000</td><td>Templates generation time: 0.19256258010864 (65 times called)</td><td></td></tr>
<tr><td> 83.029</td><td> 0.000</td><td>Generate DEBUG output</td><td></td></tr>
</table>
Изменено legenda (2011-12-28 04:14:52)
Не в сети
#106 2011-12-28 08:47:04
Re: Помогите разобратся что так грузит сайт
Ну во первых таких событий всего 7. То есть тормоза появляются относительно редко. Файл удали пока, чтобы заново отсчет пошел.
Затыка всего три.
Первый это:
0.025 0.008 Template engine is activated
5.833 5.808 DB connection established
5 секунд подключение к БД?
Второй:
19.032 Config file is loaded
19 секунд загрузка config файла?
17.988 Config file is loaded
18 секунд загрузка config файла?
И третий:
0.075 exec showNews // similar
11.394 call showNews() for [ 11.3389 ] sec
0.079 0.000 exec showNews // similar
10.613 10.533 call showNews() for [ 10.5561 ] sec
Что он тут делает 10 секунд при первом вызове showNews тоже непонятно?
Подождем коммента Виталия
Не в сети
#107 2011-12-28 09:44:57
Re: Помогите разобратся что так грузит сайт
legenda, посмотри сам на логи.
Обрати внимание, что тормоза происходят в самые непредсказуемые моменты.
В такой ситуации мы тебе ничем не поможем, тут одно из двух:
1. Кол-во посетителей превышает возможности сервера
2. У хостера серьёзные проблемы с нагрузкой на систему (ты же не один на этом сервере живёшь)
Я склоняюсь к первому варианту.
Я ведь всё правильно помню, сайт - sportanalytic.com ?
Вот для примера результаты проверки скорости работы твоего сайта.
* 30 запросов в 1 поток:
Concurrency Level: 1
Time taken for tests: 23.003 seconds
Complete requests: 30
Failed requests: 0
Write errors: 0
Total transferred: 6007020 bytes
HTML transferred: 5994870 bytes
Requests per second: 1.30 [#/sec] (mean)
Time per request: 766.759 [ms] (mean)
Time per request: 766.759 [ms] (mean, across all concurrent requests)
Transfer rate: 255.02 [Kbytes/sec] received* 30 запросов в 2 потока:
Concurrency Level: 2
Time taken for tests: 16.312 seconds
Complete requests: 30
Failed requests: 2
(Connect: 0, Receive: 0, Length: 2, Exceptions: 0)
Write errors: 0
Total transferred: 6007018 bytes
HTML transferred: 5994868 bytes
Requests per second: 1.84 [#/sec] (mean)
Time per request: 1087.499 [ms] (mean)
Time per request: 543.750 [ms] (mean, across all concurrent requests)
Transfer rate: 359.62 [Kbytes/sec] received* 30 запросов в 3 потока:
Concurrency Level: 3
Time taken for tests: 12.068 seconds
Complete requests: 30
Failed requests: 2
(Connect: 0, Receive: 0, Length: 2, Exceptions: 0)
Write errors: 0
Total transferred: 6205693 bytes
HTML transferred: 6193138 bytes
Requests per second: 2.49 [#/sec] (mean)
Time per request: 1206.825 [ms] (mean)
Time per request: 402.275 [ms] (mean, across all concurrent requests)
Transfer rate: 502.16 [Kbytes/sec] receivedОбрати внимание, даже при 3х одновременных запросах (на главную страницу) время генерации страницы составляет 1.2 сек.
3 одновременных потока и время генерации 1.5 секунды дадут нам производительность в 1.5/3 = 0.5 req/sec => 172k (теоретических) и ~70k (практических с учётом неравномерности нагрузки в разное время суток) хитов в сутки.
А теперь - смертельный номер, делаем 10 одновременных запросов:
Concurrency Level: 10
Time taken for tests: 9.645 seconds
Complete requests: 30
Failed requests: 2
(Connect: 0, Receive: 0, Length: 2, Exceptions: 0)
Write errors: 0
Total transferred: 6004978 bytes
HTML transferred: 5992828 bytes
Requests per second: 3.11 [#/sec] (mean)
Time per request: 3215.132 [ms] (mean)
Time per request: 321.513 [ms] (mean, across all concurrent requests)
Transfer rate: 607.98 [Kbytes/sec] received... странно, смерть не наступила. Ну да ладно.
Заметь, теперь на генерацию страницы уходит уже 3.2 секунды (одновременно сервер получает по 10 запросов !!)
Идём дальше - 20 одновременных запросов, общее кол-во - 100 штук:
Concurrency Level: 20
Time taken for tests: 28.831 seconds
Complete requests: 100
Failed requests: 9
(Connect: 0, Receive: 0, Length: 9, Exceptions: 0)
Write errors: 0
Total transferred: 20016587 bytes
HTML transferred: 19976087 bytes
Requests per second: 3.47 [#/sec] (mean)
Time per request: 5766.234 [ms] (mean)
Time per request: 288.312 [ms] (mean, across all concurrent requests)
Transfer rate: 678.00 [Kbytes/sec] receivedВремя генерации - 5.7sec/запрос (в одном потоке).
Продолжаем DOS'ить сайт - 40 одновременных запросов, всего 100 штук:
Concurrency Level: 40
Time taken for tests: 26.310 seconds
Complete requests: 100
Failed requests: 26
(Connect: 0, Receive: 0, Length: 26, Exceptions: 0)
Write errors: 0
Total transferred: 20016605 bytes
HTML transferred: 19976105 bytes
Requests per second: 3.80 [#/sec] (mean)
Time per request: 10524.014 [ms] (mean)
Time per request: 263.100 [ms] (mean, across all concurrent requests)
Transfer rate: 742.97 [Kbytes/sec] receivedПри 40 одновременных запросах время генерации страницы составило уже 10.5 секунд.... для посетителей это уже совсем плохо, но до твоих цифр (под 60+ секунд) мы пока не добрались.
Увеличиваем нагрузку - 500 запросов в 60 одновременных сессиях (уже явная DOS атака):
Concurrency Level: 60
Time taken for tests: 149.762 seconds
Complete requests: 500
Failed requests: 418
(Connect: 0, Receive: 0, Length: 418, Exceptions: 0)
Write errors: 0
Total transferred: 100080882 bytes
HTML transferred: 99878382 bytes
Requests per second: 3.34 [#/sec] (mean)
Time per request: 17971.388 [ms] (mean)
Time per request: 299.523 [ms] (mean, across all concurrent requests)
Transfer rate: 652.61 [Kbytes/sec] receivedУра, мы уже добрались до 17 секунд на генерацию страницы. Правда добрались под явным DOS'ом... при этом среднее время генерации страницы (с учётом одновременных потоков) получается 300ms, т.е. в пике в час ты можешь отдать до 12 тысяч хитов.
Но и в этой ситуации мы так и не увидели твоих 80+ секунд... что же делать?
Раз уж пошла такая пьянка, то давай отправим 1000 запросов в 150 одновременных сессий...
Concurrency Level: 150
Time taken for tests: 288.442 seconds
Complete requests: 1000
Failed requests: 904
(Connect: 0, Receive: 0, Length: 904, Exceptions: 0)
Write errors: 0
Non-2xx responses: 7
Total transferred: 198798784 bytes
HTML transferred: 198395108 bytes
Requests per second: 3.47 [#/sec] (mean)
Time per request: 43266.315 [ms] (mean)
Time per request: 288.442 [ms] (mean, across all concurrent requests)
Transfer rate: 673.06 [Kbytes/sec] receivedУра, мы добились этого!
Время генерации 43 секунды... но постойте, такое время получилось при 150 одновременных запросах!!!
Т.е. за 280 секунд (4.5 минуты) ты выдал 1000 хитов, что даёт ~13k хитов в час.
Как ты видишь, цифра (максимальная производительность) на 150 одновременных запросах очень близка к цифре, полученной и на 60 одновременных запросах..
Мой вывод:
1. У сайта нет проблем с производительностью, цифры в 15-30-60-90 секунд выдаются из-за бешенного кол-ва одновременных запросов к серверу, сервер физически не может справиться с таким потоком. Обращать внимания на эти цифры нет никакого смысла, у тебя же не 100 ядерный процессор и ты сам должен понимать, что увеличение одновременного кол-ва запросов в 2 раза ведёт к замедлению работы всех одновременных скриптов в те же 2 (и даже больше) раз. Кстати, у твоего сервера (судя по результатам) - всего 2-3 ядра.
2. Пиковая оптимальная производительность твоего сервера (с твоей конфигурацией плагинов) составляет 2.5 запроса в секунду.
3. Пиковая допустимая производительность составляет 2.8-2.9 запросов в секунду. Всё что выше - ведёт у показанным тобой (и мной) примерам.
4. Если хочешь, чтобы сайт работал быстрее, то на выбор:
4.1. Ставь более мощный сервер (отчасти может спасти вынос mySQL на отдельный сервер (может дать прирост до 40+%), кое-что даст оптимизация настроек mySQL...)
4.2. Отключай всякие ресурсоёмкие плагины
Кстати, для сравнения - вот результаты отправки 1000 запросов в 30 сессиях на сайт (работает на NG), получающий 2 ядра от Core2Quad Q9550:
Concurrency Level: 30
Time taken for tests: 144.562 seconds
Complete requests: 1000
Failed requests: 624
(Connect: 0, Receive: 0, Length: 624, Exceptions: 0)
Write errors: 0
Total transferred: 95647694 bytes
HTML transferred: 95337694 bytes
Requests per second: 6.92 [#/sec] (mean)
Time per request: 4336.868 [ms] (mean)
Time per request: 144.562 [ms] (mean, across all concurrent requests)
Transfer rate: 646.13 [Kbytes/sec] receivedему требуется 150-160ms на генерацию страницы, что даёт оптимальную нагрузку в 6 запросов в секунду.
Если сайт получит все 4 ядра, то сможет отдавать до 12 запросов в секунду (в максимуме это 1 миллион запросов в сутки, в реальности же с учётом неравномерности нагрузки - всего 400-500k запросов в сутки, что позволит обслужить 50-150k посетителей в сутки).
Много это или мало - судить не мне.
Не в сети
#108 2011-12-28 09:53:58
#109 2011-12-28 09:54:58
#110 2011-12-28 17:06:59
Re: Помогите разобратся что так грузит сайт
vitaly, у меня есть такой же но другой сервак (по характеристикам в 4 раза меньше) для других сайтов, там стоит 3 сайта с такими же плагинами (в том числе similar, top_news, xfields), и их посещалка в 2-3 раза више, а вчера например сумарно было больше 100к, что практически в 10 раз больше чем вчера на спортаналитике. Единственное чем отличается что там в каждого сайта сравнительно мало новостей (5-10 тис.) и база весит примерно 20 мбт, а у спортаналитика около 100 тис. и вес бази - 0,5 гиг, при этом на втором серваке даже близко нету той проблемы, поэтому я почемуто не думаю что все связано с серваком.
Если смотреть на что тратятся все ресурси так это все идлет на mysqld, нуда скорее всего надо оптимизацию настроек mySQL, кто может в этом помочь?
цифры в 15-30-60-90 секунд выдаются из-за бешенного кол-ва одновременных запросов к серверу, сервер физически не может справиться с таким потоком.
Я думаю что правельнее не наращивать ресурсы сервера а смотреть откуда берется бешеное кол-во одновременных запросов к серверу, как мне кажется что 90% запросов просто левых...
Сегодня ночью я пробовал отключить 3 плагина similar, top_news, xfields. Сайт просто летал (SQL запитів: 5, Генерація сторінки: 0.15 сек - 0,35 максимум) , думаю примерно что также летал бы и при большом количестве посетителей (500-1000 онлайн)
Тоесть проблему надо искать именно в этих трех плагинах.
Если similar, хрен с ним могу и без него обойтись раз уж такое дело, но вот с xfields и top_news проблемно.
1. Можно както оптимизировать чтоб xfields не делал 50 запросов? ато както это очень меня напрягает.
2. Если в top_news много блоков он очень долго грузится, что можно сделать чтоб оптимизировать нагрузку?
Изменено legenda (2011-12-28 17:23:57)
Не в сети
#111 2011-12-28 17:34:06
Re: Помогите разобратся что так грузит сайт
vitaly, Раз уж пошла такая пьянка, то давай отправим 1000 запросов в 150 одновременных сессий...
Код:
Concurrency Level: 150
Time taken for tests: 288.442 seconds
Complete requests: 1000
Failed requests: 904
(Connect: 0, Receive: 0, Length: 904, Exceptions: 0)
Write errors: 0
Non-2xx responses: 7
Total transferred: 198798784 bytes
HTML transferred: 198395108 bytes
Requests per second: 3.47 [#/sec] (mean)
Time per request: 43266.315 [ms] (mean)
Time per request: 288.442 [ms] (mean, across all concurrent requests)
Transfer rate: 673.06 [Kbytes/sec] received
если перевести в реал сколько это хитов за минуту?
Изменено legenda (2011-12-28 17:34:29)
Не в сети
#112 2011-12-28 18:55:53
Re: Помогите разобратся что так грузит сайт
Я думаю что правельнее не наращивать ресурсы сервера а смотреть откуда берется бешеное кол-во одновременных запросов к серверу, как мне кажется что 90% запросов просто левых...
Я думаю Виталий имел ввиду не запросов SQL, а запросов от людей
Все запросы у тебя выполняются моментально, очень быстро, судя по логам которые ты выкладывал. Даже те 50 от xfields меньше секунды.
Не в сети
#113 2011-12-28 19:32:05
#114 2011-12-29 02:48:36
Re: Помогите разобратся что так грузит сайт
vitaly, Раз уж пошла такая пьянка, то давай отправим 1000 запросов в 150 одновременных сессий...
Код:
Concurrency Level: 150
Time taken for tests: 288.442 seconds
Complete requests: 1000
Failed requests: 904
(Connect: 0, Receive: 0, Length: 904, Exceptions: 0)
Write errors: 0
Non-2xx responses: 7
Total transferred: 198798784 bytes
HTML transferred: 198395108 bytes
Requests per second: 3.47 [#/sec] (mean)
Time per request: 43266.315 [ms] (mean)
Time per request: 288.442 [ms] (mean, across all concurrent requests)
Transfer rate: 673.06 [Kbytes/sec] receivedесли перевести в реал сколько это хитов за минуту?
Это ~200 хитов в минуту.
По поводу оптимизации и всего остального - это уже работа для системного администратора.
Проанализировать логи, посмотреть средний профиль запросов, выполнить тонкую настройку mysql, web сервера и так далее.
К примеру, некоторые страницы можно кешировать средствами nginx'а, это может дать (а может и не дать - нужно чётко понимать требования) значительный прирост в производительности.
Будет желание - обращайся. Но ценник начинается от $100 (зависит от требований).
Как вариант - можешь найти исполнителей на такую задачу на сайтах фрилансеров.
Не в сети
#115 2011-12-29 03:21:39
#116 2011-12-29 15:02:13
Re: Помогите разобратся что так грузит сайт
vitaly, можеш помочь откоректировать xfields чтоб небило этих 50 запросов?, я понимаю что они виполняются бистро, но всетаки я б хотел както убрать, а то страничках новостей
SQL запитів: 6, Генерація сторінки: 0.15 сек
а на главной
SQL запитів: 55, Генерація сторінки: 0.38 сек
Я вот немного подкоректировал сам шаблон, немного блоки top_news поменял, счас боюсь накаркать но все летает, жду болееменее нормальной посещалки чтоб понять все ли ок, но єти 50запросов от xfields меня очень волнуют.
Изменено legenda (2011-12-29 15:02:43)
Не в сети
#117 2011-12-30 15:45:21
Re: Помогите разобратся что так грузит сайт
вот что увидел, когда ставлю в настройках сайта "Количество новостей для отображения на одной странице" - 10 , то xfields генерует на главной 10 запросов, когда 50 то 50 запросов, хотя у меня на главной ваше неидуть те новости, у меня там только блоки, че делать?
Не в сети
#118 2011-12-30 18:13:44
#119 2011-12-30 18:24:07
Re: Помогите разобратся что так грузит сайт
В принципе проблема решаема, как и с рейтингом http://ngcms.ru/forum/viewtopic.php?pid=24165#p24165
Если бы это было сделано в двиге, то эти IDшники и здесь можно было использовать, чтобы делать один запрос вместо N = количеству новостей.
Не в сети
#120 2011-12-31 16:28:07
Re: Помогите разобратся что так грузит сайт
Сегодня еще случайно в базе заметил таблицу с назвой test_news весит примерно 200 метров и практичести в ней все тожесамое что и в 2z_news, я чет немогу вспомнить откуда она там могла взятся, насколько я понимаю такой таблици не должно бить, или она для чегото предназначена?
вот ее структура
Изменено legenda (2011-12-31 16:28:42)
Не в сети
#121 2011-12-31 16:41:26
#122 2011-12-31 17:02:41
#123 2011-12-31 18:39:21
#124 2012-01-25 16:06:50
Re: Помогите разобратся что так грузит сайт
Внимательно ознакомился с выше написанным включил кеширование где смог, и хотел-бы задать такой-же вопрос, сайт постоянно чего-то грузит
SQL queries:
[ 0.0002 ] select nc.*, ni.id as icon_id, ni.name as icon_name, ni.storage as icon_storage, ni.folder as icon_folder, ni.preview as icon_preview, ni.width as icon_width, ni.height as icon_height, ni.p_width as icon_pwidth, ni.p_height as icon_pheight from `ng_category` as nc left join `ng_images` ni on nc.image_id = ni.id order by nc.posorder asc
[ 9.9999999999999E-5 ] select * from ng_users where authcookie = '98db920e29cb05e16debb205d115a74a' limit 1
[ 0.0002 ] SELECT * FROM ng_news WHERE (`approve` = '1') AND (`mainpage` = '1') order by pinned desc, id desc limit 0,3
[ 9.9999999999999E-5 ] SELECT n.id, n.title, n.alt_name, n.catid, n.postdate FROM ng_bookmarks AS b LEFT JOIN ng_news n ON n.id = b.news_id WHERE b.user_id = '1'
[ 0 ] SELECT COUNT(*) FROM ng_bookmarks WHERE news_id=86
[ 0.008 ] SELECT * FROM ng_news WHERE id !=86 AND approve=1 AND ((catid regexp '[[:<:]](19)[[:>:]]')) ORDER BY rand() LIMIT 0,20
[ 0.0001 ] SELECT COUNT(*) FROM ng_bookmarks WHERE news_id=28
[ 0.0071 ] SELECT * FROM ng_news WHERE id !=28 AND approve=1 AND ((catid regexp '[[:<:]](19)[[:>:]]') OR (catid regexp '[[:<:]](18)[[:>:]]')) ORDER BY rand() LIMIT 0,20
[ 0 ] SELECT COUNT(*) FROM ng_bookmarks WHERE news_id=25
[ 0.0075 ] SELECT * FROM ng_news WHERE id !=25 AND approve=1 AND ((catid regexp '[[:<:]](8)[[:>:]]')) ORDER BY rand() LIMIT 0,20
[ 0 ] SELECT count(*) as count FROM ng_news WHERE (`approve` = '1') AND (`mainpage` = '1')
[ 0.0008 ] select * from ng_vote where id = '1'
[ 0.00020000000000001 ] SELECT `session` FROM ng_online WHERE `session` = '7d11ec7ef3d50f6f3be061c4cba083ee' LIMIT 1
[ 0.00070000000000001 ] UPDATE `ng_online` SET `lasttime`=1327494204, `id`=1, `login`='kolia_jim', `status`=1, `com`='5' WHERE `session`='7d11ec7ef3d50f6f3be061c4cba083ee'
[ 0.00020000000000001 ] SELECT * FROM `ng_online` WHERE `lasttime` > 1327493904;
Time profiler:
Time Delta Event Desc
0.001 0.000 Config file is loaded
0.002 0.001 Core files are included
0.004 0.002 Template engine is activated
0.005 0.001 DB connection established
0.005 0.000 DB category list is loaded
0.005 0.000 Ready to load auth plugins
0.006 0.001 loadActionHandlers(auth): preloaded file "auth_basic/auth_basic.php" for 0.0006 sec
0.006 0.000 Auth plugins are loaded
0.006 0.000 Auth procedure is finished
0.006 0.000 ALL core-related plugins are loaded
0.006 0.000 exec EXEC_ACTS (core)
0.006 0.000 loadActionHandlers(core): preloaded file "switcher/switcher.php" for 0 sec
0.007 0.000 loadActionHandlers(core): preloaded file "autokeys/autokeys.php" for 0.0001 sec
0.007 0.000 func EXEC_ACTS (core): call function "plugin_switcher" [1 params] for 0.0001 sec
0.007 0.000 ALL core-related plugins are executed
0.007 0.000 * CORE.PHP is complete
0.007 0.000 exec EXEC_ACTS (index_pre)
0.007 0.000 Search route for URL "/"
0.008 0.001 loadActionHandlers(news): preloaded file "similar/similar.php" for 9.9999999999999E-5 sec
0.008 0.000 loadActionHandlers(news): preloaded file "make_links/news.php" for 0 sec
0.008 0.000 loadActionHandlers(news): preloaded file "cat_description/cat_description.php" for 9.9999999999999E-5 sec
0.009 0.001 loadActionHandlers(news): preloaded file "bookmarks/bookmarks.php" for 0.0006 sec
0.009 0.000 loadActionHandlers(news): preloaded file "tags/tags.php" for 9.9999999999999E-5 sec
0.010 0.001 loadActionHandlers(news): preloaded file "comments/comments.php" for 0.0007 sec
0.011 0.000 loadActionHandlers(news:show): preloaded file "same_category_news/same_category_news.php" for 0.0001 sec
0.011 0.000 loadActionHandlers(news:show): preloaded file "varmgr/varmgr.php" for 0 sec
0.026 0.015 exec EXEC_ACTS (news_short)
0.026 0.000 loadActionHandlers(news_short): preloaded file "bb_media/bb_media.php" for 9.9999999999999E-5 sec
0.026 0.001 loadActionHandlers(news_short): preloaded file "downloadcounter/downloadcounter.php" for 0.0005 sec
0.027 0.000 loadActionHandlers(news_short): preloaded file "rating/rating.php" for 0.0001 sec
0.103 0.076 exec EXEC_ACTS (news_short)
0.178 0.075 exec EXEC_ACTS (news_short)
0.202 0.024 exec EXEC_ACTS (index)
0.203 0.000 loadActionHandlers(index): preloaded file "voting/voting.php" for 9.9999999999989E-5 sec
0.203 0.000 loadActionHandlers(index): preloaded file "weather/weather.php" for 0 sec
0.203 0.000 loadActionHandlers(index): CAN't preload file that doesn't exists: "top_news/top_news.php"
0.203 0.001 loadActionHandlers(index): preloaded file "breadcrumbs/breadcrumbs.php" for 0.00049999999999997 sec
0.204 0.000 loadActionHandlers(index): preloaded file "calendar/calendar.php" for 9.9999999999989E-5 sec
0.204 0.000 loadActionHandlers(index): preloaded file "hsgallery/gallery.php" for 0 sec
0.204 0.000 loadActionHandlers(index): preloaded file "lastnews/lastnews.php" for 9.9999999999989E-5 sec
0.204 0.000 loadActionHandlers(index): preloaded file "popular/popular.php" for 0 sec
0.204 0.000 loadActionHandlers(index): preloaded file "who_online/who_online.php" for 0.00010000000000002 sec
0.205 0.001 loadActionHandlers(index): preloaded file "archive/archive.php" for 0.00040000000000001 sec
0.206 0.001 func EXEC_ACTS (index): call function "plugin_switcher_menu" [1 params] for 0.00089999999999998 sec
0.207 0.001 func EXEC_ACTS (index): call function "bookmarks_view" [1 params] for 0.0013 sec
0.208 0.001 func EXEC_ACTS (index): call function "plugin_tags_cloudblock" [1 params] for 0.00080000000000002 sec
0.212 0.005 func EXEC_ACTS (index): call function "plugin_voting" [1 params] for 0.0045 sec
0.213 0.000 func EXEC_ACTS (index): call function "plugin_weather_block" [1 params] for 0.00029999999999999 sec
0.214 0.001 func EXEC_ACTS (index): call function "breadcrumbs" [1 params] for 0.0011 sec
0.214 0.000 func EXEC_ACTS (index): call function "plugin_calendar" [1 params] for 0.00029999999999999 sec
0.216 0.002 func EXEC_ACTS (index): call function "plugin_hsgallery" [1 params] for 0.0015 sec
0.216 0.000 func EXEC_ACTS (index): call function "plugin_lastnews" [1 params] for 0.00029999999999999 sec
0.216 0.000 func EXEC_ACTS (index): call function "plugin_popular" [1 params] for 0.00020000000000001 sec
0.220 0.003 func EXEC_ACTS (index): call function "who_online" [1 params] for 0.0031 sec
0.220 0.000 func EXEC_ACTS (index): call function "plugin_archive" [1 params] for 0.00020000000000001 sec
0.220 0.000 Master activity finished
0.221 0.002 Category menu created
0.222 0.000 exec EXEC_ACTS (usermenu)
0.223 0.001 loadPluginLibrary: pm [lib] for 0 sec
0.223 0.000 loadActionHandlers(usermenu): preloaded file "pm/pm.php" for 0.00059999999999999 sec
0.223 0.000 func EXEC_ACTS (usermenu): call function "new_pm" [1 params] for 0 sec
0.224 0.001 exec EXEC_ACTS (index_post)
0.224 0.000 loadActionHandlers(index_post): CAN't preload file that doesn't exists: "simple_title_ng/simple_title.php"
0.224 0.000 loadActionHandlers(index_post): preloaded file "simple_title_pro/index.php" for 9.9999999999989E-5 sec
0.224 0.000 loadActionHandlers(index_post): preloaded file "ads_sape/ads_sape.php" for 0.00020000000000001 sec
0.224 0.000 func EXEC_ACTS (index_post): call function "simple_title_pro" [1 params] for 0.00010000000000002 sec
0.224 0.000 func EXEC_ACTS (index_post): call function "plugin_ads_sape" [1 params] for 0 sec
0.224 0.000 Templates generation time: 0.022810459136963 (52 times called)
0.224 0.000 Generate DEBUG outputИзменено kolia (2012-01-25 16:24:06)
Чем дальше в лес - тем ближе вылез
Не в сети
#125 2012-01-25 16:29:05
Re: Помогите разобратся что так грузит сайт
1. Отредактировал твое сообщение, зря ты куку админа выложил, погулял как админ у тебя
2. В логах все отлично для такого объема включенных плагинов, запросы выполняются быстро, страница загрузилась за 0.2 секунды. Как проявляется "торможение"?
Не в сети