Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250824081207\orcl_j001_19396_bucket.trc Oracle Database 11g Enterprise Edition Release 11.2.0.1.0 - 64bit Production With the Partitioning, OLAP, Data Mining and Real Application Testing options Windows NT Version V6.2 CPU : 32 - type 8664, 16 Physical Cores Process Affinity : 0x0x0000000000000000 Memory (Avail/Total): Ph:35248M/63366M, Ph+PgF:40377M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 19396, image: ORACLE.EXE (J001) *** 2025-08-24 08:12:07.626 *** SESSION ID:(201.44007) 2025-08-24 08:12:07.626 *** 2025-08-24 08:12:07.626 Process diagnostic dump for ORACLE.EXE (J001), OS id=19396, pid: 25, proc_ser: 41, sid: 201, sess_ser: 44007 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19396 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=0.305559 sec, exc=0.305559 sec, total=0.305559 sec wait times: max=0.500000 sec, heur=27.869761 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=0.499974 sec, exc=0.499974 sec, total=0.499974 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=0.500014 sec, exc=0.500014 sec, total=0.500014 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=0.510104 sec, exc=0.510104 sec, total=0.510104 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.510084 sec, exc=0.510084 sec, total=0.510084 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 201 serial 44007 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [28 samples, 08:11:39 - 08:12:07] idle wait at each sample [session created at: 08:11:39] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-24 08:12:07.626 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 19396, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-24 07:58:38.474086 :C3ED2F13:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16248 pso_num=25 pso_serial#=27 2025-08-24 07:58:38.474240 :C3ED2F14:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16248 osp_idx=23 osp_ver=257689 osp_pg=0 (spawn #520540) 2025-08-24 07:58:38.474241 :C3ED2F15:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 07:58:38.474241 :C3ED2F16:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=195 time=408020471 2025-08-24 07:58:38.474241 :C3ED2F17:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 07:58:38.474242 :C3ED2F18:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 07:58:38.474242 :C3ED2F19:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 07:58:38.474242 :C3ED2F1A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 07:58:38.474243 :C3ED2F1B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 07:58:38.474736 :C3ED2F1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 07:59:08.565138 :C3ED38F0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 07:59:38.597441 :C3ED4301:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 07:59:38.586 2025-08-24 07:59:38.597443 :C3ED4302:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21876 pso_num=25 pso_serial#=28 2025-08-24 07:59:38.597578 :C3ED4303:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21876 osp_idx=23 osp_ver=257690 osp_pg=0 (spawn #520542) 2025-08-24 07:59:38.597579 :C3ED4304:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 07:59:38.597579 :C3ED4305:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=196 time=408080596 2025-08-24 07:59:38.597580 :C3ED4306:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 07:59:38.597580 :C3ED4307:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 07:59:38.597580 :C3ED4308:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 07:59:38.597581 :C3ED4309:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 07:59:38.597581 :C3ED430A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 07:59:38.598065 :C3ED430B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 07:59:53.617434 :C3ED4890:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-24 07:59:53.617467*:C3ED4891:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1755993593 eid=17021327 ctx=0x000000077BD98A08 cwh=0x0000000026B901B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-24 07:59:53.617467*:C3ED4892:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 pubTabIdxCnt=0 2025-08-24 07:59:53.617467*:C3ED4893:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-08-24 07:59:53.617467*:C3ED4894:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-08-24 07:59:53.617467*:C3ED4895:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 xsc->flg4=65792 flags=2 2025-08-24 07:59:53.617467*:C3ED4896:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-08-24 07:59:53.618040 :C3ED4897:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 07:59:53.623552 :C3ED489F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 07:59:53.624532 :C3ED48A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 07:59:53.624666 :C3ED48B0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 08:00:23.704691 :C3ED53D1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:00:38.677933 :C3ED58D5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:00:38.666 2025-08-24 08:00:38.677935 :C3ED58D6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20996 pso_num=25 pso_serial#=29 2025-08-24 08:00:38.678068 :C3ED58D7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20996 osp_idx=23 osp_ver=257691 osp_pg=0 (spawn #520546) 2025-08-24 08:00:38.678069 :C3ED58D8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:00:38.678069 :C3ED58D9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=197 time=408140674 2025-08-24 08:00:38.678070 :C3ED58DA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:00:38.678070 :C3ED58DB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:00:38.678071 :C3ED58DC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:00:38.678071 :C3ED58DD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:00:38.678071 :C3ED58DE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:00:38.678535 :C3ED58DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:01:08.779658 :C3ED6398:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:01:38.770012 :C3ED6DCD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:01:38.758 2025-08-24 08:01:38.770013 :C3ED6DCE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20684 pso_num=25 pso_serial#=30 2025-08-24 08:01:38.770144 :C3ED6DCF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20684 osp_idx=23 osp_ver=257692 osp_pg=0 (spawn #520548) 2025-08-24 08:01:38.770145 :C3ED6DD0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:01:38.770145 :C3ED6DD1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=198 time=408200768 2025-08-24 08:01:38.770146 :C3ED6DD2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:01:38.770146 :C3ED6DD3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:01:38.770147 :C3ED6DD4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:01:38.770147 :C3ED6DD5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:01:38.770147 :C3ED6DD6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:01:38.770683 :C3ED6DD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:02:08.866822 :C3ED7823:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:02:38.878391 :C3ED8223:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:02:38.866 2025-08-24 08:02:38.878393 :C3ED8224:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17932 pso_num=25 pso_serial#=31 2025-08-24 08:02:38.878515 :C3ED8225:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17932 osp_idx=23 osp_ver=257693 osp_pg=0 (spawn #520550) 2025-08-24 08:02:38.878515 :C3ED8226:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:02:38.878516 :C3ED8227:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=199 time=408260877 2025-08-24 08:02:38.878516 :C3ED8228:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:02:38.878516 :C3ED8229:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:02:38.878517 :C3ED822A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:02:38.878517 :C3ED822B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:02:38.878517 :C3ED822C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:02:38.879024 :C3ED822D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:03:08.966191 :C3ED8C1B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:03:20.833152 :C3ED902F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:03:20.821 2025-08-24 08:03:20.833154 :C3ED9030:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22148 pso_num=25 pso_serial#=32 2025-08-24 08:03:20.833296 :C3ED9031:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22148 osp_idx=23 osp_ver=257694 osp_pg=0 (spawn #520552) 2025-08-24 08:03:20.833297 :C3ED9032:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:03:20.833298 :C3ED9033:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=200 time=408302830 2025-08-24 08:03:20.833298 :C3ED9034:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:03:20.833299 :C3ED9035:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:03:20.833299 :C3ED9036:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:03:20.833300 :C3ED9037:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:03:20.833300 :C3ED9038:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:03:20.833910 :C3ED9039:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:03:20.833974 :C3ED9040:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-24 08:03:20.842674 :C3ED9064:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:03:38.969741 :C3ED995F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:03:38.958 2025-08-24 08:03:38.969742 :C3ED9960:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22484 pso_num=25 pso_serial#=33 2025-08-24 08:03:38.969862 :C3ED9961:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22484 osp_idx=23 osp_ver=257695 osp_pg=0 (spawn #520554) 2025-08-24 08:03:38.969862 :C3ED9962:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:03:38.969863 :C3ED9963:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=201 time=408320971 2025-08-24 08:03:38.969863 :C3ED9964:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:03:38.969864 :C3ED9965:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:03:38.969864 :C3ED9966:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:03:38.969864 :C3ED9967:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:03:38.969865 :C3ED9968:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:03:38.970341 :C3ED9969:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:04:09.069540 :C3EDA385:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:04:39.075449 :C3EDAD95:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:04:39.064 2025-08-24 08:04:39.075451 :C3EDAD96:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6128 pso_num=25 pso_serial#=34 2025-08-24 08:04:39.075570 :C3EDAD97:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6128 osp_idx=23 osp_ver=257696 osp_pg=0 (spawn #520556) 2025-08-24 08:04:39.075571 :C3EDAD98:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:04:39.075572 :C3EDAD99:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=202 time=408381080 2025-08-24 08:04:39.075572 :C3EDAD9A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:04:39.075572 :C3EDAD9B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:04:39.075573 :C3EDAD9C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:04:39.075573 :C3EDAD9D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:04:39.075574 :C3EDAD9E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:04:39.076061 :C3EDAD9F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:05:09.145855 :C3EDB778:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:05:39.151498 :C3EDC749:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:05:39.140 2025-08-24 08:05:39.151501 :C3EDC74A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16832 pso_num=25 pso_serial#=35 2025-08-24 08:05:39.151639 :C3EDC74B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16832 osp_idx=23 osp_ver=257697 osp_pg=0 (spawn #520558) 2025-08-24 08:05:39.151640 :C3EDC74C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:05:39.151641 :C3EDC74D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=203 time=408441158 2025-08-24 08:05:39.151641 :C3EDC74E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:05:39.151642 :C3EDC74F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:05:39.151642 :C3EDC750:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:05:39.151642 :C3EDC751:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:05:39.151643 :C3EDC752:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:05:39.152125 :C3EDC753:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:06:09.248408 :C3EDD24E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:06:39.257404 :C3EDDC69:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:06:39.246 2025-08-24 08:06:39.257406 :C3EDDC6A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20448 pso_num=25 pso_serial#=36 2025-08-24 08:06:39.257522 :C3EDDC6B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20448 osp_idx=23 osp_ver=257698 osp_pg=0 (spawn #520560) 2025-08-24 08:06:39.257523 :C3EDDC6C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:06:39.257523 :C3EDDC6D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=204 time=408501268 2025-08-24 08:06:39.257523 :C3EDDC6E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:06:39.257524 :C3EDDC6F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:06:39.257524 :C3EDDC70:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:06:39.257524 :C3EDDC71:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:06:39.257524 :C3EDDC72:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:06:39.257987 :C3EDDC73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:07:09.333258 :C3EDE76D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:07:39.366558 :C3EDF112:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:07:39.355 2025-08-24 08:07:39.366560 :C3EDF113:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20488 pso_num=25 pso_serial#=37 2025-08-24 08:07:39.366695 :C3EDF114:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20488 osp_idx=23 osp_ver=257699 osp_pg=0 (spawn #520563) 2025-08-24 08:07:39.366695 :C3EDF115:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:07:39.366696 :C3EDF116:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=205 time=408561377 2025-08-24 08:07:39.366696 :C3EDF117:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:07:39.366697 :C3EDF118:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:07:39.366697 :C3EDF119:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:07:39.366697 :C3EDF11A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:07:39.366698 :C3EDF11B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:07:39.367179 :C3EDF11C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:08:09.465964 :C3EDFB1D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:08:39.445956 :C3EE05AE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:08:39.435 2025-08-24 08:08:39.445958 :C3EE05AF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6364 pso_num=25 pso_serial#=38 2025-08-24 08:08:39.446094 :C3EE05B0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6364 osp_idx=23 osp_ver=257700 osp_pg=0 (spawn #520566) 2025-08-24 08:08:39.446095 :C3EE05B1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:08:39.446096 :C3EE05B2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=206 time=408621455 2025-08-24 08:08:39.446097 :C3EE05B3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:08:39.446097 :C3EE05B4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:08:39.446097 :C3EE05B5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:08:39.446098 :C3EE05B6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:08:39.446098 :C3EE05B7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:08:39.446639 :C3EE05B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:09:09.508389 :C3EE0FA9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:09:39.530547 :C3EE19AA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:09:39.519 2025-08-24 08:09:39.530549 :C3EE19AB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22328 pso_num=25 pso_serial#=39 2025-08-24 08:09:39.530679 :C3EE19AC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22328 osp_idx=23 osp_ver=257701 osp_pg=0 (spawn #520568) 2025-08-24 08:09:39.530680 :C3EE19AD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:09:39.530680 :C3EE19AE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=207 time=408681533 2025-08-24 08:09:39.530681 :C3EE19AF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:09:39.530681 :C3EE19B0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:09:39.530681 :C3EE19B1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:09:39.530682 :C3EE19B2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:09:39.530682 :C3EE19B3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:09:39.531233 :C3EE19B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:09:54.548962 :C3EE1EE4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-24 08:10:24.630623 :C3EE28EE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:10:39.646752 :C3EE2DFF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:10:39.635 2025-08-24 08:10:39.646755 :C3EE2E00:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21680 pso_num=25 pso_serial#=40 2025-08-24 08:10:39.646907 :C3EE2E01:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21680 osp_idx=23 osp_ver=257702 osp_pg=0 (spawn #520570) 2025-08-24 08:10:39.646908 :C3EE2E02:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:10:39.646909 :C3EE2E03:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=208 time=408741643 2025-08-24 08:10:39.646909 :C3EE2E04:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:10:39.646909 :C3EE2E05:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:10:39.646910 :C3EE2E06:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:10:39.646910 :C3EE2E07:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:10:39.646910 :C3EE2E08:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:10:39.647391 :C3EE2E09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 08:11:09.748870 :C3EE37F5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-24 08:11:39.763743 :C3EE41F0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-24 08:11:39.752 2025-08-24 08:11:39.763745 :C3EE41F1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19396 pso_num=25 pso_serial#=41 2025-08-24 08:11:39.763858 :C3EE41F2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19396 osp_idx=23 osp_ver=257703 osp_pg=0 (spawn #520572) 2025-08-24 08:11:39.763859 :C3EE41F3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:11:39.763859 :C3EE41F4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=209 time=408801768 2025-08-24 08:11:39.763859 :C3EE41F5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:11:39.763860 :C3EE41F6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:11:39.763860 :C3EE41F7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:11:39.763860 :C3EE41F8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:11:39.763861 :C3EE41F9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:11:39.764332 :C3EE41FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 19396, J001)