У меня возникла эта проблема с момента появления нового сервера centos 7, и около месяца я безуспешно гуглял об этом.
Моя проблема заключалась в том, что ответ apache отправлялся с задержкой около 4 секунд после выполнения сценария php.
Я использую xdebug, чтобы увидеть, что у любого длинного скрипта / функции есть задержка, но нет.
ниже пример моего файла трассировки xdebug.
TRACE START [2019-06-10 08:58:27]
0.0080 295160 -> define('ENVIRONMENT', 'testing') /var/www/html/index.php:64
0.0097 295160 >=> TRUE
0.0097 295160 -> define('HAVE_EMAIL', FALSE) /var/www/html/index.php:68
0.0097 295160 >=> TRUE
0.0098 295160 -> ini_set('display_errors', 0) /var/www/html/index.php:86
0.0098 295576 >=> '1'
.
.
.
1.1370 7960056 -> log_message($level = 'debug', $message = 'Total execution time: 1.1056') /var/www/html/system/core/Output.php:532
1.1370 7960104 -> write_log($level = 'debug', $msg = 'Total execution time: 1.1056') /var/www/html/system/Common.php:478
1.1370 7960152 -> strtoupper('debug') /var/www/html/system/core/Log.php:168
1.1370 7960184 >=> 'DEBUG'
1.1370 7960152 >=> FALSE
1.1370 7960104 >=> NULL
1.1370 7959800 >=> NULL
1.1370 7959800 -> Hooks->call_hook($which = 'post_system') /var/www/html/system/core.php:543
1.1370 7959848 >=> FALSE
1.1370 7959752 >=> 1
1.1371 7901032 -> xdebug_stop_trace() /var/www/html/index.php:298
1.1371 7901080
TRACE END [2019-06-10 08:58:28]
из приведенной выше трассы мы видим, что скрипт начинается с 0,0080 сек и заканчивается на 1,1371 сек. для завершения сценария потребовалось около 1,1 сек.
но в моем журнале доступа к файлу apache был записан:
[06/11/2019:10:32:13 +0800] XXX.XXX.XXX.XXX XXX.XXX.XXX.XXX TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 16972 -ms- 5298889
и четкий ответ отправки Apache через 5298889 мс (5.298сек).
, где 5,2 минус 1,1 = 4,1 с, и мне нужно выяснить, что происходит с этими дополнительными 4 ++ с.
здесь некоторые кадрированные изображения огненных насекомых на временной шкале водопада, которые также показывают общее время 5,3 секунды.
PHP: версия 5.6,
Апач: 2.4.6,
ОС: Centos 7,
Брандмауэр: отключить,
SElinux: отключить
Журнал ошибок Apache: не повезло и / var / log / message: не повезло
Кто-нибудь имел представление, что происходит? и любая помощь будет признательна.
спасибо.
Edit:
Еще одна находка, я пытаюсь открыть и закрыть браузер. Первый запрос после открытия браузера был быстрым, а затем он последовательно занимал 5 сек. (
<here im close and open again browser>
[06/11/2019:15:00:15 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 449531
[06/11/2019:15:00:44 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 5261276
[06/11/2019:15:12:27 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 5210857
[06/11/2019:15:20:33 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 5324744
[06/11/2019:15:22:23 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 5193830
[06/11/2019:15:24:15 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 5298699
[06/11/2019:15:31:05 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 5484622
<here im close and open again browser>
[06/11/2019:15:31:35 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 333542
[06/11/2019:15:31:56 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 5195283
[06/11/2019:15:32:03 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 5275482
[06/11/2019:15:32:11 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 12218 -ms- 5664277
[06/11/2019:15:33:47 +0800] xxx.xxx.xxx.xxx xxx.xxx.xxx.xxx TLSv1.2 DHE-RSA-AES256-GCM-SHA384 "GET report/stat/sh1 HTTP/1.1" 16269 -ms- 5224315