../

CentOS Stream 8でPHP7.4からPDOでMySQL8.0に接続する際に「Packets out of order. Expected 1 received 0」のワーニングが出る時がある。毎回確実に出る訳でもなく、どのような状況の時に出るのかは判明できていない。心当たりのある原因を後述しておく。

PHP message: @@ enter getUsers('admin',false)
PHP message: @@ new UserManager()
PHP message: @@ new UserDataAccessor()
PHP message: @@ PersistenceBase::__construct()
PHP message: @@ PersistenceBase::pdo = null
PHP message: 
PHP Warning:  Packets out of order. Expected 1 received 0. Packet size=145 
              in common/integration/PersistenceBase.php on line 46
              
PHP message: @@@ connected mysql:dbname=test;host=localhost;port=3306;charset=utf8
PHP message: @@@ select * from user order by user_id --> 6

数日前は何度か起きていたが、最近はまったく起きていない。

上記の場合、PDOに接続でき、ユーザー情報の検索はできている。PDOに接続に行く際に「Packets out of order. Expected 1 received 0」のWarningが出たと言っている。「パケットの順序がおかしい。1でないといけないのに0になっている」と言っている。Warningだから無視していいのか、解決すべきなのかも分からず。

  private function pdo(): PDO {
  
    error_log('@@ PersistenceBase::pdo = ' . (is_null($this->pdo)? 'null' : 'available'));
    
    if (empty($this->pdo)){
    
      $dsn = $this->props['db.dsn'];
      $user = $this->props['db.user'];
      $password = $this->props['db.password'];
      $options = [
          PDO::ATTR_ERRMODE => PDO::ERRMODE_EXCEPTION,
          PDO::ATTR_DEFAULT_FETCH_MODE => PDO::FETCH_ASSOC,
          // PDO::ATTR_EMULATE_PREPARES, false,
          PDO::ATTR_PERSISTENT => true
      ];
      try {
        
        $this->pdo = new PDO($dsn, $user, $password, $options);
        error_log("@@@ connected " . $dsn);
        
      } catch (PDOException $e) {
        
        error_log("@@@ dsn = " . $dsn);
        error_log("@@@ user = " . $user);
        error_log("@@@ password = " . $password);
        error_log("@@@ connect failed: " . $e->getMessage());
        error_log($e->getTraceAsString());
      }
    }
    return $this->pdo;
  }

ネット情報を見てもよく分からず。再現するようになったら、そのとき再度、調べたい。関連しそうなログは、以下のもの。

$ tail -200 /var/log/php_errors.log
$ tail -200 /var/log/httpd/error_log
$ tail -200 /var/log/mysql/mysqld.log
$ tail -200 /var/log/nginx/error.log
$ tail -200 /var/log/php-fpm/error.log

原因は、MySQLの wait_timeout が8時間の問題だったかも

確認はしていないのだけど、原因は、MySQLの wait_timeout の設定だったかもしれない。MySQLは、デフォルトでは8時間アクセスがないとタイムアウトする。例えば、前日にはうまく行っていても、翌朝にはMySQLを再起動しないといけないときが、Javaでもあった。今回の環境でも、wait_timeoutを設定しないまま使っていたためかもしれない。

以下のように、wait_timeout に31536000をセットしたから起きなくなったのかな。31536000 = 365 * 24 * 3600 である。

$ vi /etc/my.cnf.d/mysql-server.cnf

[mysqld]
character-set-server=utf8
lower_case_table_names=1

interactive_timeout=31536000
wait_timeout=31536000

PHPのシステムロガー error_log() の使い方

ついでなので、PHPのシステムロガーの使い方を整理しておく。呼び出しには、汎用のerror_log()を使う。

error_log(string $message, int $message_type = 0, ?string $destination = null): bool

message_type=0のとき、message がPHPのシステムロガーに送られ、error_log の値に応じて、OSのシステムログ機構を使って保存されるか、ファイルに保存されるかが決まる。デフォルトのオプション。
message_type=3のとき、message は destination で指定されたファイルに追加される。messageの末尾に改行文字を付与した方がよい。

PHPのシステムロガーの設定は、php.ini で行う。

$ vi /etc/php.ini

; 出力するエラーのレベル 
;error_reporting = E_ALL & ~E_DEPRECATED & ~E_STRICT
error_reporting = E_ALL

; エラーを表示するか。どこに表示するか。{ On,Off,stderr,stdout }
display_errors = On

; 起動時のエラーを表示するか。
display_startup_errors = On

; ログを出力するか。stderrやerror_logで指定される場所に出力される。
log_errors = On

; ログの出力先ファイル
error_log = /var/log/php_errors.log

; ログの出力先をsyslogに委ねる場合
;error_log = syslog
;syslog.ident = php
;syslog.facility = user

$ systemctl restart httpd
$ systemctl restart nginx

以前は、error_reporting = E_ALL | E_STRICT と書いていたらしい。PHP7.4では error_reporting = E_ALL でいい。E_STRICTなど細かいものを除外するときは、error_reporting = E_ALL & ~E_DEPRECATED & ~E_STRICT と書くそうだ。

../