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 case 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 case 4317361 (ORA 445) ========
[TOC00003]
----- Beginning of Customized case 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
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 for Me (Login required).Search for additional results
Visit SAP Support Portal's SAP Notes and KBA Search.