[pgsql-jp: 24951] Resource temporarily unavailable/postgresql7.1.3/Solaris

[Uehara, Sadaharu]

はじめまして。ＫＥＫの上原と申します。

　最近、postgresql6.3.2から7.1.3へupgradeしてテストしていますが、
以前には出なかったようなエラーが出ます。原因がわかりましたら
お知らせ下さりますようお願いいたします。

　
　このエラーはときどき（偶発的に）出ます。厳密な再現性はありません。
backupまたはrestore時に主にでます。これで、Clientがエラーで停止して
しまいます。サーバは停止しません。backup/restoreは、6.3.2の時に独自に作った　
Perl script でやっていますが、blobの扱い以外の部分は、基本的に、pg_dump　
と同じようなことをやっています。

　プラットホームは、Solaris です。Clientも同じ計算機上にあります。
　postmasterの起動時に、-B 256 -o '-S 1024' を指定しています。
オプション　-o '-S 1024'　の有無に関わらずこのエラーは起こります。


　クライアント側でのエラーメッセージは、以下の通りです。

　　The Postmaster has informed me that some other backend  died abnormally and possibly
corrupted shared memory.
        I have rolled back the current transaction and am       going to terminate your
database system connection and exit.
        Please reconnect to the database system and repeat your query.
psql: pqReadData() -- backend closed the channel unexpectedly.
        This probably means the backend terminated abnormally
        before or while processing the request.
cannot vacuum 'cdc_daqom', 512


　上の場合は、restore時　の　vacuumの付近で出たもののようです。

エラーの出るところやメッセージの細かい内容は一定していませんが、サーバ側に現れる
エラーメッセージ（下にある）

"Resource temporarily unavailable"

は共通しています。このメッセージがクライアント側にも現れる場合もあります。



これに対応するサーバー側のエラーログは、以下の通りです。


DEBUG:  Pages 10: Changed 6, reaped 5, Empty 0, New 0; Tup 280: Vac 280, Keep/VTL 0/0,
Crash 0, UnUsed 0, MinLen 128, MaxLen 128; Re-using: Free/Avail. Space 36440/36440;
EndEmpty/Avail. Pages 0/5. CPU 0.00s/0.00u sec.
DEBUG:  Index pnt_pkey: Pages 4; Tuples 280: Deleted 280. CPU 0.01s/0.01u sec.
DEBUG:  Index pnt_idx: Pages 6; Tuples 280: Deleted 280. CPU 0.00s/0.01u sec.
DEBUG:  Rel pnt: Pages: 10 --> 5; Tuple(s) moved: 255. CPU 0.00s/0.00u sec.
DEBUG:  Index pnt_pkey: Pages 4; Tuples 280: Deleted 255. CPU 0.00s/0.00u sec.
DEBUG:  Index pnt_idx: Pages 6; Tuples 280: Deleted 255. CPU 0.00s/0.01u sec.
DEBUG:  --Relation pg_toast_453790--
DEBUG:  Pages 0: Changed 0, reaped 0, Empty 0, New 0; Tup 0: Vac 0, Keep/VTL 0/0, Crash 0,
UnUsed 0, MinLen 0, MaxLen 0; Re-using: Free/Avail. Space 0/0; EndEmpty/Avail. Pages 0/0.
CPU 0.00s/0.00u sec.
DEBUG:  Index pg_toast_453790_idx: Pages 1; Tuples 0. CPU 0.00s/0.00u sec.
DEBUG:  XLogWrite: new log file created - consider increasing WAL_FILES
DEBUG:  XLogWrite: new log file created - consider increasing WAL_FILES
DEBUG:  XLogWrite: new log file created - consider increasing WAL_FILES
DEBUG:  MoveOfflineLogs: remove 0000000000000016
DEBUG:  MoveOfflineLogs: remove 0000000000000017
DEBUG:  MoveOfflineLogs: remove 0000000000000015
DEBUG:  XLogWrite: new log file created - consider increasing WAL_FILES
DEBUG:  XLogWrite: new log file created - consider increasing WAL_FILES
DEBUG:  XLogWrite: new log file created - consider increasing WAL_FILES
DEBUG:  mdblindwrt: close() failed: Resource temporarily unavailable
FATAL 2:  cannot write block 11026 of 454719/16948 blind: Resource temporarily unavailable
Server process (pid 8320) exited with status 512 at Thu Feb 21 11:11:13 2002
Terminating any active server processes...
NOTICE:  Message from PostgreSQL backend:
        The Postmaster has informed me that some other backend  died abnormally and
possibly corrupted shared memory.
        I have rolled back the current transaction and am       going to terminate your
database system connection and exit.
        Please reconnect to the database system and repeat your query.
Server processes were terminated at Thu Feb 21 11:11:13 2002
Reinitializing shared memory and semaphores
DEBUG:  database system was interrupted at 2002-02-21 11:11:10 JST
DEBUG:  CheckPoint record at (0, 456885768)
DEBUG:  Redo record at (0, 452988376); Undo record at (0, 452988040); Shutdown FALSE

　どうぞよろしくお願い申し上げます。


　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　上原　貞治
　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　uehara ＠ post.kek.jp
　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　高エネルギー加速器研究機構
　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　素粒子原子核研究所
　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　　Belle実験グループ