我的Apache服务器需要很长时间来处理请求。 我附上strace它可以看到下面两个延迟:
1)非常关键(143秒处理)
1335 0.000037 write(16, "\235\0\0\0\3INSERT INTO `br_anonymous_user_tokens` (`dtExpires`, `nmToken`, `dtCreated`) VALUES ('2014-08-25', '46e35dc39a41e836b806f48d21621b066ea182a9', '2014-06-25')", 161) = 161 1335 0.000111 read(16, "\t\0\0\1\0\1\374\262\n\2\0\0\0", 16384) = 13 1335 143.588134 gettimeofday({1403675497, 653337}, NULL) = 0
文件描述符#16似乎是mysql套接字:
line from strace 1335 0.000328 socket(PF_LOCAL, SOCK_STREAM, 0) = 16
和这里
pidof mysqld 15393 lsof -p 15393 mysqld 15393 mysql 12u IPv4 26913133 0t0 TCP *:mysql (LISTEN)
所以似乎Apache正在等待mysql执行写入前一行套接字的查询。 我对吗? 这是否意味着我需要了解为什么MySQL需要这么长时间才能执行简单的查询?
2)很长
1335 0.000040 poll([{fd=14, events=POLLIN}], 1, 5000) = 0 (Timeout) 1335 5.005295 gettimeofday({1403675502, 686212}, NULL) = 0
在这里我试图find文件描述符#14找出超时来自哪里。 我使用了这里描述的技术,但是没有一个显示出描述符。 我怎样才能找出超时的来源?
问题已解决。 我查看了information_schema MySQL数据库中的PROCESSLIST表,结果发现有些表被locking, Waiting for table level lock 。 于是我search了一下,发现锁的一个原因可能是mysqldump备份 – 这正是我最近configuration的。 但是由于工作configuration不当,每分钟都会运行一次,不断lockingMySQL。 现在备份configuration正确,服务器工作正常。
然而, poll的第二个问题仍然没有解决。