断続的な autofs nfs マウントに失敗する

Issue

最近、ワークステーションが RHEL 5.5 から 6.1 にアップグレードされました。以下の動作は RHEL 5.5 では観察されませんでした。

cron ジョブが 10 分ごとに実行されます。69 の nfs マウント可能なファイルシステムが利用可能であることをテストします。最初に、適切な nfs ホスト IP アドレスが ping に応答することを確認します。応答する場合は、特定のファイルにアクセスできるかどうかがチェックされます。

1 つまたは複数のファイルシステムがこのテストに失敗することがありますが、次のテスト (10 分後) は成功します。失敗には、異なるファイルシステムと異なる nfs サーバーが関係していることに注意してください。(すべてのサーバーは nfs v3 を提供し、RHEL 5.7 を実行します。)

失敗時にログインしていると、マウントはファイルシステムがマウントされていないことを確認します。したがって、自動マウントが
ファイルシステムのマウントに失敗していることを疑います。

失敗は、6.1 で提供される /etc/sysconfig/autofs のバージョンで発生します。以下にオプションを示します。

  MOUNT_WAIT=180
  BROWSE_MODE="yes"
  MOUNT_NFS_DEFAULT_PROTOCOL=3
  LOGGING="debug"
  OPTIONS="-d"

1 つの例を見ると参考になるかもしれません。/disk/pemccd としてクライアントに知られているファイルシステムのテストは、1 月 15 日 15:20 に失敗しました。

自動マウント情報は、以下のようになります。

# ypcat -k auto.master | grep ^/disk
/disk auto.resource

# ypcat -k auto.resource | grep ^pemccd
pemccd -nfsvers=3,tcp,rw,noatime,nodiratime,nosuid,_netfs,nodev,fsc,rsize=1048576,wsize=1048576,acregmin=10,acregmax=120,acdirmax=120,timeo=600,retry=1,lock,hard,intr  server01:/stage/pemccd

cron ジョブは 15:20 に /disk/pemccd a へのアクセスに失敗しました。失敗した前後の /var/log/messages の一部と、sosreport を近々添付する予定です。前者は、自動マウントが実際に 15:20 にマウントを試みていることを示しています。

SERVER からの /var/log/messages の抽出 (grep コマンドを使用してクライアントとファイルシステム名を抽出)。15:20 のマウント試行はサーバーによって記録されないことに注意してください (15:23 のマウントは、筆者による追加の手動テスト)。

Jan 15 14:30:02 client01 mountd[26510]: authenticated mount request from server01:974 for /stage/pemccd (/stage/pemccd)
Jan 15 14:35:29 client01 mountd[26504]: authenticated unmount request from server01:844 for /stage/pemccd (/stage/pemccd)
Jan 15 14:40:04 client01 mountd[26541]: authenticated mount request from server01:765 for /stage/pemccd (/stage/pemccd)
Jan 15 14:45:44 client01 mountd[26544]: authenticated unmount request from server01:798 for /stage/pemccd (/stage/pemccd)
Jan 15 14:50:02 client01 mountd[26489]: authenticated mount request from server01:735 for /stage/pemccd (/stage/pemccd)
Jan 15 14:55:59 client01 mountd[26539]: authenticated unmount request from server01:902 for /stage/pemccd (/stage/pemccd)
Jan 15 15:00:02 client01 mountd[26502]: authenticated mount request from server01:670 for /stage/pemccd (/stage/pemccd)
Jan 15 15:06:12 client01 mountd[26486]: authenticated unmount request from server01:1015 for /stage/pemccd (/stage/pemccd)
Jan 15 15:10:02 client01 mountd[26508]: authenticated mount request from server01:902 for /stage/pemccd (/stage/pemccd)
Jan 15 15:15:08 client01 mountd[26523]: authenticated unmount request from server01:884 for /stage/pemccd (/stage/pemccd)
Jan 15 15:23:23 client01 mountd[26485]: authenticated mount request from server01:962 for /stage/pemccd (/stage/pemccd)
Jan 15 15:30:01 client01 mountd[26543]: authenticated mount request from server01:679 for /stage/pemccd (/stage/pemccd)
Jan 15 15:35:33 client01 mountd[26532]: authenticated unmount request from server01:868 for /stage/pemccd (/stage/pemccd)
Jan 15 15:40:02 client01 mountd[26505]: authenticated mount request from server01:778 for /stage/pemccd (/stage/pemccd)
Jan 15 15:45:44 client01 mountd[26479]: authenticated unmount request from server01:830 for /stage/pemccd (/stage/pemccd)