同事在优化页面加载慢的问题中将数据分离为2个接口来分别异步加载,但是实际的情况是接口1一直在查询,接口2也一直在查询,接口2一定要等接口1查询完成才能返回结果。一直以为fast-cgi进程数问题,反复设置无效,通过getmypid就能看到每次相应的进程id不同。正好我有空就想到通过register_tick_function来判断到底是卡在哪一行,一旦注册register_tick_function,php执行一行代码都会调用注册的处理函数,通过计算上一次代码执行时间和本次执行时间即可定位位置。
<?php /** * Class Tracker */ class Tracker { /** * 执行代码时间(单位毫秒) * @var int */ protected $eval_code_time = 0; /** * 执行代码过慢时间(单位毫秒) * @var int */ protected $slow_code_time = 200; /** * 获取当前毫秒时间戳 * millisecondTime * @return float|int */ protected function millisecondTime() { return microtime(true) * 1000; } /** * setSlowCodeTime * @param $time * @return Tracker * @throws Exception */ public function setSlowCodeTime($time) { if ($time <= 0) { throw new \Exception('执行代码过慢时间必须大于0'); } $this->slow_code_time = $time; return $this; } /** * startCollect * @throws Exception */ public function startCollect() { declare (ticks = 1); if (!register_tick_function([$this, 'handle'], true)) { throw new \Exception('注册tracker处理函数失败'); } } /** * handle */ public function handle() { $millisecondTime = $this->millisecondTime(); if (!$this->eval_code_time) { $this->eval_code_time = $millisecondTime; } if (($millisecondTime - $this->eval_code_time) > $this->slow_code_time) { $e = new Exception(); var_dump($e->getTrace());die(); } else { $this->eval_code_time = $millisecondTime; } } } //查询执行超过1秒的代码位置 $object = new Tracker(); $object->setSlowCodeTime(1)->startCollect(); sleep(1);
最终定位到的问题是同事本机开发环境session是保存在文件中的,存在session锁的问题,上面的简单粘贴下代码。
可能存在的问题,如果代码执行直接卡死进程关闭是无法定位到的,由于是windows,否则可以开启fpm慢日志即可快速定位。