1 Reply Latest reply: Dec 2, 2012 11:03 PM by asahide RSS

    データインポート後にcannot allocate new logが出力し、oracleが不安定

    user1838196
      os:RHLEE5.5
      OracleEE:11.2.0.3

      下記操作を実行したら、drop userが出来なくなりました。
      また、アラートファイルに「Thread 1 cannot allocate new log, sequence 9875
      」が出力しています。
      ログ・スイッチが待ち状態となっているときに発生する認識ですが、回避にはどうすれば
      良いでしょうか。

      ご教示をお願いいたします。

      --------------実施操作------------

      ・インポート実行
      impdp scott/scott@abcde dumpfile=DATA_PUMP_DIR:expdp_abcde_20121202.dmp logfile=DATA_PUMP_DIR:expdp_abcde_20121202.log schemas=scott remap_schema=scott:scott remap_tablespace=users:TS_TBL_S01
      ⇒実行後、15分程度応答なしのため、Ctrl+Cを押下し、コマンドをsqlをキャンセル


      ・インポート再実行
      impdp scott/scott@abcde dumpfile=DATA_PUMP_DIR:expdp_abcde_20121202.dmp logfile=DATA_PUMP_DIR:expdp_abcde_20121202.log schemas=scott remap_schema=scott:scott remap_tablespace=users:TS_TBL_S01
      ⇒20分程度待った後、メッセージが出力
       ⇒~はすでに存在している といった旨のメッセージが大量に出力される。


      ・正常にインポートされたか不明のため、再度ユーザの作成を試みる
      sqlplus / as sysdba
      @/opt/oracle/app/oracle/admin/abcde/scripts/CreateUser_scott.sql
      ⇒drop userの部分で処理が進まない状態となる(2時間放置)

      DBの挙動が不安定なため、この状態で下記の通りインスタンスの再起動を実施した。
      (再起動したからと言って直る根拠はそもそもなし)

      ↑ここまで----------------------------------------------↓alertファイル

      optimizer_use_sql_plan_baselines= FALSE
      control_management_pack_access= "NONE"
      diagnostic_dest = "/oradata/abcde/log"
      →挙動が怪しかったので、再起動しました。結果的には変わらず。
      MMNL started with pid=16, OS id=21120
      ORACLE_BASE from environment = /opt/oracle/app/oracle
      Sun Dec 02 22:12:39 2012
      ALTER DATABASE MOUNT
      Successful mount of redo thread 1, with mount id 196131735
      Database mounted in Exclusive Mode
      Lost write protection disabled
      Completed: ALTER DATABASE MOUNT
      Sun Dec 02 22:12:43 2012
      ALTER DATABASE OPEN
      Thread 1 advanced to log sequence 9868 (thread open)
      Thread 1 opened at log sequence 9868
      Current log# 3 seq# 9868 mem# 0: /oradata/abcde/redo/online01/redo03A.dbf
      Current log# 3 seq# 9868 mem# 1: /oradata/abcde/redo/online02/redo03B.dbf
      Successful open of redo thread 1
      SMON: enabling cache recovery
      Successfully onlined Undo Tablespace 2.
      Verifying file header compatibility for 11g tablespace encryption..
      Verifying 11g file header compatibility for tablespace encryption completed
      SMON: enabling tx recovery
      Database Characterset is AL32UTF8
      No Resource Manager plan active
      replication_dependency_tracking turned off (no async multimaster replication found)
      Starting background process QMNC
      Sun Dec 02 22:12:47 2012
      QMNC started with pid=18, OS id=21155
      Completed: ALTER DATABASE OPEN
      Starting background process CJQ0
      Sun Dec 02 22:12:48 2012
      CJQ0 started with pid=19, OS id=21167
      Setting Resource Manager plan SCHEDULER[0x3009]:DEFAULT_MAINTENANCE_PLAN via scheduler window
      Setting Resource Manager plan DEFAULT_MAINTENANCE_PLAN via parameter
      Sun Dec 02 22:12:52 2012
      Starting background process VKRM
      Sun Dec 02 22:12:52 2012
      VKRM started with pid=20, OS id=21169
      Sun Dec 02 22:16:56 2012
      Thread 1 advanced to log sequence 9869 (LGWR switch)
      Current log# 4 seq# 9869 mem# 0: /oradata/abcde/redo/online01/redo04A.dbf
      Current log# 4 seq# 9869 mem# 1: /oradata/abcde/redo/online02/redo04B.dbf
      Sun Dec 02 22:17:47 2012
      Starting background process SMCO
      Sun Dec 02 22:17:47 2012
      SMCO started with pid=26, OS id=21194
      Sun Dec 02 22:20:31 2012
      Thread 1 advanced to log sequence 9870 (LGWR switch)
      Current log# 5 seq# 9870 mem# 0: /oradata/abcde/redo/online01/redo05A.dbf
      Current log# 5 seq# 9870 mem# 1: /oradata/abcde/redo/online02/redo05B.dbf
      Mon Dec 03 00:12:52 2012
      Thread 1 advanced to log sequence 9871 (LGWR switch)
      Current log# 1 seq# 9871 mem# 0: /oradata/abcde/redo/online01/redo01A.dbf
      Current log# 1 seq# 9871 mem# 1: /oradata/abcde/redo/online02/redo01B.dbf
      Thread 1 advanced to log sequence 9872 (LGWR switch)
      Current log# 2 seq# 9872 mem# 0: /oradata/abcde/redo/online01/redo02A.dbf
      Current log# 2 seq# 9872 mem# 1: /oradata/abcde/redo/online02/redo02B.dbf
      Thread 1 advanced to log sequence 9873 (LGWR switch)
      Current log# 3 seq# 9873 mem# 0: /oradata/abcde/redo/online01/redo03A.dbf
      Current log# 3 seq# 9873 mem# 1: /oradata/abcde/redo/online02/redo03B.dbf
      Thread 1 advanced to log sequence 9874 (LGWR switch)
      Current log# 4 seq# 9874 mem# 0: /oradata/abcde/redo/online01/redo04A.dbf
      Current log# 4 seq# 9874 mem# 1: /oradata/abcde/redo/online02/redo04B.dbf
      Thread 1 cannot allocate new log, sequence 9875
      →この後も断続的に本メッセージが出力している。

      Edited by: user1838196 on 2012/12/02 20:44
        • 1. no
          asahide
          11gなので違うかもしれませんが、LOG_ARCHIVE_STARTのパラメータが設定されていたらはずしてみてはどうでしょうか。
          アーカイブがされずに止まっているように見受けられます。
          もしくはアーカイブの領域がいっぱいとか?