Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250809080049\orcl_w000_17820_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:35620M/63366M, Ph+PgF:41074M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 17820, image: ORACLE.EXE (W000) *** 2025-08-09 08:00:49.608 *** SESSION ID:(201.54873) 2025-08-09 08:00:49.608 *** 2025-08-09 08:00:49.608 Process diagnostic dump for ORACLE.EXE (W000), OS id=17820, pid: 25, proc_ser: 234, sid: 201, sess_ser: 54873 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=128 seq_num=129 snap_id=1 wait times: snap=0.650893 sec, exc=0.650893 sec, total=0.650893 sec wait times: max=5.000000 sec, heur=10 min 22 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.000024 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=127 seq_num=128 snap_id=1 wait times: snap=5.010348 sec, exc=5.010348 sec, total=5.010348 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=126 seq_num=127 snap_id=1 wait times: snap=5.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=125 seq_num=126 snap_id=1 wait times: snap=5.014762 sec, exc=5.014762 sec, total=5.014762 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=124 seq_num=125 snap_id=1 wait times: snap=5.004615 sec, exc=5.004615 sec, total=5.004615 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=123 seq_num=124 snap_id=1 wait times: snap=5.001307 sec, exc=5.001307 sec, total=5.001307 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000417 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=122 seq_num=123 snap_id=1 wait times: snap=0.781304 sec, exc=0.781304 sec, total=0.781304 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=121 seq_num=122 snap_id=1 wait times: snap=5.001130 sec, exc=5.001130 sec, total=5.001130 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=120 seq_num=121 snap_id=1 wait times: snap=5.008777 sec, exc=5.008777 sec, total=5.008777 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=119 seq_num=120 snap_id=1 wait times: snap=5.000127 sec, exc=5.000127 sec, total=5.000127 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=118 seq_num=119 snap_id=1 wait times: snap=5.004653 sec, exc=5.004653 sec, total=5.004653 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 201 serial 54873 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 07:58:49 - 08:00:49] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-09 08:00:49.608 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 17820, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-09 05:20:04.126338 :BD4B5CF4: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-08-09 05:30:05.100912 :BD4C1E90:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 05:30:05.496503 :BD4C1ED3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 05:30:05.491 2025-08-09 05:30:05.496505 :BD4C1ED4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19776 pso_num=25 pso_serial#=221 2025-08-09 05:30:05.496662 :BD4C1ED5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19776 osp_idx=23 osp_ver=232893 osp_pg=0 (spawn #470189) 2025-08-09 05:30:05.496663 :BD4C1ED6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 05:30:05.496663 :BD4C1ED7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=130 time=3398074798 2025-08-09 05:30:05.496664 :BD4C1ED8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 05:30:05.496664 :BD4C1ED9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 05:30:05.496665 :BD4C1EDA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 05:30:05.496665 :BD4C1EDB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 05:30:05.496665 :BD4C1EDC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 05:30:05.497168 :BD4C1EDD: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-08-09 05:30:05.497216 :BD4C1EE5: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-08-09 05:40:06.414453 :BD4CE204:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 05:40:06.872368 :BD4CE255:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 05:40:06.868 2025-08-09 05:40:06.872370 :BD4CE256:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21396 pso_num=25 pso_serial#=222 2025-08-09 05:40:06.872510 :BD4CE257:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21396 osp_idx=23 osp_ver=232894 osp_pg=0 (spawn #470212) 2025-08-09 05:40:06.872511 :BD4CE258:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 05:40:06.872511 :BD4CE259:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=131 time=3398676173 2025-08-09 05:40:06.872512 :BD4CE25A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 05:40:06.872512 :BD4CE25B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 05:40:06.872513 :BD4CE25C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 05:40:06.872513 :BD4CE25D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 05:40:06.872514 :BD4CE25E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 05:40:06.873029 :BD4CE25F: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-08-09 05:40:06.873073 :BD4CE268: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-08-09 05:50:07.716042 :BD4DA424:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 05:50:08.205104 :BD4DA474:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 05:50:08.200 2025-08-09 05:50:08.205107 :BD4DA475:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20372 pso_num=25 pso_serial#=223 2025-08-09 05:50:08.205240 :BD4DA476:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20372 osp_idx=23 osp_ver=232895 osp_pg=0 (spawn #470235) 2025-08-09 05:50:08.205241 :BD4DA477:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 05:50:08.205241 :BD4DA478:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=132 time=3399277517 2025-08-09 05:50:08.205242 :BD4DA479:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 05:50:08.205242 :BD4DA47A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 05:50:08.205242 :BD4DA47B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 05:50:08.205243 :BD4DA47C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 05:50:08.205243 :BD4DA47D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 05:50:08.205737 :BD4DA47E: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-08-09 05:50:08.205785 :BD4DA485: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-08-09 05:50:26.473754 :BD4DAA4B: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-08-09 06:00:09.434829 :BD4E70E3: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-08-09 06:10:10.292055 :BD4F41EE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 06:10:10.718400 :BD4F423A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 06:10:10.713 2025-08-09 06:10:10.718402 :BD4F423B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=680 pso_num=25 pso_serial#=224 2025-08-09 06:10:10.718541 :BD4F423C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=680 osp_idx=23 osp_ver=232896 osp_pg=0 (spawn #470288) 2025-08-09 06:10:10.718542 :BD4F423D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 06:10:10.718542 :BD4F423E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=133 time=3400480017 2025-08-09 06:10:10.718543 :BD4F423F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 06:10:10.718543 :BD4F4240:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 06:10:10.718543 :BD4F4241:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 06:10:10.718544 :BD4F4242:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 06:10:10.718544 :BD4F4243:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 06:10:10.719046 :BD4F4244: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-08-09 06:10:10.719096 :BD4F424B: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-08-09 06:20:11.607526 :BD500713:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 06:20:12.018518 :BD500756:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 06:20:12.013 2025-08-09 06:20:12.018520 :BD500757:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21004 pso_num=25 pso_serial#=225 2025-08-09 06:20:12.018653 :BD500758:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21004 osp_idx=23 osp_ver=232897 osp_pg=0 (spawn #470311) 2025-08-09 06:20:12.018653 :BD500759:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 06:20:12.018654 :BD50075A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=134 time=3401081329 2025-08-09 06:20:12.018654 :BD50075B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 06:20:12.018654 :BD50075C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 06:20:12.018654 :BD50075D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 06:20:12.018655 :BD50075E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 06:20:12.018655 :BD50075F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 06:20:12.019151 :BD500760: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-08-09 06:20:12.019195 :BD500767: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-08-09 06:30:12.900956 :BD50CC4D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 06:30:13.249429 :BD50CC9B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 06:30:13.244 2025-08-09 06:30:13.249431 :BD50CC9C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19964 pso_num=25 pso_serial#=226 2025-08-09 06:30:13.249594 :BD50CC9D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19964 osp_idx=23 osp_ver=232898 osp_pg=0 (spawn #470333) 2025-08-09 06:30:13.249594 :BD50CC9E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 06:30:13.249595 :BD50CC9F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=135 time=3401682548 2025-08-09 06:30:13.249595 :BD50CCA0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 06:30:13.249596 :BD50CCA1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 06:30:13.249596 :BD50CCA2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 06:30:13.249596 :BD50CCA3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 06:30:13.249597 :BD50CCA4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 06:30:13.250141 :BD50CCA5: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-08-09 06:30:13.250190 :BD50CCAC: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-08-09 06:40:14.165313 :BD51913E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 06:40:14.529361 :BD51918B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 06:40:14.524 2025-08-09 06:40:14.529363 :BD51918C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21448 pso_num=25 pso_serial#=227 2025-08-09 06:40:14.529508 :BD51918D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21448 osp_idx=23 osp_ver=232899 osp_pg=0 (spawn #470356) 2025-08-09 06:40:14.529509 :BD51918E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 06:40:14.529510 :BD51918F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=136 time=3402283829 2025-08-09 06:40:14.529510 :BD519190:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 06:40:14.529511 :BD519191:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 06:40:14.529511 :BD519192:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 06:40:14.529511 :BD519193:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 06:40:14.529512 :BD519194:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 06:40:14.530016 :BD519195: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-08-09 06:40:14.530060 :BD51919E: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-08-09 06:50:15.431396 :BD5254A1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 06:50:15.748847 :BD5254F0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 06:50:15.744 2025-08-09 06:50:15.748849 :BD5254F1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21236 pso_num=25 pso_serial#=228 2025-08-09 06:50:15.748995 :BD5254F2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21236 osp_idx=23 osp_ver=232900 osp_pg=0 (spawn #470379) 2025-08-09 06:50:15.748996 :BD5254F3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 06:50:15.748996 :BD5254F4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=137 time=3402885048 2025-08-09 06:50:15.748997 :BD5254F5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 06:50:15.748997 :BD5254F6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 06:50:15.748997 :BD5254F7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 06:50:15.748998 :BD5254F8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 06:50:15.748998 :BD5254F9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 06:50:15.749516 :BD5254FA: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-08-09 06:50:15.749559 :BD525501: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-08-09 07:00:16.554233 :BD5317ED:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 07:00:16.871669 :BD53185B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 07:00:16.867 2025-08-09 07:00:16.871671 :BD53185C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15628 pso_num=25 pso_serial#=229 2025-08-09 07:00:16.871814 :BD53185D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15628 osp_idx=23 osp_ver=232901 osp_pg=0 (spawn #470401) 2025-08-09 07:00:16.871814 :BD53185E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:00:16.871815 :BD53185F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=138 time=3403486173 2025-08-09 07:00:16.871816 :BD531860:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:00:16.871816 :BD531861:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:00:16.871817 :BD531862:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:00:16.871818 :BD531863:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:00:16.871818 :BD531864:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:00:16.872335 :BD531865: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-08-09 07:00:16.872383 :BD53186C: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-08-09 07:10:17.709101 :BD53E318:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 07:10:18.046401 :BD53E366:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 07:10:18.041 2025-08-09 07:10:18.046403 :BD53E367:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17876 pso_num=25 pso_serial#=230 2025-08-09 07:10:18.046553 :BD53E368:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17876 osp_idx=23 osp_ver=232902 osp_pg=0 (spawn #470427) 2025-08-09 07:10:18.046554 :BD53E369:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:10:18.046554 :BD53E36A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=139 time=3404087345 2025-08-09 07:10:18.046555 :BD53E36B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:10:18.046555 :BD53E36C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:10:18.046555 :BD53E36D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:10:18.046556 :BD53E36E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:10:18.046556 :BD53E36F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:10:18.047098 :BD53E370: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-08-09 07:10:18.047149 :BD53E377: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-08-09 07:20:18.870176 :BD54A5F6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 07:20:19.218591 :BD54A63A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 07:20:19.213 2025-08-09 07:20:19.218593 :BD54A63B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16580 pso_num=25 pso_serial#=231 2025-08-09 07:20:19.218728 :BD54A63C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16580 osp_idx=23 osp_ver=232903 osp_pg=0 (spawn #470450) 2025-08-09 07:20:19.218728 :BD54A63D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:20:19.218729 :BD54A63E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=140 time=3404688517 2025-08-09 07:20:19.218729 :BD54A63F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:20:19.218730 :BD54A640:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:20:19.218730 :BD54A641:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:20:19.218730 :BD54A642:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:20:19.218731 :BD54A643:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:20:19.219221 :BD54A644: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-08-09 07:20:19.219265 :BD54A64B: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-08-09 07:30:20.041004 :BD556B8C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 07:30:20.358991 :BD556BDD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 07:30:20.354 2025-08-09 07:30:20.358994 :BD556BDE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19564 pso_num=25 pso_serial#=232 2025-08-09 07:30:20.359169 :BD556BDF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19564 osp_idx=23 osp_ver=232904 osp_pg=0 (spawn #470472) 2025-08-09 07:30:20.359170 :BD556BE0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:30:20.359171 :BD556BE1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=141 time=3405289658 2025-08-09 07:30:20.359171 :BD556BE2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:30:20.359172 :BD556BE3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:30:20.359172 :BD556BE4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:30:20.359173 :BD556BE5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:30:20.359174 :BD556BE6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:30:20.359714 :BD556BE7: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-08-09 07:30:20.359775 :BD556BEF: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-08-09 07:40:21.319675 :BD562F97:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 07:40:21.592956 :BD562FD0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 07:40:21.587 2025-08-09 07:40:21.592958 :BD562FD1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19508 pso_num=25 pso_serial#=233 2025-08-09 07:40:21.593099 :BD562FD2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19508 osp_idx=23 osp_ver=232905 osp_pg=0 (spawn #470495) 2025-08-09 07:40:21.593100 :BD562FD3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:40:21.593100 :BD562FD4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=142 time=3405890892 2025-08-09 07:40:21.593100 :BD562FD5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:40:21.593101 :BD562FD6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:40:21.593101 :BD562FD7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:40:21.593101 :BD562FD8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:40:21.593102 :BD562FD9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:40:21.593611 :BD562FDA: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-08-09 07:40:21.593655 :BD562FE1: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-08-09 07:50:22.397650 :BD56F1FD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-09 07:50:22.808637 :BD56F23E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-09 07:50:22.803 2025-08-09 07:50:22.808639 :BD56F23F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17820 pso_num=25 pso_serial#=234 2025-08-09 07:50:22.808774 :BD56F240:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17820 osp_idx=23 osp_ver=232906 osp_pg=0 (spawn #470518) 2025-08-09 07:50:22.808775 :BD56F241:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:50:22.808775 :BD56F242:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=143 time=3406492111 2025-08-09 07:50:22.808776 :BD56F243:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:50:22.808776 :BD56F244:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:50:22.808776 :BD56F245:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:50:22.808777 :BD56F246:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:50:22.808777 :BD56F247:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:50:22.809307 :BD56F248: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-08-09 07:50:22.809351 :BD56F250: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-08-09 07:50:27.317829 :BD56F427: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-08-09 08:00:23.930629 :BD57B59D: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 17820, W000)