Оптимизация PHP: профилирование как основа

Расширенные примеры профилирования

Пример 1. Детальный анализ cachegrind файла

После профилирования Xdebug файл cachegrind.out содержит записи в формате:

# /tmp/profiling/cachegrind.out.12345
version: 1
creator: xdebug 3.2.0
cmd: /var/www/index.php
part: 1

events: Time

fl=php:internal
fn=php::count
5 20

fl=/var/www/app/helpers.php
fn=App\Helpers\formatPrice
10 200
1 30

Расшифровка: для каждой функции указывается количество вызовов и затраченное время (в микросекундах). Первая строка после fn= – это число вызовов, вторая – общее время (включая дочерние).

Пример визуализации в QCacheGrind:

• Self Cost – время, затраченное непосредственно в теле функции (без учёта вызовов).
• Inclusive Cost – общее время с учетом всех вложенных вызовов.
• Calls Count – количество вызовов функции.

Самые узкие места обычно находятся в функциях с высоким Self Cost и большим количеством вызовов (например, многократные повторные запросы к БД).

Пример 2. Ручное профилирование с микроразметкой и выводом в лог

function profile(string $label, callable $fn): mixed {
    $start = microtime(true);
    $result = $fn();
    $elapsed = (microtime(true) - $start) * 1000;
    error_log("PROFILER: {$label} = {$elapsed} ms");
    return $result;
}

// Использование
$data = profile('fetch_users', function () use ($db) {
    return $db->query('SELECT * FROM users WHERE active = 1')->fetchAll();
});

Результат в логе:

[2025-03-25 12:34:56] PROFILER: fetch_users = 12.3456 ms

Пример 3. XHProf с сохранением в БД и построением графиков

Установите XHGui (веб-интерфейс для XHProf/Tideways):

git clone https://github.com/perftools/xhgui.git
cd xhgui
composer install
cp config/default.php config/config.php
# настройте подключение к MongoDB (XHGui использует MongoDB для хранения)

Включите профилирование в точке входа (например, в index.php):

<?php
// Autoload
require 'path/to/xhgui/vendor/autoload.php';

use XHGui\Profiler\Profiler;
use XHGui\Saver\MongoSaver;

$saver = new MongoSaver('mongodb://localhost:27017/xhgui');
$profiler = new Profiler($saver);
$profiler->start();

// … ваш код …

$profiler->stop();
?>

Перейдите в браузере на http://your-xhgui-install/ для просмотра собранных профилей. Можно сортировать по времени, памяти, количеству вызовов.

Пример 4. Blackfire с автоматической профилировкой тестов в CI

Используйте Blackfire в GitLab CI:

# .gitlab-ci.yml
blackfire:
  stage: test
  script:
    - blackfire run php vendor/bin/phpunit
    - blackfire --output blackfire_report.json run php -r 'echo "done";'
  artifacts:
    paths:
      - blackfire_report.json

Отчеты можно сохранять и сравнивать между коммитами, выявляя регрессии производительности.

Пример 5. Использование Xdebug без QCacheGrind – анализ через текстовый вывод

Xdebug может генерировать не только cachegrind, но и человекочитаемый трейс (xdebug.mode = trace). Режим trace покажет последовательность вызовов функций с временными метками:

xdebug.mode = trace
xdebug.trace_output_name = trace.%p
xdebug.trace_output_dir = /tmp

Пример содержимого файла trace.12345.xt:

TRACE START [2025-03-25 12:34:56.789]
    0.0010    123456   -> {main}() /var/www/index.php:0
    0.0015    124000     -> require_once() /var/www/index.php:10
    0.0020    125000       -> App\Router::route() /var/www/router.php:25
    0.0050    130000         -> App\Controller::index() /var/www/router.php:30
    0.0080    135000           -> App\Model::fetchData() /var/www/Controller.php:50
    0.0120    140000             -> PDO::query() /var/www/Model.php:30
TRACE END   [2025-03-25 12:34:56.812]

Первая колонка – время от начала, вторая – потребление памяти в байтах, затем сама функция и файл с номером строки.

Этот режим подходит для поиска неожиданных вызовов функций или проверки последовательности, но даёт меньше статистики по каждому вызову, чем профилирование.