Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250616220004\orcl_w000_16108_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:36588M/63366M, Ph+PgF:43025M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16108, image: ORACLE.EXE (W000) *** 2025-06-16 22:00:04.911 *** SESSION ID:(201.27109) 2025-06-16 22:00:04.911 *** 2025-06-16 22:00:04.911 Process diagnostic dump for ORACLE.EXE (W000), OS id=16108, pid: 25, proc_ser: 35, sid: 201, sess_ser: 27109 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.584902 sec, exc=0.584902 sec, total=0.584902 sec wait times: max=5.000000 sec, heur=25.612518 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.000023 sec since current wait 0: 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.002538 sec, exc=5.002538 sec, total=5.002538 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 1: 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.010856 sec, exc=5.010856 sec, total=5.010856 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 2: 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.014731 sec, exc=5.014731 sec, total=5.014731 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: 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.004616 sec, exc=5.004616 sec, total=5.004616 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=4.994758 sec, exc=4.994758 sec, total=4.994758 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.004002 sec of elapsed time 5: waited for 'class slave wait' slave id=0x1243c8d0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000040 sec, exc=0.000040 sec, total=0.000040 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 27109 --------------------------------------------------- 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 --------------------------------------------------- [26 samples, 21:59:39 - 22:00:04] idle wait at each sample [session created at: 21:59:39] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-16 22:00:04.911 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16108, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-16 19:29:21.217037 :A595C43A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 19:29:21.217037 :A595C43B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 19:29:21.217583 :A595C43C: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-06-16 19:29:21.217629 :A595C443: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-06-16 19:39:22.035036 :A5968A6D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 19:39:22.617798 :A5968AAA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 19:39:22.613 2025-06-16 19:39:22.617800 :A5968AAB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16740 pso_num=25 pso_serial#=22 2025-06-16 19:39:22.617932 :A5968AAC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16740 osp_idx=23 osp_ver=159509 osp_pg=0 (spawn #292166) 2025-06-16 19:39:22.617933 :A5968AAD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 19:39:22.617933 :A5968AAE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=58 time=3078505813 2025-06-16 19:39:22.617933 :A5968AAF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 19:39:22.617934 :A5968AB0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 19:39:22.617934 :A5968AB1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 19:39:22.617934 :A5968AB2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 19:39:22.617934 :A5968AB3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 19:39:22.618411 :A5968AB4: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-06-16 19:39:22.618460 :A5968ABE: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-06-16 19:49:23.358709 :A5975001:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 19:49:23.800914 :A597505A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 19:49:23.796 2025-06-16 19:49:23.800916 :A597505B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12532 pso_num=25 pso_serial#=23 2025-06-16 19:49:23.801053 :A597505C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12532 osp_idx=23 osp_ver=159510 osp_pg=0 (spawn #292188) 2025-06-16 19:49:23.801054 :A597505D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 19:49:23.801054 :A597505E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=59 time=3079106985 2025-06-16 19:49:23.801055 :A597505F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 19:49:23.801055 :A5975060:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 19:49:23.801055 :A5975061:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 19:49:23.801055 :A5975062:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 19:49:23.801056 :A5975063:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 19:49:23.801552 :A5975064: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-06-16 19:49:23.801596 :A597506C: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-06-16 19:59:24.569321 :A59815DD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 19:59:25.073937 :A598164E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 19:59:25.069 2025-06-16 19:59:25.073939 :A598164F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10976 pso_num=25 pso_serial#=24 2025-06-16 19:59:25.074077 :A5981650:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10976 osp_idx=23 osp_ver=159511 osp_pg=0 (spawn #292211) 2025-06-16 19:59:25.074078 :A5981651:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 19:59:25.074079 :A5981652:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=60 time=3079708266 2025-06-16 19:59:25.074080 :A5981653:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 19:59:25.074080 :A5981654:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 19:59:25.074080 :A5981655:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 19:59:25.074081 :A5981656:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 19:59:25.074081 :A5981657:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 19:59:25.074587 :A5981658: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-06-16 19:59:25.074636 :A5981660: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-06-16 20:09:25.919932 :A598E390:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 20:09:26.362213 :A598E3F6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 20:09:26.357 2025-06-16 20:09:26.362215 :A598E3F7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16948 pso_num=25 pso_serial#=25 2025-06-16 20:09:26.362348 :A598E3F8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16948 osp_idx=23 osp_ver=159512 osp_pg=0 (spawn #292237) 2025-06-16 20:09:26.362349 :A598E3F9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 20:09:26.362350 :A598E3FA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=61 time=3080309547 2025-06-16 20:09:26.362350 :A598E3FB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 20:09:26.362351 :A598E3FC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 20:09:26.362351 :A598E3FD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 20:09:26.362351 :A598E3FE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 20:09:26.362352 :A598E3FF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 20:09:26.362862 :A598E400: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-06-16 20:09:26.362912 :A598E408: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-06-16 20:15:22.905284 :A599597F: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-06-16 20:19:27.639639 :A599ACB3: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-06-16 20:29:28.450695 :A59A7209:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 20:29:28.777919 :A59A7251:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 20:29:28.773 2025-06-16 20:29:28.777921 :A59A7252:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16724 pso_num=25 pso_serial#=26 2025-06-16 20:29:28.778057 :A59A7253:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16724 osp_idx=23 osp_ver=159513 osp_pg=0 (spawn #292281) 2025-06-16 20:29:28.778058 :A59A7254:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 20:29:28.778058 :A59A7255:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=62 time=3081511969 2025-06-16 20:29:28.778059 :A59A7256:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 20:29:28.778059 :A59A7257:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 20:29:28.778059 :A59A7258:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 20:29:28.778060 :A59A7259:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 20:29:28.778060 :A59A725A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 20:29:28.778537 :A59A725B: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-06-16 20:29:28.778589 :A59A7265: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-06-16 20:39:29.533732 :A59B38A0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 20:39:29.976010 :A59B38F1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 20:39:29.971 2025-06-16 20:39:29.976012 :A59B38F2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13416 pso_num=25 pso_serial#=27 2025-06-16 20:39:29.976146 :A59B38F3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13416 osp_idx=23 osp_ver=159514 osp_pg=0 (spawn #292304) 2025-06-16 20:39:29.976147 :A59B38F4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 20:39:29.976147 :A59B38F5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=63 time=3082113172 2025-06-16 20:39:29.976148 :A59B38F6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 20:39:29.976148 :A59B38F7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 20:39:29.976148 :A59B38F8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 20:39:29.976149 :A59B38F9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 20:39:29.976149 :A59B38FA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 20:39:29.976663 :A59B38FB: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-06-16 20:39:29.976708 :A59B3903: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-06-16 20:49:30.739675 :A59BFDE6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 20:49:31.042714 :A59BFE40:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 20:49:31.038 2025-06-16 20:49:31.042716 :A59BFE41:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15432 pso_num=25 pso_serial#=28 2025-06-16 20:49:31.042852 :A59BFE42:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15432 osp_idx=23 osp_ver=159515 osp_pg=0 (spawn #292326) 2025-06-16 20:49:31.042853 :A59BFE43:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 20:49:31.042853 :A59BFE44:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=64 time=3082714235 2025-06-16 20:49:31.042854 :A59BFE45:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 20:49:31.042854 :A59BFE46:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 20:49:31.042854 :A59BFE47:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 20:49:31.042855 :A59BFE48:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 20:49:31.042855 :A59BFE49:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 20:49:31.043341 :A59BFE4A: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-06-16 20:49:31.043386 :A59BFE51: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-06-16 20:59:31.941167 :A59CC3DA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 20:59:32.258643 :A59CC426:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 20:59:32.254 2025-06-16 20:59:32.258645 :A59CC427:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14260 pso_num=25 pso_serial#=29 2025-06-16 20:59:32.258799 :A59CC428:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14260 osp_idx=23 osp_ver=159516 osp_pg=0 (spawn #292349) 2025-06-16 20:59:32.258800 :A59CC429:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 20:59:32.258800 :A59CC42A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=65 time=3083315454 2025-06-16 20:59:32.258801 :A59CC42B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 20:59:32.258801 :A59CC42C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 20:59:32.258801 :A59CC42D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 20:59:32.258802 :A59CC42E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 20:59:32.258803 :A59CC42F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 20:59:32.259338 :A59CC430: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-06-16 20:59:32.259382 :A59CC437: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-06-16 21:09:33.110369 :A59D9282:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 21:09:33.443209 :A59D92C8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 21:09:33.439 2025-06-16 21:09:33.443211 :A59D92C9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12152 pso_num=25 pso_serial#=30 2025-06-16 21:09:33.443353 :A59D92CA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12152 osp_idx=23 osp_ver=159517 osp_pg=0 (spawn #292375) 2025-06-16 21:09:33.443354 :A59D92CB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:09:33.443354 :A59D92CC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=66 time=3083916641 2025-06-16 21:09:33.443355 :A59D92CD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:09:33.443355 :A59D92CE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:09:33.443355 :A59D92CF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:09:33.443355 :A59D92D0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:09:33.443355 :A59D92D1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:09:33.443854 :A59D92D2: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-06-16 21:09:33.443903 :A59D92D9: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-06-16 21:19:34.322367 :A59E57C4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 21:19:34.608249 :A59E57FB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 21:19:34.604 2025-06-16 21:19:34.608251 :A59E57FC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14740 pso_num=25 pso_serial#=31 2025-06-16 21:19:34.608386 :A59E57FD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14740 osp_idx=23 osp_ver=159518 osp_pg=0 (spawn #292397) 2025-06-16 21:19:34.608387 :A59E57FE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:19:34.608388 :A59E57FF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=67 time=3084517797 2025-06-16 21:19:34.608389 :A59E5800:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:19:34.608389 :A59E5801:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:19:34.608389 :A59E5802:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:19:34.608390 :A59E5803:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:19:34.608390 :A59E5804:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:19:34.608890 :A59E5805: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-06-16 21:19:34.608934 :A59E580D: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-06-16 21:29:35.467720 :A59F1CFE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 21:29:35.707491 :A59F1D45:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 21:29:35.702 2025-06-16 21:29:35.707492 :A59F1D46:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15264 pso_num=25 pso_serial#=32 2025-06-16 21:29:35.707645 :A59F1D47:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15264 osp_idx=23 osp_ver=159519 osp_pg=0 (spawn #292420) 2025-06-16 21:29:35.707645 :A59F1D48:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:29:35.707646 :A59F1D49:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=68 time=3085118891 2025-06-16 21:29:35.707646 :A59F1D4A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:29:35.707647 :A59F1D4B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:29:35.707647 :A59F1D4C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:29:35.707647 :A59F1D4D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:29:35.707648 :A59F1D4E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:29:35.708238 :A59F1D4F: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-06-16 21:29:35.708312 :A59F1D59: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-06-16 21:39:36.579450 :A59FE329:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 21:39:36.927907 :A59FE363:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 21:39:36.923 2025-06-16 21:39:36.927908 :A59FE364:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13024 pso_num=25 pso_serial#=33 2025-06-16 21:39:36.928052 :A59FE365:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13024 osp_idx=23 osp_ver=159520 osp_pg=0 (spawn #292443) 2025-06-16 21:39:36.928053 :A59FE366:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:39:36.928053 :A59FE367:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=69 time=3085720125 2025-06-16 21:39:36.928054 :A59FE368:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:39:36.928054 :A59FE369:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:39:36.928054 :A59FE36A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:39:36.928055 :A59FE36B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:39:36.928055 :A59FE36C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:39:36.928557 :A59FE36D: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-06-16 21:39:36.928602 :A59FE376: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-06-16 21:49:37.790538 :A5A0A917:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 21:49:38.092281 :A5A0A961:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 21:49:38.086 2025-06-16 21:49:38.092285 :A5A0A962:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12992 pso_num=25 pso_serial#=34 2025-06-16 21:49:38.092434 :A5A0A963:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12992 osp_idx=23 osp_ver=159521 osp_pg=0 (spawn #292465) 2025-06-16 21:49:38.092435 :A5A0A964:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:49:38.092435 :A5A0A965:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=70 time=3086321282 2025-06-16 21:49:38.092436 :A5A0A966:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:49:38.092436 :A5A0A967:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:49:38.092437 :A5A0A968:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:49:38.092437 :A5A0A969:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:49:38.092437 :A5A0A96A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:49:38.092950 :A5A0A96B: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-06-16 21:49:38.092995 :A5A0A972: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-06-16 21:59:39.045210 :A5A16EA0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-16 21:59:39.299988 :A5A16EDA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-16 21:59:39.295 2025-06-16 21:59:39.299990 :A5A16EDB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16108 pso_num=25 pso_serial#=35 2025-06-16 21:59:39.300143 :A5A16EDC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16108 osp_idx=23 osp_ver=159522 osp_pg=0 (spawn #292488) 2025-06-16 21:59:39.300144 :A5A16EDD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:59:39.300145 :A5A16EDE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=71 time=3086922485 2025-06-16 21:59:39.300145 :A5A16EDF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:59:39.300145 :A5A16EE0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:59:39.300146 :A5A16EE1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:59:39.300146 :A5A16EE2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:59:39.300146 :A5A16EE3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:59:39.300672 :A5A16EE4: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-06-16 21:59:39.300717 :A5A16EEC: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: 16108, W000)