Oracle Database Discussions

Hello,

we are using oracle fail safe 3.4 and microsoft cluster for high availability on windows server 2008 and oracle 10g. We were peacefully running this environment from last couple of months but currently we are hit by a
connection problem. The changes made to the environment is we have added reporting server which also gets connected to same oracle database server but is not a cluster node. When below error occurs failover happens from node1 to node2. i have pasted two log and one alert file below,
And in application log we got error code 10066 for Oracle MSCS Service. Any help regarding will be greatly appreciated.

prdb_lgwr_7196.trc------------------*********----------------------
Dump file h:\admin\prdb\bdump\prdb_lgwr_7196.trc
Thu Feb 25 23:41:11 2010
ORACLE V10.2.0.3.0 - Production vsnsta=0
vsnsql=14 vsnxtr=3
Oracle Database 10g Release 10.2.0.3.0 - Production
Windows NT Version V6.0 Service Pack 2
CPU : 4 - type 586, 4 Physical Cores
Process Affinity : 0x00000000
Memory (Avail/Total): Ph:2190M/3325M, Ph+PgF:5199M/6873M, VA:1256M/2047M
Instance name: prdb

Redo thread mounted by this instance: 1

Oracle process number: 6

Windows thread id: 7196, image: ORACLE.EXE (LGWR)


*** SERVICE NAME:() 2010-02-25 23:41:11.228
*** SESSION ID:(166.1) 2010-02-25 23:41:11.228
tkcrrsarc: (WARN) Failed to find ARCH for message (message:0x1)
tkcrrpa: (WARN) Failed initial attempt to send ARCH message (message:0x1)
Maximum redo generation record size = 156160 bytes
Maximum redo generation change vector size = 150672 bytes
tkcrrsarc: (WARN) Failed to find ARCH for message (message:0x10)
tkcrrpa: (WARN) Failed initial attempt to send ARCH message (message:0x10)

prdb_ora_7668.trc--------------------*************----------------------------
Oracle Database 10g Release 10.2.0.3.0 - Production
Windows NT Version V6.0 Service Pack 2
CPU : 4 - type 586, 4 Physical Cores
Process Affinity : 0x00000000
Memory (Avail/Total): Ph:2282M/3325M, Ph+PgF:5665M/6873M, VA:1319M/2047M
Instance name: prdb

Redo thread mounted by this instance: 0 <none>

Oracle process number: 0

Windows thread id: 7668, image: ORACLE.EXE (SHAD)


KCCDEBUG_LEVEL = 0
Running with 1 strand for Non-Enterprise Edition
Running without dynamic strand for Non-Enterprise Edition
Dynamic strand is set to FALSE
Running with 1 shared and 18 private strand(s). Zero-copy redo is FALSE
Dump file h:\admin\prdb\udump\prdb_ora_7668.trc
Thu Feb 25 23:41:05 2010
ORACLE V10.2.0.3.0 - Production vsnsta=0
vsnsql=14 vsnxtr=3
*** SERVICE NAME:() 2010-02-25 23:41:05.207
*** SESSION ID:(159.1) 2010-02-25 23:41:05.207
kccsga_update_ckpt: num_1 = 8, num_2 = 0, num_3 = 0, lbn_2 = 0, lbn_3 = 0
Thread 1 checkpoint: logseq 22965, block 2, scn 1039290418
cache-low rba: logseq 22965, block 85285
on-disk rba: logseq 22966, block 16821, scn 1039318623
start recovery at logseq 22965, block 85285, scn 0
----- Redo read statistics for thread 1 -----
Read rate (ASYNC): 15800Kb in 0.39s => 39.56 Mb/sec
Total physical reads: 19674Kb
Longest record: 27Kb, moves: 0/36033 (0%)
Change moves: 89/614 (14%), moved: 0Mb
Longest LWN: 404Kb, moves: 3/3385 (0%), moved: 0Mb
Last redo scn: 0x0000.3df2be69 (1039318633)

----- Recovery Hash Table Statistics ---------
Hash table buckets = 32768
Longest hash chain = 3
Average hash chain = 2024/1977 = 1.0
Max compares per lookup = 2
Avg compares per lookup = 87532/91294 = 1.0

*** 2010-02-25 23:41:09.902
KCRA: start recovery claims for 2024 data blocks
*** 2010-02-25 23:41:10.479
KCRA: blocks processed = 2024/2024, claimed = 2024, eliminated = 0
*** 2010-02-25 23:41:10.479
Recovery of Online Redo Log: Thread 1 Group 2 Seq 22965 Reading mem 0
*** 2010-02-25 23:41:10.698
Recovery of Online Redo Log: Thread 1 Group 3 Seq 22966 Reading mem 0
----- Recovery Hash Table Statistics ---------
Hash table buckets = 32768
Longest hash chain = 3
Average hash chain = 2024/1977 = 1.0
Max compares per lookup = 3
Avg compares per lookup = 78383/89022 = 0.9

tkcrrsarc: (WARN) Failed to find ARCH for message (message:0x1)
tkcrrpa: (WARN) Failed initial attempt to send ARCH message (message:0x1)

-------------------------***************-------------------------

alert log is as follows,
Thu Feb 25 20:13:14 2010
Thread 1 cannot allocate new log, sequence 22961
Private strand flush not complete
Thread 1 advanced to log sequence 22965
Current log# 2 seq# 22965 mem# 0: H:\PRDB\REDO02.LOG
Thu Feb 25 23:29:00 2010
Thread 1 advanced to log sequence 22966
Current log# 3 seq# 22966 mem# 0: H:\PRDB\REDO03.LOG
Dump file h:\admin\prdb\bdump\alert_prdb.log
Thu Feb 25 23:41:04 2010
ORACLE V10.2.0.3.0 - Production vsnsta=0
vsnsql=14 vsnxtr=3
Windows NT Version V6.0 Service Pack 2
CPU : 4 - type 586, 4 Physical Cores
Process Affinity : 0x00000000
Memory (Avail/Total): Ph:2295M/3325M, Ph+PgF:5826M/6873M, VA:1903M/2047M
Thu Feb 25 23:41:04 2010
Starting ORACLE instance (restrict)
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0
Picked latch-free SCN scheme 2
Autotune of undo retention is turned on.
IMODE=BR
ILAT =18
LICENSE_MAX_USERS = 0
SYS auditing is disabled
ksdpec: called for event 13740 prior to event group initialization
Starting up ORACLE RDBMS Version: 10.2.0.3.0.
System parameters with non-default values:
PMON started with pid=2, OS id=2668

Thu Feb 25 23:41:05 2010
starting up 1 dispatcher(s) for network address '(ADDRESS=(PARTIAL=YES)(PROTOCOL=TCP))'...
MMNL started with pid=12, OS id=6844
Thu Feb 25 23:41:05 2010
starting up 1 shared server(s) ...
Oracle Data Guard is not available in this edition of Oracle.
Thu Feb 25 23:41:05 2010
alter database "PRDB" mount exclusive
Thu Feb 25 23:41:09 2010
Setting recovery target incarnation to 2
Thu Feb 25 23:41:09 2010
Successful mount of redo thread 1, with mount id 1096298769
Thu Feb 25 23:41:09 2010
Database mounted in Exclusive Mode
Completed: alter database "PRDB" mount exclusive
Thu Feb 25 23:41:09 2010
alter database open
Thu Feb 25 23:41:09 2010
Beginning crash recovery of 1 threads
Thu Feb 25 23:41:09 2010
Started redo scan
Thu Feb 25 23:41:09 2010
Completed redo scan
31600 redo blocks read, 2024 data blocks need recovery
Thu Feb 25 23:41:10 2010
Started redo application at
Thread 1: logseq 22965, block 85285
Thu Feb 25 23:41:10 2010
Recovery of Online Redo Log: Thread 1 Group 2 Seq 22965 Reading mem 0
Mem# 0: H:\PRDB\REDO02.LOG
Thu Feb 25 23:41:10 2010
Recovery of Online Redo Log: Thread 1 Group 3 Seq 22966 Reading mem 0
Mem# 0: H:\PRDB\REDO03.LOG
Thu Feb 25 23:41:11 2010
Completed redo application
Thu Feb 25 23:41:11 2010
Completed crash recovery at
Thread 1: logseq 22966, block 16830, scn 1039338634
2024 data blocks read, 2024 data blocks written, 31600 redo blocks read
Thu Feb 25 23:41:11 2010
LGWR: STARTING ARCH PROCESSES
ARC0 started with pid=16, OS id=6372
Thu Feb 25 23:41:11 2010
ARC0: Archival started
Thu Feb 25 23:41:11 2010
ARC1: Archival started
LGWR: STARTING ARCH PROCESSES COMPLETE
Thread 1 advanced to log sequence 22967
Thread 1 opened at log sequence 22967
Current log# 1 seq# 22967 mem# 0: H:\PRDB\REDO01.LOG
Successful open of redo thread 1
Thu Feb 25 23:41:11 2010
ARC0: Becoming the 'no FAL' ARCH
ARC0: Becoming the 'no SRL' ARCH
Thu Feb 25 23:41:11 2010
SMON: enabling cache recovery
Thu Feb 25 23:41:11 2010
Successfully onlined Undo Tablespace 1.
Thu Feb 25 23:41:11 2010
SMON: enabling tx recovery
Thu Feb 25 23:41:11 2010
Database Characterset is WE8MSWIN1252
ARC1 started with pid=17, OS id=2176
Thu Feb 25 23:41:12 2010
ARC1: Becoming the heartbeat ARCH
Thu Feb 25 23:41:12 2010
replication_dependency_tracking turned off (no async multimaster replication found)
Starting background process QMNC
QMNC started with pid=18, OS id=6936
Thu Feb 25 23:41:13 2010
Completed: alter database open
Thu Feb 25 23:41:13 2010
ALTER SYSTEM disable restricted session;

Edited by: user5865877 on Feb 26, 2010 4:07 AM