RAC dead lock 的問題

[复制链接]
查看11 | 回复1 | 2005-10-30 17:05:33 | 显示全部楼层 |阅读模式
生產用的數據庫是9.2.0.7,
昨天下午發生數據庫故障,alert log中有以下的error:
========================================
Mon Sep1 14:58:51 2008
ARC8: Evaluating archive log 2 thread 1 sequence 185306
Current log# 3 seq# 185307 mem# 1: /oradata/sys1/REDOLOGS/REDO03A.LOG
ARC8: Beginning to archive log 2 thread 1 sequence 185306
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185306.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185306.ARC'
ARC8: Completed archivinglog 2 thread 1 sequence 185306
Mon Sep1 15:08:53 2008
Thread 1 advanced to log sequence 185308
Current log# 1 seq# 185308 mem# 0: /oradata/sys2/REDOLOGS/REDO01.LOG
Current log# 1 seq# 185308 mem# 1: /oradata/sys1/REDOLOGS/REDO01A.LOG
Mon Sep1 15:08:53 2008
ARC3: Evaluating archive log 3 thread 1 sequence 185307
ARC3: Beginning to archive log 3 thread 1 sequence 185307
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185307.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185307.ARC'
ARC3: Completed archivinglog 3 thread 1 sequence 185307
Mon Sep1 15:18:53 2008
Thread 1 advanced to log sequence 185309
Current log# 2 seq# 185309 mem# 0: /oradata/sys2/REDOLOGS/REDO02.LOG
Current log# 2 seq# 185309 mem# 1: /oradata/sys1/REDOLOGS/REDO02A.LOG
Mon Sep1 15:18:53 2008
ARC7: Evaluating archive log 1 thread 1 sequence 185308
ARC7: Beginning to archive log 1 thread 1 sequence 185308
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185308.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185308.ARC'
ARC7: Completed archivinglog 1 thread 1 sequence 185308
Mon Sep1 15:28:52 2008
Thread 1 advanced to log sequence 185310
Mon Sep1 15:28:52 2008
ARC2: Evaluating archive log 2 thread 1 sequence 185309
Mon Sep1 15:28:52 2008
Current log# 3 seq# 185310 mem# 0: /oradata/sys2/REDOLOGS/REDO03.LOG
Current log# 3 seq# 185310 mem# 1: /oradata/sys1/REDOLOGS/REDO03A.LOG
Mon Sep1 15:28:52 2008
ARC2: Beginning to archive log 2 thread 1 sequence 185309
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185309.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185309.ARC'
ARC2: Completed archivinglog 2 thread 1 sequence 185309
Mon Sep1 15:38:52 2008
Thread 1 advanced to log sequence 185311
Current log# 1 seq# 185311 mem# 0: /oradata/sys2/REDOLOGS/REDO01.LOG
Current log# 1 seq# 185311 mem# 1: /oradata/sys1/REDOLOGS/REDO01A.LOG
Mon Sep1 15:38:52 2008
ARC6: Evaluating archive log 3 thread 1 sequence 185310
ARC6: Beginning to archive log 3 thread 1 sequence 185310
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185310.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185310.ARC'
ARC6: Completed archivinglog 3 thread 1 sequence 185310
Mon Sep1 15:48:49 2008
Shutting down instance: further logons disabled
Mon Sep1 15:48:51 2008
Thread 1 advanced to log sequence 185312
Current log# 2 seq# 185312 mem# 0: /oradata/sys2/REDOLOGS/REDO02.LOG
Current log# 2 seq# 185312 mem# 1: /oradata/sys1/REDOLOGS/REDO02A.LOG
Mon Sep1 15:48:52 2008
ARC0: Evaluating archive log 1 thread 1 sequence 185311
ARC0: Beginning to archive log 1 thread 1 sequence 185311
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185311.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185311.ARC'
ARC0: Completed archivinglog 1 thread 1 sequence 185311
Mon Sep1 15:49:10 2008
Errors in file /oracle/admin/NTUH/udump/ntuh1_ora_24134.trc:
ORA-00604: error occurred at recursive SQL level 1
ORA-04092: cannot COMMIT in a trigger
ORA-06512: at "PERFSTAT.STATSPACK", line 2676
ORA-06512: at "PERFSTAT.STATSPACK", line 91
ORA-06512: at line 2
Mon Sep1 15:49:10 2008
Shutting down instance (immediate)
License high water mark = 764
Mon Sep1 15:49:19 2008
ALTER DATABASE CLOSE NORMAL
Mon Sep1 15:49:19 2008
SMON: disabling tx recovery

SMON: disabling cache recovery
Mon Sep1 15:49:21 2008
Shutting down archive processes
Archiving is disabled
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARC2: Archival stopped
Mon Sep1 15:49:21 2008
ARCH shutting down
Mon Sep1 15:49:21 2008
ARC1: Archival stopped
Mon Sep1 15:49:21 2008
ARC4: Archival stopped
Mon Sep1 15:49:21 2008
ARC6: Archival stopped
Mon Sep1 15:49:21 2008
ARC5: Archival stopped
Mon Sep1 15:49:21 2008
ARC3: Archival stopped
Mon Sep1 15:49:21 2008
ARC0: Archival stopped
Mon Sep1 15:49:21 2008
ARC8: Archival stopped
Mon Sep1 15:49:21 2008
ARC7: Archival stopped
Mon Sep1 15:49:21 2008
ARC9: Archival stopped
Mon Sep1 15:49:21 2008
Thread 1 closed at log sequence 185312
Successful close of redo thread 1
Mon Sep1 15:50:23 2008
Completed: ALTER DATABASE CLOSE NORMAL
Mon Sep1 15:50:23 2008
ALTER DATABASE DISMOUNT
Completed: ALTER DATABASE DISMOUNT
ARCH: Archiving is disabled
Shutting down archive processes
Archiving is disabled
Archive process shutdown avoided: 0 active
ARCH: Archiving is disabled
Shutting down archive processes
Archiving is disabled
Archive process shutdown avoided: 0 active
Mon Sep1 15:52:41 2008
Starting ORACLE instance (force)
Mon Sep1 15:52:41 2008
Global Enqueue Service Resources = 52542, pool = 16
Mon Sep1 15:52:41 2008
Global Enqueue Service Enqueues = 81000
Mon Sep1 15:52:41 2008
WARNING: EINVAL creating segment of size 0x00000002e9800000
fix shm parameters in /etc/system or equivalent
Mon Sep1 15:53:00 2008
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0
Mon Sep1 15:53:21 2008
SCN scheme 3
Using log_archive_dest parameter default value
Oracle instance running with ODM: VERITAS 4.0 ODM Library, Version 1.1
LICENSE_MAX_USERS = 0
SYS auditing is disabled
Starting up ORACLE RDBMS Version: 9.2.0.7.0.
System parameters with non-default values:
processes
= 1500
sessions
= 3000
timed_statistics = TRUE
shared_pool_size = 2147483648
large_pool_size
= 134217728
java_pool_size = 67108864
resource_manager_plan= SYSTEM_PLAN
control_files
= /oradata/sys1/CONTROL/CONTROL01, /oradata/sys2/CONTROL/CONTROL02, /oradata/sys3/CONTROL/CONTROL03
db_block_size
= 8192
db_writer_processes= 10
db_cache_size
= 9663676416
max_commit_propagation_delay= 0
compatible
= 9.2.0.1
log_archive_start= TRUE
log_archive_dest_1 = LOCATION=/oradata/arch
log_archive_dest_2 = LOCATION=/oradata/archbcv OPTIONAL
log_archive_max_processes= 10
log_archive_format = %d_%t_%s.ARC
log_buffer
= 5242880
archive_lag_target = 600
cluster_database = TRUE
cluster_database_instances= 2
thread
= 1
fast_start_mttr_target = 300
instance_number
= 1
undo_management
= AUTO
undo_tablespace
= UNDOTBS1
undo_retention = 1500
remote_login_passwordfile= EXCLUSIVE
db_domain
=
instance_name
= NTUH1
service_names
= NTUH
session_cached_cursors = 200
utl_file_dir
= /oradata/arch/utl_dir
job_queue_processes= 10
cursor_sharing = similar
background_dump_dest = /oracle/admin/NTUH/bdump
user_dump_dest = /oracle/admin/NTUH/udump
core_dump_dest = /oracle/admin/NTUH/cdump
audit_trail
= TRUE
sort_area_size = 1572864
db_name
= NTUH
open_cursors
= 500
Mon Sep1 15:53:27 2008
cluster interconnect IPC version:
VERITAS IPC 4.0MP2 11:21:09 Jun7 2005
IPC Vendor 86 proto 76 Version 1.0
PMON started with pid=2
DIAG started with pid=3
LMON started with pid=4
LMD0 started with pid=5
LMS0 started with pid=6
LMS1 started with pid=7
LMS2 started with pid=8
LMS3 started with pid=9
DBW0 started with pid=10
DBW1 started with pid=11
DBW2 started with pid=12
DBW3 started with pid=13
DBW4 started with pid=14
DBW5 started with pid=15
DBW6 started with pid=16
DBW7 started with pid=17
DBW8 started with pid=18
DBW9 started with pid=19
LGWR started with pid=20
CKPT started with pid=21
SMON started with pid=22
RECO started with pid=23
CJQ0 started with pid=24
Mon Sep1 15:53:32 2008
ARCH: STARTING ARCH PROCESSES
ARC0 started with pid=25
ARC0: Archival started
ARC1 started with pid=26
ARC1: Archival started
ARC2 started with pid=27
ARC2: Archival started
ARC3 started with pid=28
ARC3: Archival started
ARC4 started with pid=29
ARC4: Archival started
ARC5 started with pid=30
ARC5: Archival started
ARC6 started with pid=31
ARC6: Archival started
ARC7 started with pid=32
ARC7: Archival started
ARC8 started with pid=33
ARC8: Archival started
ARC9 started with pid=34
Mon Sep1 15:53:33 2008
ARCH: STARTING ARCH PROCESSES COMPLETE
ARC9: Archival started
Mon Sep1 15:53:33 2008
ARC0: Becoming the 'no FAL' ARCH
ARC0: Becoming the 'no FAL' ARCHARC0: Thread not mounted
Mon Sep1 15:53:33 2008
ARC1: Becoming the heartbeat ARCH
ARC1: Becoming the heartbeat ARCHARC1: Thread not mounted
Mon Sep1 15:53:33 2008
ARC5: Thread not mounted
Mon Sep1 15:53:33 2008
ARC6: Thread not mounted
Mon Sep1 15:53:33 2008
ARC3: Thread not mounted
Mon Sep1 15:53:33 2008
ARC8: Thread not mounted
Mon Sep1 15:53:33 2008
ARC9: Thread not mounted
Mon Sep1 15:53:33 2008
ARC2: Thread not mounted
Mon Sep1 15:53:33 2008
ARC4: Thread not mounted
Mon Sep1 15:53:33 2008
ARC7: Thread not mounted
Mon Sep1 15:53:33 2008
ALTER DATABASE MOUNT
Mon Sep1 15:53:33 2008
lmon registered with NM - instance id 1 (internal mem no 0)
Mon Sep1 15:53:34 2008
Reconfiguration started (old inc 0, new inc 1)
List of nodes:
0
Global Resource Directory frozen
one node partition
Communication channels reestablished
Master broadcasted resource hash value bitmaps
Non-local Process blocks cleaned out
Resources and enqueues cleaned out
Resources remastered 0
0 GCS shadows traversed, 0 cancelled, 0 closed
0 GCS resources traversed, 0 cancelled
set master node info
Submitted all remote-enqueue requests
Update rdomain variables
Dwn-cvts replayed, VALBLKs dubious
All grantable enqueues granted
0 GCS shadows traversed, 0 replayed, 0 unopened
Submitted all GCS remote-cache requests
0 write requests issued in 0 GCS resources
0 PIs marked suspect, 0 flush PI msgs
Mon Sep1 15:53:35 2008
Reconfiguration complete
Post SMON to start 1st pass IR
Mon Sep1 15:53:35 2008
This instance was first to mount
LCK0 started with pid=36
Mon Sep1 15:53:39 2008
Successful mount of redo thread 1, with mount id 2166862335
Mon Sep1 15:53:39 2008
Database mounted in Shared Mode (CLUSTER_DATABASE=TRUE).
Completed: ALTER DATABASE MOUNT
Mon Sep1 15:53:40 2008
ALTER DATABASE OPEN
This instance was first to open
Picked broadcast on commit scheme to generate SCNs
Mon Sep1 15:53:41 2008
LGWR: Primary database is in CLUSTER CONSISTENT mode
Thread 1 opened at log sequence 185312
Current log# 2 seq# 185312 mem# 0: /oradata/sys2/REDOLOGS/REDO02.LOG
Current log# 2 seq# 185312 mem# 1: /oradata/sys1/REDOLOGS/REDO02A.LOG
Successful open of redo thread 1
Mon Sep1 15:53:43 2008
SMON: enabling cache recovery
Instance recovery: looking for dead threads
Instance recovery: lock domain invalid but no dead threads
Mon Sep1 15:53:45 2008
Successfully onlined Undo Tablespace 1.
Mon Sep1 15:53:45 2008
SMON: enabling tx recovery
Mon Sep1 15:53:45 2008
Database Characterset is ZHT16MSWIN950
replication_dependency_tracking turned off (no async multimaster replication found)
Completed: ALTER DATABASE OPEN
Mon Sep1 15:54:05 2008
Thread 1 advanced to log sequence 185313
Mon Sep1 15:54:05 2008
ARC3: Evaluating archive log 2 thread 1 sequence 185312
Current log# 3 seq# 185313 mem# 0: /oradata/sys2/REDOLOGS/REDO03.LOG
Mon Sep1 15:54:05 2008
Current log# 3 seq# 185313 mem# 1: /oradata/sys1/REDOLOGS/REDO03A.LOG
Mon Sep1 15:54:05 2008
ARC3: Beginning to archive log 2 thread 1 sequence 185312
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185312.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185312.ARC'
ARC3: Completed archivinglog 2 thread 1 sequence 185312
ARC3: Evaluating archive log 6 thread 2 sequence 166478
ARC3: Beginning to archive log 6 thread 2 sequence 166478
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_2_166478.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_2_166478.ARC'
ARC3: Completed archivinglog 6 thread 2 sequence 166478
Mon Sep1 15:55:10 2008

Mon Sep1 16:48:31 2008
ARC8: Completed archivinglog 2 thread 1 sequence 185324
Mon Sep1 16:48:31 2008
ARC0: Evaluating archive log 3 thread 1 sequence 185325
ARC0: Unable to archive log 3 thread 1 sequence 185325
Log actively being archived by another process
ARC0: Evaluating archive log 3 thread 1 sequence 185325
ARC0: Unable to archive log 3 thread 1 sequence 185325
Log actively being archived by another process
Mon Sep1 16:48:33 2008
ARC2: Evaluating archive log 3 thread 1 sequence 185325
ARC2: Unable to archive log 3 thread 1 sequence 185325
Log actively being archived by another process
Mon Sep1 16:48:33 2008
ARC3: Evaluating archive log 3 thread 1 sequence 185325
Mon Sep1 16:48:33 2008
ARC4: Evaluating archive log 3 thread 1 sequence 185325
Mon Sep1 16:48:33 2008
ARC4: Unable to archive log 3 thread 1 sequence 185325
Mon Sep1 16:48:33 2008
ARC3: Unable to archive log 3 thread 1 sequence 185325
Log actively being archived by another process
Mon Sep1 16:48:33 2008
Log actively being archived by another process
Mon Sep1 16:48:34 2008
ARC7: Evaluating archive log 3 thread 1 sequence 185325
Mon Sep1 16:48:34 2008
ARC8: Evaluating archive log 3 thread 1 sequence 185325
Mon Sep1 16:48:34 2008
ARC6: Evaluating archive log 3 thread 1 sequence 185325
Mon Sep1 16:48:34 2008
ARC7: Unable to archive log 3 thread 1 sequence 185325
Mon Sep1 16:48:34 2008
ARC6: Unable to archive log 3 thread 1 sequence 185325
Mon Sep1 16:48:34 2008
ARC8: Unable to archive log 3 thread 1 sequence 185325
Mon Sep1 16:48:34 2008
Log actively being archived by another process
Mon Sep1 16:48:34 2008
Log actively being archived by another process
Mon Sep1 16:48:34 2008
Log actively being archived by another process
Mon Sep1 16:48:34 2008
ARC5: Evaluating archive log 3 thread 1 sequence 185325
ARC5: Unable to archive log 3 thread 1 sequence 185325
Log actively being archived by another process
Mon Sep1 16:48:34 2008
ARC1: Evaluating archive log 3 thread 1 sequence 185325
ARC1: Unable to archive log 3 thread 1 sequence 185325
Log actively being archived by another process
Mon Sep1 16:48:47 2008
ARC2: Evaluating archive log 3 thread 1 sequence 185325
ARC2: Unable to archive log 3 thread 1 sequence 185325
Log actively being archived by another process
Mon Sep1 16:48:47 2008
Thread 1 advanced to log sequence 185327
ARC2: Evaluating archive log 1 thread 1 sequence 185326
Mon Sep1 16:48:47 2008
Current log# 2 seq# 185327 mem# 0: /oradata/sys2/REDOLOGS/REDO02.LOG
Mon Sep1 16:48:47 2008
ARC2: Beginning to archive log 1 thread 1 sequence 185326
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185326.ARC'
Mon Sep1 16:48:47 2008
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185326.ARC'
Mon Sep1 16:48:47 2008
Current log# 2 seq# 185327 mem# 1: /oradata/sys1/REDOLOGS/REDO02A.LOG
Mon Sep1 16:48:56 2008
ARC9: Completed archivinglog 3 thread 1 sequence 185325
Mon Sep1 16:48:56 2008
ARC3: Evaluating archive log 1 thread 1 sequence 185326
Mon Sep1 16:48:56 2008
ARC9: Evaluating archive log 1 thread 1 sequence 185326
Mon Sep1 16:48:56 2008
ARC3: Unable to archive log 1 thread 1 sequence 185326
Mon Sep1 16:48:56 2008
ARC9: Unable to archive log 1 thread 1 sequence 185326
Mon Sep1 16:48:56 2008
Log actively being archived by another process
Mon Sep1 16:48:56 2008
Log actively being archived by another process
Mon Sep1 16:49:08 2008
Thread 1 advanced to log sequence 185328
Current log# 3 seq# 185328 mem# 0: /oradata/sys2/REDOLOGS/REDO03.LOG
Current log# 3 seq# 185328 mem# 1: /oradata/sys1/REDOLOGS/REDO03A.LOG
Mon Sep1 16:49:10 2008
ARC4: Evaluating archive log 1 thread 1 sequence 185326
ARC4: Unable to archive log 1 thread 1 sequence 185326
Log actively being archived by another process
ARC4: Evaluating archive log 2 thread 1 sequence 185327
ARC4: Beginning to archive log 2 thread 1 sequence 185327
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185327.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185327.ARC'
Mon Sep1 16:49:12 2008
ARC2: Completed archivinglog 1 thread 1 sequence 185326
Mon Sep1 16:49:12 2008
ARC5: Evaluating archive log 2 thread 1 sequence 185327
ARC5: Unable to archive log 2 thread 1 sequence 185327
Log actively being archived by another process
Mon Sep1 16:49:34 2008
ARC1: Evaluating archive log 2 thread 1 sequence 185327
ARC1: Unable to archive log 2 thread 1 sequence 185327
Log actively being archived by another process
Mon Sep1 16:49:35 2008
ARC4: Completed archivinglog 2 thread 1 sequence 185327
Mon Sep1 16:56:49 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
Mon Sep1 16:59:08 2008
Thread 1 advanced to log sequence 185329
Current log# 1 seq# 185329 mem# 0: /oradata/sys2/REDOLOGS/REDO01.LOG
Mon Sep1 16:59:08 2008
ARC9: Evaluating archive log 3 thread 1 sequence 185328
Mon Sep1 16:59:08 2008
Current log# 1 seq# 185329 mem# 1: /oradata/sys1/REDOLOGS/REDO01A.LOG
Mon Sep1 16:59:08 2008
ARC9: Beginning to archive log 3 thread 1 sequence 185328
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185328.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185328.ARC'
Mon Sep1 16:59:13 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
ARC9: Completed archivinglog 3 thread 1 sequence 185328
Mon Sep1 17:00:37 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
Mon Sep1 17:00:48 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
Mon Sep1 17:02:00 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
Mon Sep1 17:03:25 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
Mon Sep1 17:05:25 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
Mon Sep1 17:07:37 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
Mon Sep1 17:08:25 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
Mon Sep1 17:09:07 2008
Thread 1 advanced to log sequence 185330
Current log# 2 seq# 185330 mem# 0: /oradata/sys2/REDOLOGS/REDO02.LOG
Current log# 2 seq# 185330 mem# 1: /oradata/sys1/REDOLOGS/REDO02A.LOG
Mon Sep1 17:09:07 2008
ARC3: Evaluating archive log 1 thread 1 sequence 185329
ARC3: Beginning to archive log 1 thread 1 sequence 185329
Creating archive destination LOG_ARCHIVE_DEST_2: '/oradata/archbcv/2041829088_1_185329.ARC'
Creating archive destination LOG_ARCHIVE_DEST_1: '/oradata/arch/2041829088_1_185329.ARC'
ARC3: Completed archivinglog 1 thread 1 sequence 185329
Mon Sep1 17:09:25 2008
Global Enqueue Services Deadlock detected. More info in filen /oracle/admin/NTUH/bdump/ntuh1_lmd0_1822.trc.
Mon Sep1 17:11:33 2008
SMON: Parallel transaction recovery tried
Mon Sep1 17:12:15 2008
PMON failed to delete process, see PMON trace file
PMON deletion of process succeeded
Mon Sep1 17:19:05 2008
Thread 1 advanced to log sequence 185331
Current log# 3 seq# 185331 mem# 0: /oradata/sys2/REDOLOGS/REDO03.LOG
Mon Sep1 17:19:05 2008
ARC7: Evaluating archive log 2 thread 1 sequence 185330
Mon Sep1 17:19:05 2008
Current log# 3 seq# 185331 mem# 1: /oradata/sys1/REDOLOGS/REDO03A.LOG

PMON trace file log:
====================================================
deleting process 4025b8e7e68 pid=66 seq=7
waiting for block file#: 18 rdba: 0x048567af flg: 0x00080000 st: 0x04 mode: 0x02
deletion of process 4025b8e7e68 pid=66 seq=7 unsuccessful
deleting process 4025b8faea0 pid=125 seq=5
waiting for block file#: 18 rdba: 0x048567af flg: 0x00080000 st: 0x04 mode: 0x02
deletion of process 4025b8faea0 pid=125 seq=5 unsuccessful

有關PMON 的trace中很清楚看到是file#18發生dead lock,我進去查了系統,只知道這個file的tablespace是放index的tbs,
可以知道是哪一個index發生dead lock嗎?
回复

使用道具 举报

千问 | 2005-10-30 17:05:33 | 显示全部楼层
哈~自問自答~~
如何找出競爭激烈的object?
資料庫中看到發生dead lock的競爭,
看pmon的trace file有看到很多process是wait在block file#: 18 rdba: 0x048567af flg: 0x00080000 st: 0x04 mode: 0x02上,
如下所示:
waiting for block file#: 18 rdba: 0x048567af flg: 0x00080000 st: 0x04 mode: 0x02
deletion of process 4025b9bc988 pid=726 seq=2 unsuccessful
我們可以透過下面的方式找出block所在的object name.
這裡面很重要的是要將rdba值所對應的data file block找出來,我們之後才可以透過dba_extents來將object找出來.
而有關rdba的說明請參考eygle blog上的說明.
(ref:http://www.eygle.com/archives/2007/07/function_rdba_convert.html)
由於rdba值是一個16進位值,必須透過轉換將其轉換成10進位,轉換的方式亦請參考eygle blog文件.
(ref:http://www.eygle.com/archives/2004/06/oracle_howto_convert.html)
SQL> variable file# number
SQL> variable block# number
SQL> execute :file#:=dbms_utility.data_block_address_file(to_number('48567af','xxxxxxx'));
PL/SQL procedure successfully completed.
SQL> execute :block#:=dbms_utility.data_block_address_block(to_number('48567af','xxxxxxx'));
SQL> print file#
FILE#
----------
18
SQL> print block#
BLOCK#
----------
354223
透過dba_extents找出block_id所在的object.
SQL> select * from dba_extents;
OWNERSEGMENT_NAMEPARTITION_NAMESEGMENT_TYPETABLESPACE_NAMEEXTENT_IDFILE_IDBLOCK_IDBYTESBLOCKSRELATIVE_FNO
DBDIAIND4_D0001INDEXDBDIA_IDX136183578978388608102418
DBDIAIND_D0001INDEXDBDIA_IDX121183568738388608102418
DBDIAIND3_D0001INDEXDBDIA_IDX125183558498388608102418
DBDIAIND1_D0001INDEXDBDIA_IDX134183548258388608102418
DBDIAIND5_D0001INDEXDBDIA_IDX127183538018388608102418
DBDIAIND2_D0001INDEXDBDIA_IDX124183527778388608102418
DBDIAIND3_D0001INDEXDBDIA_IDX124183517538388608102418
DBDIAIND1_D0001INDEXDBDIA_IDX133183507298388608102418
回复

使用道具 举报

您需要登录后才可以回帖 登录 | 立即注册

本版积分规则

主题

0

回帖

4882万

积分

论坛元老

Rank: 8Rank: 8

积分
48824836
热门排行