2 Replies Latest reply: Apr 19, 2013 11:33 AM by emersonmartins8 RSS

    ORA-00445: background process "m000" did not start after 120 seconds

    emersonmartins8
      Hi everyone.

      Can you help me.

      I'm getting these warnings in my database in the OEM and in the morning effect shutdown and resolve temporary.

      Contents alert.log


      Errors in file C:\ORACLE\diag\rdbms\prod\prod\trace\prod_mmon_2536.trc (incident=65385):
      ORA-00445: background process "m000" did not start after 120 seconds
      Incident details in: C:\ORACLE\diag\rdbms\prod\prod\incident\incdir_65385\prod_mmon_2536_i65385.trc
      Thu Apr 18 23:10:51 2013
      Dumping diagnostic data in directory=[cdmp_20130418231051], requested by (instance=1, osid=2536 (MMON)), summary=[incident=65385].
      Thu Apr 18 23:12:51 2013
      Errors in file C:\ORACLE\diag\rdbms\prod\prod\trace\prod_cjq0_1700.trc (incident=65545):
      ORA-00445: background process "J000" did not start after 120 seconds
      Incident details in: C:\ORACLE\diag\rdbms\prod\prod\incident\incdir_65545\prod_cjq0_1700_i65545.trc
      kkjcre1p: unable to spawn jobq slave process
      Errors in file C:\ORACLE\diag\rdbms\prod\prod\trace\prod_cjq0_1700.trc:
      Thu Apr 18 23:12:56 2013
      Dumping diagnostic data in directory=[cdmp_20130418231256], requested by (instance=1, osid=1700 (CJQ0)), summary=[incident=65545].
      Thu Apr 18 23:14:56 2013
      Errors in file C:\ORACLE\diag\rdbms\prod\prod\trace\prod_mmon_2536.trc (incident=65386):
      ORA-00445: background process "m000" did not start after 120 seconds
      Incident details in: C:\ORACLE\diag\rdbms\prod\prod\incident\incdir_65386\prod_mmon_2536_i65386.trc


      Content trace file


      Instance name: prod
      Redo thread mounted by this instance: 1
      Oracle process number: 16
      Windows thread id: 2536, image: ORACLE.EXE (MMON)


      *** 2013-04-18 23:10:45.792
      *** SESSION ID:(1670.1) 2013-04-18 23:10:45.792
      *** CLIENT ID:() 2013-04-18 23:10:45.792
      *** SERVICE NAME:(SYS$BACKGROUND) 2013-04-18 23:10:45.792
      *** MODULE NAME:() 2013-04-18 23:10:45.792
      *** ACTION NAME:() 2013-04-18 23:10:45.792

      Dump continued from file: C:\ORACLE\diag\rdbms\prod\prod\trace\prod_mmon_2536.trc
      ORA-00445: background process "m000" did not start after 120 seconds


      *** 2013-04-18 23:10:45.808
      ========= Dump for incident 65385 (ORA 445) ========
      ----- Beginning of Customized Incident Dump(s) -----
      -------------------------------------------------------------------------------
      Trace Bucket Dump Begin: default bucket for process 16 (osid: 2536, MMON)
      TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
      -------------------------------------------------------------------------------
      2013-04-18 23:08:24.938906 :9AAD6F1F:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[300] comment=[MMON Completion Callback Dispatcher]
      2013-04-18 23:08:24.938910 :9AAD6F20:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[301] comment=[MMON set edition interrupt action]
      2013-04-18 23:08:24.938934 :9AAD6F21:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[333] comment=[Check for sync messages from other instances]
      2013-04-18 23:08:24.938947 :9AAD6F22:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[335] comment=[Check for autostart messages from other instances]
      2013-04-18 23:08:24.938952 :9AAD6F23:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[341] comment=[Process staged incidents]
      2013-04-18 23:08:24.938956 :9AAD6F24:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[342] comment=[DDE MMON action to schedule async action slaves]
      2013-04-18 23:08:24.938964 :9AAD6F25:db_trace:ksb.c@1781:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (timeout action) : acnum=[0] comment=[Monitor Cleanup]
      2013-04-18 23:08:24.938966 :9AAD6F26:db_trace:ksb.c@1781:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (timeout action) : acnum=[1] comment=[Update KGSTM Translation]
      2013-04-18 23:08:24.939021 :9AAD6F27:db_trace:ksb.c@1781:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (timeout action) : acnum=[3] comment=[KSB action for bast checking]
      2013-04-18 23:08:24.939023 :9AAD6F28:db_trace:ksb.c@1781:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (timeout action) : acnum=[41] comment=[reconfiguration MMON action]
      2013-04-18 23:08:24.939026 :9AAD6F29:db_trace:ksb.c@1781:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (timeout action) : acnum=[61] comment=[SGA memory tuning parameter update]
      2013-04-18 23:08:24.939028 :9AAD6F2A:db_trace:ksb.c@1781:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (timeout action) : acnum=[67] comment=[SGA memory tuning]
      2013-04-18 23:08:24.939174 :9AAD6F2B:db_trace:ksb.c@1781:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (timeout action) : acnum=[150] comment=[Flashback Marker]
      2013-04-18 23:08:24.939177 :9AAD6F2C:db_trace:ksb.c@1781:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (timeout action) : acnum=[167] comment=[Block Cleanout Optim, Rac specific code]
      2013-04-18 23:08:24.939180 :9AAD6F2D:db_trace:ksb.c@1781:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (timeout action) : acnum=[168] comment=[BCO:]
      2013-04-18 23:08:24.939186 :9AAD6F2E:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[2] comment=[KSB action for X-instance calls]
      2013-04-18 23:08:24.939187 :9AAD6F2F:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[18] comment=[KSB action for ksbxic() calls]
      2013-04-18 23:08:24.939188 :9AAD6F30:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[38] comment=[MMON request for RLB metrics]
      2013-04-18 23:08:24.939190 :9AAD6F31:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[104] comment=[Scumnt mount lock]
      2013-04-18 23:08:24.939191 :9AAD6F32:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[105] comment=[Poll system events broadcast channel]
      2013-04-18 23:08:24.939192 :9AAD6F33:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[298] comment=[MMON Remote action Listener]
      2013-04-18 23:08:24.939195 :9AAD6F34:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[299] comment=[MMON Local action Listener]
      2013-04-18 23:08:24.939196 :9AAD6F35:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[300] comment=[MMON Completion Callback Dispatcher]
      2013-04-18 23:08:24.939198 :9AAD6F36:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[301] comment=[MMON set edition interrupt action]
      2013-04-18 23:08:24.939207 :9AAD6F37:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[333] comment=[Check for sync messages from other instances]
      2013-04-18 23:08:24.939211 :9AAD6F38:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[335] comment=[Check for autostart messages from other instances]
      2013-04-18 23:08:24.939214 :9AAD6F39:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[341] comment=[Process staged incidents]
      2013-04-18 23:08:24.939216 :9AAD6F3A:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[342] comment=[DDE MMON action to schedule async action slaves]
      2013-04-18 23:08:27.949567 :9AAD7028:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[2] comment=[KSB action for X-instance calls]
      2013-04-18 23:08:27.949598 :9AAD7029:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[18] comment=[KSB action for ksbxic() calls]
      2013-04-18 23:08:27.949607 :9AAD702A:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[38] comment=[MMON request for RLB metrics]
      2013-04-18 23:08:27.949613 :9AAD702B:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[104] comment=[Scumnt mount lock]
      2013-04-18 23:08:27.949617 :9AAD702C:db_trace:ksb.c@1755:ksbcti(): [10254:16:1670] KSBCTI: (MMON) : (interrupt action) : acnum=[105] comment=[Poll system events broadcast channel]
      .......
      2013-04-18 23:08:40.005092 :9AAD7518:db_trace:ksb.c@2815:ksbsrv(): [10254:16:1670] KSBSRV: enter for server = 19926972288
      2013-04-18 23:08:40.005094 :9AAD7519:db_trace:ksb.c@2847:ksbsrv(): [10254:16:1670] KSBSRV: obtained PR enqueue
      2013-04-18 23:08:40.005097 :9AAD751A:db_trace:ksb.c@2905:ksbsrv(): [10254:16:1670] KSBSRV: called start callback function
      2013-04-18 23:08:40.005132 :9AAD751D:db_trace:ksb.c@2988:ksbsrv(): [10254:16:1670] KSBSRV: oracle executable name validated proc=[m000]
      2013-04-18 23:08:40.005133 :9AAD751E:db_trace:ksb.c@2991:ksbsrv(): [10254:16:1670] KSBSRV: starting to wait for 12000 seconds
      2013-04-18 23:10:45.591710 :9AADA98B:db_trace:ksb.c@2999:ksbsrv(): [10254:16:1670] KSBSRV: timed out while waiting
      2013-04-18 23:10:45.591714 :9AADA98C:db_trace:ksb.c@3055:ksbsrv(): [10254:16:1670] KSBSRV: end callback function called
        • 1. Re: ORA-00445: background process "m000" did not start after 120 seconds
          DK2010
          Hi,

          Welcome to the forum.

          Have you checked the metalink id

          ORA-00445 Is Reported While Waiting Too Long To Spawn A Process [ID 1382349.1]

          It says
          //////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////
          
          Bug 7377880: STARETL: ORA-00445: BACKGROUND PROCESS "M000" DID NOT START AFTER 120 SECONDS
          --> Status 32 - Not a Bug
            The problem appears to be that you have too much activity on your machine;
            the fix is to shut down some of the activity.
          
          //////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////////
          • 2. Re: ORA-00445: background process "m000" did not start after 120 seconds
            emersonmartins8
            Thanks.

            All right!

            The server being managed by the AMM.But I need to change it to be managed by SGA_TARGET .. Which metric to use memory for the parameters to improve performance and stop errors.

            SGA_MAX_SIZE and SGA_TARGET

            Oracle Database 11g Standard
            32 GB
            Single Instance
            Windows Server 2008