orace service terminated unexpectedly

893485
    hi all,

    My databse version is Oracle Database 10g Enterprise Edition Release 10.2.0.4.0 - 64bi and windows server2003.The database got restarted on its own and there were no errors in alert and trace file.. I could see the below error in event viewer attached below.
    Error :The OracleServiceECM53 service terminated unexpectedly. It has done this 3 time(s). at 11:19:14 PM.
    Error:The OracleServiceECM53 service was successfully sent a start control. At 11:55:05 PM.
    From this error how can we find the root cause of the problem.
    no errros in alert file before or after restart of database.
    Please helpme as i am new to admin side.
    Thanks& Regards,
    Venu
      • 1. Re: orace service terminated unexpectedly
        893485
        hi all ,
        The below is my alert log file
        Mon Oct 03 17:28:48 2011
        Thread 1 cannot allocate new log, sequence 10068
        Private strand flush not complete
        Current log# 2 seq# 10067 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Mon Oct 03 17:28:48 2011
        Thread 1 advanced to log sequence 10068 (LGWR switch)
        Current log# 3 seq# 10068 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Mon Oct 03 18:05:18 2011
        Thread 1 advanced to log sequence 10069 (LGWR switch)
        Current log# 1 seq# 10069 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Mon Oct 03 18:22:33 2011
        Thread 1 advanced to log sequence 10070 (LGWR switch)
        Current log# 2 seq# 10070 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Mon Oct 03 19:11:00 2011
        Thread 1 advanced to log sequence 10071 (LGWR switch)
        Current log# 3 seq# 10071 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Mon Oct 03 19:59:20 2011
        Thread 1 advanced to log sequence 10072 (LGWR switch)
        Current log# 1 seq# 10072 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Mon Oct 03 20:41:13 2011
        Thread 1 advanced to log sequence 10073 (LGWR switch)
        Current log# 2 seq# 10073 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Mon Oct 03 20:48:55 2011
        Thread 1 advanced to log sequence 10074 (LGWR switch)
        Current log# 3 seq# 10074 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Mon Oct 03 20:56:36 2011
        Thread 1 advanced to log sequence 10075 (LGWR switch)
        Current log# 1 seq# 10075 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Mon Oct 03 21:04:13 2011
        Thread 1 advanced to log sequence 10076 (LGWR switch)
        Current log# 2 seq# 10076 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Mon Oct 03 21:13:24 2011
        Thread 1 advanced to log sequence 10077 (LGWR switch)
        Current log# 3 seq# 10077 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Mon Oct 03 21:20:56 2011
        ALTER SYSTEM ARCHIVE LOG
        Mon Oct 03 21:20:57 2011
        Thread 1 advanced to log sequence 10078 (LGWR switch)
        Current log# 1 seq# 10078 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Mon Oct 03 21:30:06 2011
        Thread 1 advanced to log sequence 10079 (LGWR switch)
        Current log# 2 seq# 10079 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Mon Oct 03 21:39:05 2011
        Thread 1 advanced to log sequence 10080 (LGWR switch)
        Current log# 3 seq# 10080 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Mon Oct 03 21:47:29 2011
        Thread 1 advanced to log sequence 10081 (LGWR switch)
        Current log# 1 seq# 10081 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Mon Oct 03 21:56:02 2011
        Thread 1 advanced to log sequence 10082 (LGWR switch)
        Current log# 2 seq# 10082 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Mon Oct 03 22:00:32 2011
        Thread 1 advanced to log sequence 10083 (LGWR switch)
        Current log# 3 seq# 10083 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Mon Oct 03 22:09:35 2011
        Thread 1 advanced to log sequence 10084 (LGWR switch)
        Current log# 1 seq# 10084 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Mon Oct 03 22:21:07 2011
        Thread 1 advanced to log sequence 10085 (LGWR switch)
        Current log# 2 seq# 10085 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Mon Oct 03 22:35:50 2011
        Thread 1 advanced to log sequence 10086 (LGWR switch)
        Current log# 3 seq# 10086 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Mon Oct 03 22:56:05 2011
        Thread 1 advanced to log sequence 10087 (LGWR switch)
        Current log# 1 seq# 10087 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Dump file d:\oracle\product\10.2.0\admin\ecm53\bdump\alert_ecm53.log
        Mon Oct 03 23:55:06 2011
        ORACLE V10.2.0.4.0 - 64bit Production vsnsta=0
        vsnsql=14 vsnxtr=3
        Windows NT Version V5.2 Service Pack 2
        CPU : 16 - type 8664, 4 Physical Cores
        Process Affinity : 0x0000000000000000
        Memory (Avail/Total): Ph:30192M/32766M, Ph+PgF:34217M/35823M
        Mon Oct 03 23:55:06 2011
        Starting ORACLE instance (normal)
        LICENSE_MAX_SESSION = 0
        LICENSE_SESSIONS_WARNING = 0
        Picked latch-free SCN scheme 3
        Using LOG_ARCHIVE_DEST_10 parameter default value as USE_DB_RECOVERY_FILE_DEST
        Autotune of undo retention is turned on.
        IMODE=BR
        ILAT =121
        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.4.0.
        System parameters with non-default values:
        processes = 1000
        sessions = 1105
        sga_max_size = 26222788608
        __shared_pool_size = 6610223104
        __large_pool_size = 16777216
        __java_pool_size = 16777216
        __streams_pool_size = 0
        sga_target = 26222788608
        control_files = D:\ORADATA\ECM53\CONTROL01.CTL, D:\ORADATA\ECM53\CONTROL02.CTL, D:\ORADATA\ECM53\CONTROL03.CTL
        db_block_size = 8192
        __db_cache_size = 19562233856
        compatible = 10.2.0.3.0
        db_file_multiblock_read_count= 16
        db_recovery_file_dest = E:\flash_recovery_area
        db_recovery_file_dest_size= 214748364800
        undo_management = AUTO
        undo_tablespace = UNDOTBS1
        remote_login_passwordfile= EXCLUSIVE
        db_domain =
        dispatchers = (PROTOCOL=TCP) (SERVICE=ecm53XDB)
        session_cached_cursors = 50
        job_queue_processes = 10
        cursor_sharing = FORCE
        audit_file_dest = D:\ORACLE\PRODUCT\10.2.0\ADMIN\ECM53\ADUMP
        background_dump_dest = D:\ORACLE\PRODUCT\10.2.0\ADMIN\ECM53\BDUMP
        user_dump_dest = D:\ORACLE\PRODUCT\10.2.0\ADMIN\ECM53\UDUMP
        core_dump_dest = D:\ORACLE\PRODUCT\10.2.0\ADMIN\ECM53\CDUMP
        optimizer_features_enable= 10.2.0.4.1
        db_name = ecm53
        open_cursors = 300
        optimizer_mode = CHOOSE
        optimizer_index_cost_adj = 5
        optimizer_index_caching = 95
        pga_aggregate_target = 2516582400
        PMON started with pid=2, OS id=3872
        PSP0 started with pid=3, OS id=8628
        MMAN started with pid=4, OS id=9156
        DBW0 started with pid=5, OS id=8156
        DBW1 started with pid=6, OS id=5748
        LGWR started with pid=7, OS id=11092
        CKPT started with pid=8, OS id=10544
        SMON started with pid=9, OS id=4260
        RECO started with pid=10, OS id=11188
        CJQ0 started with pid=11, OS id=10952
        MMON started with pid=12, OS id=5776
        Mon Oct 03 23:55:07 2011
        starting up 1 dispatcher(s) for network address '(ADDRESS=(PARTIAL=YES)(PROTOCOL=TCP))'...
        MMNL started with pid=13, OS id=8284
        Mon Oct 03 23:55:07 2011
        starting up 1 shared server(s) ...
        Mon Oct 03 23:55:07 2011
        alter database mount exclusive
        Mon Oct 03 23:55:11 2011
        Setting recovery target incarnation to 2
        Mon Oct 03 23:55:12 2011
        Successful mount of redo thread 1, with mount id 701833115
        Mon Oct 03 23:55:12 2011
        Database mounted in Exclusive Mode
        Completed: alter database mount exclusive
        Mon Oct 03 23:55:12 2011
        alter database open
        Mon Oct 03 23:55:12 2011
        Beginning crash recovery of 1 threads
        parallel recovery started with 15 processes
        Mon Oct 03 23:55:12 2011
        Started redo scan
        Mon Oct 03 23:55:12 2011
        Completed redo scan
        15159 redo blocks read, 1446 data blocks need recovery
        Mon Oct 03 23:55:14 2011
        Started redo application at
        Thread 1: logseq 10087, block 56458
        Mon Oct 03 23:55:14 2011
        Recovery of Online Redo Log: Thread 1 Group 1 Seq 10087 Reading mem 0
        Mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Mon Oct 03 23:55:14 2011
        Completed redo application
        Mon Oct 03 23:55:19 2011
        Completed crash recovery at
        Thread 1: logseq 10087, block 71617, scn 765905932
        1446 data blocks read, 1446 data blocks written, 15159 redo blocks read
        Mon Oct 03 23:55:20 2011
        LGWR: STARTING ARCH PROCESSES
        ARC0 started with pid=32, OS id=8024
        Mon Oct 03 23:55:20 2011
        ARC0: Archival started
        ARC1: Archival started
        ARC1 started with pid=33, OS id=9632
        Mon Oct 03 23:55:21 2011
        LGWR: STARTING ARCH PROCESSES COMPLETE
        Mon Oct 03 23:55:21 2011
        Thread 1 advanced to log sequence 10088 (thread open)
        Thread 1 opened at log sequence 10088
        Current log# 2 seq# 10088 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Successful open of redo thread 1
        Mon Oct 03 23:55:22 2011
        MTTR advisory is disabled because FAST_START_MTTR_TARGET is not set
        Mon Oct 03 23:55:22 2011
        ARC1: Becoming the 'no FAL' ARCH
        ARC1: Becoming the 'no SRL' ARCH
        Mon Oct 03 23:55:22 2011
        ARC0: Becoming the heartbeat ARCH
        Mon Oct 03 23:55:22 2011
        SMON: enabling cache recovery
        Mon Oct 03 23:55:22 2011
        db_recovery_file_dest_size of 204800 MB is 26.65% used. This is a
        user-specified limit on the amount of space that will be used by this
        database for recovery-related files, and does not reflect the amount of
        space available in the underlying filesystem or ASM diskgroup.
        Mon Oct 03 23:55:24 2011
        Successfully onlined Undo Tablespace 1.
        Mon Oct 03 23:55:24 2011
        SMON: enabling tx recovery
        Mon Oct 03 23:55:24 2011
        Database Characterset is AL32UTF8
        Opening with internal Resource Manager plan
        where NUMA PG = 1, CPUs = 16
        replication_dependency_tracking turned off (no async multimaster replication found)
        Starting background process QMNC
        QMNC started with pid=35, OS id=11160
        Mon Oct 03 23:55:29 2011
        Completed: alter database open
        Tue Oct 04 00:41:24 2011
        Thread 1 advanced to log sequence 10089 (LGWR switch)
        Current log# 3 seq# 10089 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Tue Oct 04 00:50:21 2011
        Thread 1 advanced to log sequence 10090 (LGWR switch)
        Current log# 1 seq# 10090 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Tue Oct 04 00:58:23 2011
        Thread 1 advanced to log sequence 10091 (LGWR switch)
        Current log# 2 seq# 10091 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Tue Oct 04 01:10:12 2011
        Thread 1 advanced to log sequence 10092 (LGWR switch)
        Current log# 3 seq# 10092 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Tue Oct 04 01:15:24 2011
        ALTER SYSTEM ARCHIVE LOG
        Tue Oct 04 01:15:24 2011
        Thread 1 cannot allocate new log, sequence 10093
        Private strand flush not complete
        Current log# 3 seq# 10092 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Tue Oct 04 01:15:25 2011
        Thread 1 advanced to log sequence 10093 (LGWR switch)
        Current log# 1 seq# 10093 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Tue Oct 04 01:25:17 2011
        Thread 1 advanced to log sequence 10094 (LGWR switch)
        Current log# 2 seq# 10094 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Tue Oct 04 01:26:24 2011
        Thread 1 advanced to log sequence 10095 (LGWR switch)
        Current log# 3 seq# 10095 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Tue Oct 04 01:35:25 2011
        Thread 1 advanced to log sequence 10096 (LGWR switch)
        Current log# 1 seq# 10096 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        Tue Oct 04 01:44:26 2011
        Thread 1 advanced to log sequence 10097 (LGWR switch)
        Current log# 2 seq# 10097 mem# 0: D:\ORADATA\ECM53\REDO02.LOG
        Tue Oct 04 01:53:24 2011
        Thread 1 advanced to log sequence 10098 (LGWR switch)
        Current log# 3 seq# 10098 mem# 0: D:\ORADATA\ECM53\REDO03.LOG
        Tue Oct 04 02:02:24 2011
        Thread 1 advanced to log sequence 10099 (LGWR switch)
        Current log# 1 seq# 10099 mem# 0: D:\ORADATA\ECM53\REDO01.LOG
        • 2. Re: orace service terminated unexpectedly
          Fran
          Beginning crash recovery of 1 threads
          Like it shows, the problem wasn't the database, probably your server down. check the events of the server between Mon Oct 03 23:55:06 2011 and Mon Oct 03 23:40:00 2011.

          It shows you what happend with the server.
          • 3. Re: orace service terminated unexpectedly
            CKPT
            890482 wrote:
            hi all,

            My databse version is Oracle Database 10g Enterprise Edition Release 10.2.0.4.0 - 64bi and windows server2003.The database got restarted on its own and there were no errors in alert and trace file.. I could see the below error in event viewer attached below.
            Error :The OracleServiceECM53 service terminated unexpectedly. It has done this 3 time(s). at 11:19:14 PM.
            Error:The OracleServiceECM53 service was successfully sent a start control. At 11:55:05 PM.
            From this error how can we find the root cause of the problem.
            no errros in alert file before or after restart of database.
            Please helpme as i am new to admin side.
            Thanks& Regards,
            Venu
            Specific to ORACLE on WINDOWS if any server restarts or any service restarts you cant find information in ALERT LOG file,

            You have to check evene viewer, where you can see the exception and termination of services and all. :)
            • 4. Re: orace service terminated unexpectedly
              893485
              Thansk for the quick respose,
              But in the same server, another database is running which is fine running. if the server was down the both the databases would have crashed.

              Thanks & Regrds,
              Venu
              • 5. Re: orace service terminated unexpectedly
                893485
                In event viewer this is the following error i could find.
                Error :The OracleServiceECM53 service terminated unexpectedly. It has done this 3 time(s). at 11:19:14 PM.
                Error:The OracleServiceECM53 service was successfully sent a start control. At 11:55:05 PM.
                the event id is 7034

                Thanks& Regards,
                Venu

                Edited by: 890482 on Oct 10, 2011 4:08 AM
                • 6. Re: orace service terminated unexpectedly
                  CKPT
                  890482 wrote:
                  In event viewer this is the following error i could find.
                  Error :The OracleServiceECM53 service terminated unexpectedly. It has done this 3 time(s). at 11:19:14 PM.
                  Error:The OracleServiceECM53 service was successfully sent a start control. At 11:55:05 PM.
                  the event id is 7034

                  Thanks& Regards,
                  Venu

                  Edited by: 890482 on Oct 10, 2011 4:08 AM
                  Based on Event id you can research more, Now it is almost out of ORACLE part. Check in forums of Microsoft, here is below link for your event ID.

                  http://social.technet.microsoft.com/wiki/contents/articles/windows-server-2008-event-id-7034-service-stop-operations.aspx