SAP Knowledge Base Article - Preview

2983060 - ORA-00445: background process "m000" did

Symptom

You are facing below highlighted error in alert log :

 Most important line "unable to spawn jobq slave process" 

Alert log:
=====================================
Sun Sep 20 03:49:04 2020
Errors in file /oracle/mk1/saptrace/diag/rdbms/mk1/mk1/trace/mk1_mmon_10682558.trc (incident=4317361):
ORA-00445: background process "m000" did not start after 120 seconds
Incident details in: /oracle/mk1/saptrace/diag/rdbms/mk1/mk1/incident/incdir_4317361/mk1_mmon_10682558_i4317361.trc
Sun Sep 20 03:49:34 2020
Errors in file /oracle/mk1/saptrace/diag/rdbms/mk1/mk1/trace/mk1_cjq0_11665750.trc (incident=4299227):
ORA-00445: background process "J000" did not start after 30 seconds
Incident details in: /oracle/mk1/saptrace/diag/rdbms/mk1/mk1/incident/incdir_4299227/mk1_cjq0_11665750_i4299227.trc
Sun Sep 20 03:49:37 2020
kkjcre1p: unable to spawn jobq slave process
Sun Sep 20 03:49:37 2020

mk1_mmon_10682558_i4298831.trc ( MMON incident trace file)
=====================================
Oracle Database 12c Enterprise Edition Release 12.2.0.1.0 - 64bit Production
Build label: RDBMS_12.2.0.1.0_AIX.PPC64_170125.170330
ORACLE_HOME: /oracle/mk1/122
System name: AIX
Node name: parsap06
Release: 1
Version: 6
Machine: 00F9B2504C00
Instance name: mk1
Redo thread mounted by this instance: 1
Oracle process number: 35
Unix process pid: 10682558, image: oracle@parsap06 (MMON)
:
:
:

[TOC00000]
Jump to table of contents
Dump continued from file: /oracle/mk1/saptrace/diag/rdbms/mk1/mk1/trace/mk1_mmon_10682558.trc
[TOC00001]
ORA-00445: background process "m000" did not start after 120 seconds

[TOC00001-END]
[TOC00002]
========= Dump for incident 4317361 (ORA 445) ========
[TOC00003]
----- Beginning of Customized Incident Dump(s) -----
[TOC00004]
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 35 (osid: 10682558, MMON)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP:SID:SERIAL#: [EVENT#:PID] DATA
-------------------------------------------------------------------------------
2020-09-20 03:42:25.417 :FB82AAFC:db_trace:ksb.c@7218:ksbsrvn_opt():2592:26880: [10254:35] KSBSRV: enter for server = 504404261591759624
2020-09-20 03:42:25.417 :FB82AAFD:db_trace:ksb.c@7487:ksbsrv_precb_invoke():2592:26880: [10254:35] KSBSRV: obtained PR enqueue
2020-09-20 03:42:25.417 :FB82AAFE:db_trace:ksb.c@7549:ksbsrv_precb_invoke():2592:26880: [10254:35] KSBSRV: called start callback function
2020-09-20 03:42:25.417 :FB82AB01:db_trace:ksb.c@7326:ksbsrvn_opt():2592:26880: [10254:35] KSBSRV: oracle executable name validated proc=[m002]
2020-09-20 03:42:25.417 :FB82AB02:db_trace:ksb.c@7328:ksbsrvn_opt():2592:26880: [10254:35] KSBSRV: starting to wait for 12000 centiseconds
2020-09-20 03:42:25.465 :FB82AB29:db_trace:ksb.c@7640:ksbsrv_postcb_invoke():2592:26880: [10254:35] KSBSRV: end callback function called
2020-09-20 03:42:25.465 :FB82AB2A:db_trace:ksb.c@7431:ksbsrvn_opt():2592:26880: [10254:35] KSBSRV: done with active = 1
2020-09-20 03:42:25.471 :FB82AB40:db_trace:ksb.c@7218:ksbsrvn_opt():2592:26880: [10254:35] KSBSRV: enter for server = 504404261591756760
2020-09-20 03:42:25.471 :FB82AB41:db_trace:ksb.c@7487:ksbsrv_precb_invoke():2592:26880: [10254:35] KSBSRV: obtained PR enqueue
2020-09-20 03:42:25.471 :FB82AB42:db_trace:ksb.c@7549:ksbsrv_precb_invoke():2592:26880: [10254:35] KSBSRV: called start callback function
2020-09-20 03:42:25.471 :FB82AB45:db_trace:ksb.c@7326:ksbsrvn_opt():2592:26880: [10254:35] KSBSRV: oracle executable name validated proc=[m001]
2020-09-20 03:42:25.471 :FB82AB46:db_trace:ksb.c@7328:ksbsrvn_opt():2592:26880: [10254:35] KSBSRV: starting to wait for 12000 centiseconds
2020-09-20 03:42:25.623 :FB82AC22:db_trace:ksb.c@7640:ksbsrv_postcb_invoke():2592:26880: [10254:35] KSBSRV: end callback function called

mk1_mmon_10682558.trc ( MMON normal trace file)
=====================================================
*** 2020-09-20 03:53:34.548
Process diagnostic dump for M000, OS id=13959296
-------------------------------------------------------------------------------
os thread scheduling delay history: (sampling every 1.000000 secs)
0.000000 secs at [ 03:53:33 ]
NOTE: scheduling delay has not been sampled for 0.898829 secs
0.000000 secs from [ 03:53:29 - 03:53:34 ], 5 sec avg
0.000000 secs from [ 03:52:35 - 03:53:34 ], 1 min avg
0.000005 secs from [ 03:48:35 - 03:53:34 ], 5 min avg
loadavg : 5.84 6.53 6.92
swap info: free_mem = 2525.51M rsv = 750.00M
alloc = 10929.26M avail = 192000.00M swap_free = 181070.74M
F S UID PID PPID C PRI NI ADDR SZ WCHAN STIME TTY TIME CMD
240001 A oramk1 13959296 1 0 60 20 c4f740590 403512 f1000a01c1af29c8 03:52:04 - 0:00 ora_m000_mk1
Stack:
ERROR: stack open failed for 13959296 32243757
13959296: ora_m000_mk1
0x0900000000091c40 open(0xfffffffffffd094, 0x0, 0x1b6, 0x0, 0x0, 0x0, 0x11ec002d, 0x4) + 0x100
0x0900000000014044 _endopen(??, ??, ??, ??) + 0x504
0x090000000001480c fopen(??, ??) + 0x4c
0x0000000102d115ac ssOswFopen(??, ??) + 0x4c
0x00000001106f4d60 snlfohd(0xfffffffffffd348, 0x100000001, 0x120f6c080, 0x25, 0x120f33e08) + 0xe0
0x00000001106db80c IPRA.$nlpardfile(??, ??, ??, ??, ??, ??, ??) + 0x10c
0x00000001106db1b4 nlpains(??, ??, ??, ??, ??, ??, ??, ??) + 0x234
0x0000000103cb5488 nlstdipi(??) + 0x188
0x0000000103caecdc nlstdggo(??, ??, ??, ??, ??, ??, ??) + 0x4dc
0x0000000103cae7b8 nlstdgg(??, ??, ??, ??, ??) + 0x38
0x0000000103cb6254 nlstdgo(??, ??, ??, ??, ??, ??, ??, ??) + 0x294
0x0000000103ca2328 npinli(0x100000001) + 0xa8
0x0000000100006048 opiinit() + 0x1a8
0x0000000100000a9c opimai_real(0x300000003, 0xffffffffffff768) + 0xdc
0x000000010000372c ssthrdmain(0x0, 0x300000003, 0xffffffffffff768) + 0x1ec
0x0000000100000470 main(0x100000001, 0xffffffffffff850) + 0xf0
0x0000000111d5dae0 __start() + 0x70
:
:
:

<<ERROR STACK>>

ksedsts()+432<-kspol_12751_dump()+152<-dbgdaExecuteAction()+880<-dbgerRunAction()+104<-dbgerRunActions()+3140<-IPRA.$dbgexPhaseII()+1764<-dbgexProcessError()+1832<-IPRA.$dbgeExecuteForError()+116<-dbgePostErrorKGE()+2056<-dbkePostKGE_kgsf()+88<-IPRA.$kgeade()+444
<-kgerelv()+188<-kserecl0()+96<-IPRA.$ksqcmi()+2952<-ksqgtlctx()+3760<-ksqgelctx()+836<-ksbsrv_precb_invoke()+3128<-ksbsrvn_opt()+7436<-ksbsrv_opt()+84<-ksvspawn()+760<-ksvcreateex()+696<-ksvcreate()+20<-kebm_mmon_schedule_slave()+256<-dbkea_mmon_exec()+864
<-ksb_run_managed_action()+65772<-ksbabs()+35384<-kebm_mmon_main()+352<-ksbrdp()+3568<-opirip()+1624<-opidrv()+556<-sou2o()+184<-opimai_real()+240<-ssthrdmain()+460<-main()+208<-__start()+112Current Wait Stack:
Not in wait; last wait ended 0.003021 sec ago


Read more...

Environment

oracle version 12.2 patch version ≤ less than 12.2.0.1.200714 - 202008
OS AIX

it applies to any OS platform

Product

mySAP ERP 2004

Keywords

ORA-00445: background process "J000" did not start after 30 seconds
kkjcre1p: unable to spawn jobq slave process
KEBMSS_SPAWN_SLAVE , KBA , BC-DB-ORA , Oracle , Problem

About this page

This is a preview of a SAP Knowledge Base Article. Click more to access the full version on SAP ONE Support launchpad (Login required).

Search for additional results

Visit SAP Support Portal's SAP Notes and KBA Search.