☰

Strace Apache 进程输出显示等待 24 秒？可能是什么原因？

tag-icon • tag-icon apache-2.2 php strace max-file-descriptors
Strace Apache 进程输出显示等待 24 秒？可能是什么原因？

对其中一个 apache 子进程执行 strace，显示两次系统调用之间间隔 24 秒。我如何确定导致此行为的原因？看起来两个子进程所用的时间几乎相同（24.7 秒和 23.92 秒）。

我们正在使用 apache prefork 和 php5 以及 mysql 5

/var/lib/php5/ 是 php 会话文件夹

apache children1 的 strace 输出，其进程号为 -20539

ps -u www-data | grep apache2 | awk'{print"-p" $1}'| xargs strace -o /strace/strace.log -ff -s4096 -r

20539 0.000064 关闭（13）= 0

20539 0.000060 打开（“/var/lib/php5/sess_9t2cvnvdoruh666rjpm515idm0”，O_RDWR|O_CREAT，0600）= 13

20539 0.000065 羊群（13，LOCK_EX）= 0

20539 24.711888fcntl(13，F_SETFD，FD_CLOEXEC) = 0

20539 0.000056 fstat（13，{st_mode=S_IFREG|0600，st_size=211，...}）= 0

20539 0.000080 lseek (13，0，SEEK_SET) = 0

apache children2 的 strace 输出，其中 processid 为 - 20556

20556 0.000056 关闭（13）= 0

20556 0.000058 打开（“/var/lib/php5/sess_9t2cvnvdoruh666rjpm515idm0”，O_RDWR|O_CREAT，0600）= 13

20556 0.000062 羊群（13，LOCK_EX）= 0

20556 23.928294fcntl(13，F_SETFD，FD_CLOEXEC) = 0

20556 0.000055 fstat（13，{st_mode=S_IFREG|0600，st_size=211，...}）= 0

20556 0.000083 lseek (13，0，SEEK_SET) = 0

文件描述符（13）调用的文件可能有问题吗？我对 strace 输出的理解有什么问题吗？

答案1

在过程中看一下lsof -p <pid>

值得注意的是，您正在寻找 FD 13

即来自我的一个httpd部署。

httpd 10865 apache 13w REG 8,5 1113187 59310687 /var/log/httpd/some_site.log

我会观察获取文件独占锁的争用情况，本例中如 FD 13 所示，如果其他进程正在争用锁，如 fcntl 所示，那么这就可以解释您的长时间等待。

相关内容