6 Replies Latest reply on Oct 10, 2011 11:16 AM by CKPT

    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