Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250917220010\orcl_w000_22456_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:35935M/63366M, Ph+PgF:40284M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 22456, image: ORACLE.EXE (W000) *** 2025-09-17 22:00:10.864 *** SESSION ID:(201.15001) 2025-09-17 22:00:10.864 *** 2025-09-17 22:00:10.864 Process diagnostic dump for ORACLE.EXE (W000), OS id=22456, pid: 25, proc_ser: 128, sid: 201, sess_ser: 15001 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.192141 sec, exc=0.192141 sec, total=0.192141 sec wait times: max=5.000000 sec, heur=30.215109 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.000029 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=5.003636 sec, exc=5.003636 sec, total=5.003636 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=5.004618 sec, exc=5.004618 sec, total=5.004618 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=5.004625 sec, exc=5.004625 sec, total=5.004625 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=5.008326 sec, exc=5.008326 sec, total=5.008326 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=5.000278 sec, exc=5.000278 sec, total=5.000278 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=5.001321 sec, exc=5.001321 sec, total=5.001321 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.004127 sec of elapsed time 6: waited for 'class slave wait' slave id=0x12589950, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 201 serial 15001 --------------------------------------------------- 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 --------------------------------------------------- [31 samples, 21:59:40 - 22:00:10] idle wait at each sample [session created at: 21:59:40] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-17 22:00:10.864 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 22456, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-17 19:29:21.084174 :CEC23F26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 19:29:21.084220 :CEC23F30:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 19:30:10.237472 :CEC24EF1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-17 19:39:22.341558 :CEC3039B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-17 19:49:23.229891 :CEC3C584:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 19:49:23.563664 :CEC3C5E1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 19:49:23.559 2025-09-17 19:49:23.563666 :CEC3C5E2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16724 pso_num=25 pso_serial#=114 2025-09-17 19:49:23.563800 :CEC3C5E3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16724 osp_idx=23 osp_ver=303067 osp_pg=0 (spawn #602205) 2025-09-17 19:49:23.563801 :CEC3C5E4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 19:49:23.563801 :CEC3C5E5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=38 time=2524214862 2025-09-17 19:49:23.563801 :CEC3C5E6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 19:49:23.563802 :CEC3C5E7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 19:49:23.563802 :CEC3C5E8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 19:49:23.563802 :CEC3C5E9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 19:49:23.563803 :CEC3C5EA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 19:49:23.564293 :CEC3C5EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 19:49:23.564338 :CEC3C5F3:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 19:59:24.425416 :CEC488FB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 19:59:24.805487 :CEC48988:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 19:59:24.800 2025-09-17 19:59:24.805490 :CEC48989:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25268 pso_num=25 pso_serial#=115 2025-09-17 19:59:24.805665 :CEC4898A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25268 osp_idx=23 osp_ver=303068 osp_pg=0 (spawn #602228) 2025-09-17 19:59:24.805666 :CEC4898B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 19:59:24.805666 :CEC4898C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=39 time=2524816112 2025-09-17 19:59:24.805666 :CEC4898D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 19:59:24.805666 :CEC4898E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 19:59:24.805667 :CEC4898F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 19:59:24.805667 :CEC48990:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 19:59:24.805667 :CEC48991:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 19:59:24.806227 :CEC48992:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 19:59:24.806283 :CEC48998:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 20:09:25.690480 :CEC556E6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 20:09:26.210191 :CEC55771:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 20:09:26.205 2025-09-17 20:09:26.210193 :CEC55772:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24780 pso_num=25 pso_serial#=116 2025-09-17 20:09:26.210337 :CEC55773:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24780 osp_idx=23 osp_ver=303069 osp_pg=0 (spawn #602254) 2025-09-17 20:09:26.210339 :CEC55774:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 20:09:26.210340 :CEC55775:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=40 time=2525417518 2025-09-17 20:09:26.210340 :CEC55776:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 20:09:26.210340 :CEC55777:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 20:09:26.210341 :CEC55778:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 20:09:26.210341 :CEC55779:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 20:09:26.210342 :CEC5577A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 20:09:26.210853 :CEC5577B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 20:09:26.210899 :CEC55782:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 20:19:27.097557 :CEC61A89:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 20:19:27.539025 :CEC61B01:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 20:19:27.534 2025-09-17 20:19:27.539028 :CEC61B02:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24568 pso_num=25 pso_serial#=117 2025-09-17 20:19:27.539174 :CEC61B03:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24568 osp_idx=23 osp_ver=303070 osp_pg=0 (spawn #602276) 2025-09-17 20:19:27.539174 :CEC61B04:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 20:19:27.539175 :CEC61B05:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=41 time=2526018846 2025-09-17 20:19:27.539175 :CEC61B06:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 20:19:27.539176 :CEC61B07:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 20:19:27.539176 :CEC61B08:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 20:19:27.539177 :CEC61B09:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 20:19:27.539177 :CEC61B0A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 20:19:27.539685 :CEC61B0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 20:19:27.539732 :CEC61B13:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 20:29:28.444655 :CEC6DEB3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 20:29:28.814002 :CEC6DF1F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 20:29:28.809 2025-09-17 20:29:28.814004 :CEC6DF20:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18824 pso_num=25 pso_serial#=118 2025-09-17 20:29:28.814142 :CEC6DF21:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18824 osp_idx=23 osp_ver=303071 osp_pg=0 (spawn #602299) 2025-09-17 20:29:28.814143 :CEC6DF22:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 20:29:28.814144 :CEC6DF23:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=42 time=2526620112 2025-09-17 20:29:28.814144 :CEC6DF24:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 20:29:28.814144 :CEC6DF25:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 20:29:28.814145 :CEC6DF26:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 20:29:28.814145 :CEC6DF27:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 20:29:28.814145 :CEC6DF28:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 20:29:28.814688 :CEC6DF29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 20:29:28.814751 :CEC6DF32:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 20:39:29.648689 :CEC7A31E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 20:39:30.138204 :CEC7A374:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 20:39:30.133 2025-09-17 20:39:30.138206 :CEC7A375:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20784 pso_num=25 pso_serial#=119 2025-09-17 20:39:30.138345 :CEC7A376:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20784 osp_idx=23 osp_ver=303072 osp_pg=0 (spawn #602322) 2025-09-17 20:39:30.138345 :CEC7A377:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 20:39:30.138346 :CEC7A378:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=43 time=2527221440 2025-09-17 20:39:30.138346 :CEC7A379:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 20:39:30.138346 :CEC7A37A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 20:39:30.138347 :CEC7A37B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 20:39:30.138347 :CEC7A37C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 20:39:30.138348 :CEC7A37D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 20:39:30.138879 :CEC7A37E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 20:39:30.138928 :CEC7A385:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 20:49:31.112376 :CEC868CA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 20:49:31.252093 :CEC868CD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 20:49:31.237 2025-09-17 20:49:31.252095 :CEC868CE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21620 pso_num=25 pso_serial#=120 2025-09-17 20:49:31.252259 :CEC868CF:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21620 (legacy spawn) 2025-09-17 20:49:31.259439 :CEC868D0: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-09-17 20:49:31.259638 :CEC868D5: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-09-17 20:49:31.260572 :CEC868DA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 20:49:31.270135 :CEC868DD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 20:49:31.269 2025-09-17 20:49:31.270137 :CEC868DE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14024 pso_num=25 pso_serial#=121 2025-09-17 20:49:31.270290 :CEC868DF:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14024 (legacy spawn) 2025-09-17 20:49:31.277282 :CEC868E0: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-09-17 20:49:31.277455 :CEC868E8: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-09-17 20:49:31.278393 :CEC868EA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 20:49:31.617122 :CEC8695A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 20:49:31.612 2025-09-17 20:49:31.617124 :CEC8695B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20960 pso_num=25 pso_serial#=122 2025-09-17 20:49:31.617261 :CEC8695C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20960 osp_idx=23 osp_ver=303075 osp_pg=0 (spawn #602344) 2025-09-17 20:49:31.617262 :CEC8695D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 20:49:31.617262 :CEC8695E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=44 time=2527822924 2025-09-17 20:49:31.617262 :CEC8695F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 20:49:31.617263 :CEC86960:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 20:49:31.617263 :CEC86961:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 20:49:31.617264 :CEC86962:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 20:49:31.617264 :CEC86963:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 20:49:31.617781 :CEC86964:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 20:49:31.617827 :CEC8696C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 20:59:32.487897 :CEC92D53:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 20:59:32.916555 :CEC92DC6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 20:59:32.910 2025-09-17 20:59:32.916557 :CEC92DC7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20504 pso_num=25 pso_serial#=123 2025-09-17 20:59:32.916699 :CEC92DC8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20504 osp_idx=23 osp_ver=303076 osp_pg=0 (spawn #602368) 2025-09-17 20:59:32.916699 :CEC92DC9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 20:59:32.916700 :CEC92DCA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=45 time=2528424221 2025-09-17 20:59:32.916700 :CEC92DCB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 20:59:32.916701 :CEC92DCC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 20:59:32.916701 :CEC92DCD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 20:59:32.916702 :CEC92DCE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 20:59:32.916702 :CEC92DCF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 20:59:32.917426 :CEC92DD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 20:59:32.917472 :CEC92DD9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 21:09:33.736244 :CEC9FCF1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 21:09:34.194076 :CEC9FD90:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 21:09:34.189 2025-09-17 21:09:34.194077 :CEC9FD91:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24236 pso_num=25 pso_serial#=124 2025-09-17 21:09:34.194213 :CEC9FD92:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24236 osp_idx=23 osp_ver=303077 osp_pg=0 (spawn #602394) 2025-09-17 21:09:34.194214 :CEC9FD93:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 21:09:34.194215 :CEC9FD94:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=46 time=2529025502 2025-09-17 21:09:34.194215 :CEC9FD95:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 21:09:34.194216 :CEC9FD96:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 21:09:34.194216 :CEC9FD97:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 21:09:34.194216 :CEC9FD98:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 21:09:34.194217 :CEC9FD99:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 21:09:34.194772 :CEC9FD9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 21:09:34.194819 :CEC9FDA3:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 21:19:35.091145 :CECAC13E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 21:19:35.502662 :CECAC1C2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 21:19:35.497 2025-09-17 21:19:35.502664 :CECAC1C3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23556 pso_num=25 pso_serial#=125 2025-09-17 21:19:35.502803 :CECAC1C4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23556 osp_idx=23 osp_ver=303078 osp_pg=0 (spawn #602416) 2025-09-17 21:19:35.502803 :CECAC1C5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 21:19:35.502804 :CECAC1C6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=47 time=2529626799 2025-09-17 21:19:35.502804 :CECAC1C7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 21:19:35.502805 :CECAC1C8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 21:19:35.502805 :CECAC1C9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 21:19:35.502805 :CECAC1CA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 21:19:35.502806 :CECAC1CB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 21:19:35.503432 :CECAC1CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 21:19:35.503480 :CECAC1D4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 21:29:36.319277 :CECB8610:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 21:29:36.855240 :CECB8690:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 21:29:36.850 2025-09-17 21:29:36.855242 :CECB8691:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23364 pso_num=25 pso_serial#=126 2025-09-17 21:29:36.855382 :CECB8692:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23364 osp_idx=23 osp_ver=303079 osp_pg=0 (spawn #602439) 2025-09-17 21:29:36.855383 :CECB8693:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 21:29:36.855383 :CECB8694:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=48 time=2530228158 2025-09-17 21:29:36.855383 :CECB8695:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 21:29:36.855384 :CECB8696:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 21:29:36.855384 :CECB8697:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 21:29:36.855385 :CECB8698:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 21:29:36.855385 :CECB8699:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 21:29:36.855927 :CECB869A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 21:29:36.855976 :CECB86A2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 21:30:11.139720 :CECB9155:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-17 21:39:38.102025 :CECC4A5C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-17 21:49:38.998226 :CECD0C19:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 21:49:39.410304 :CECD0C9A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 21:49:39.405 2025-09-17 21:49:39.410306 :CECD0C9B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25440 pso_num=25 pso_serial#=127 2025-09-17 21:49:39.410443 :CECD0C9C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25440 osp_idx=23 osp_ver=303080 osp_pg=0 (spawn #602483) 2025-09-17 21:49:39.410444 :CECD0C9D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 21:49:39.410444 :CECD0C9E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=49 time=2531430721 2025-09-17 21:49:39.410444 :CECD0C9F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 21:49:39.410445 :CECD0CA0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 21:49:39.410445 :CECD0CA1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 21:49:39.410446 :CECD0CA2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 21:49:39.410446 :CECD0CA3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 21:49:39.410977 :CECD0CA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 21:49:39.411023 :CECD0CAC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 21:59:40.315851 :CECDCE43:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 21:59:40.648606 :CECDCECF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 21:59:40.643 2025-09-17 21:59:40.648608 :CECDCED0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22456 pso_num=25 pso_serial#=128 2025-09-17 21:59:40.648754 :CECDCED1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22456 osp_idx=23 osp_ver=303081 osp_pg=0 (spawn #602506) 2025-09-17 21:59:40.648755 :CECDCED2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 21:59:40.648755 :CECDCED3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=50 time=2532031955 2025-09-17 21:59:40.648756 :CECDCED4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 21:59:40.648756 :CECDCED5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 21:59:40.648756 :CECDCED6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 21:59:40.648757 :CECDCED7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 21:59:40.648757 :CECDCED8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 21:59:40.649372 :CECDCED9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 21:59:40.649426 :CECDCEE0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 22456, W000)