Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250801220006\orcl_w000_19828_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:35842M/63366M, Ph+PgF:41417M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 19828, image: ORACLE.EXE (W000) *** 2025-08-01 22:00:07.018 *** SESSION ID:(201.36673) 2025-08-01 22:00:07.018 *** 2025-08-01 22:00:07.018 Process diagnostic dump for ORACLE.EXE (W000), OS id=19828, pid: 25, proc_ser: 56, sid: 201, sess_ser: 36673 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.987704 sec, exc=0.987704 sec, total=0.987704 sec wait times: max=5.000000 sec, heur=1 min 1 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.000022 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=5.000583 sec, exc=5.000583 sec, total=5.000583 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=11 seq_num=12 snap_id=1 wait times: snap=5.009232 sec, exc=5.009232 sec, total=5.009232 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=5.004623 sec, exc=5.004623 sec, total=5.004623 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=5.008236 sec, exc=5.008236 sec, total=5.008236 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=5.000275 sec, exc=5.000275 sec, total=5.000275 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=5.004139 sec, exc=5.004139 sec, total=5.004139 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 6: 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.001206 sec, exc=5.001206 sec, total=5.001206 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: 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.004166 sec, exc=5.004166 sec, total=5.004166 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 8: 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.004620 sec, exc=5.004620 sec, total=5.004620 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 9: 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.004624 sec, exc=5.004624 sec, total=5.004624 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 201 serial 36673 --------------------------------------------------- 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 --------------------------------------------------- [62 samples, 21:59:05 - 22:00:07] idle wait at each sample [session created at: 21:59:05] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-01 22:00:07.018 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 19828, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-01 19:28:49.006259 :BA025648:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 19:28:49.006755 :BA025649: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-01 19:28:49.006801 :BA025652: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-01 19:38:49.758852 :BA03202B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 19:38:50.139548 :BA0320BA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 19:38:50.134 2025-08-01 19:38:50.139551 :BA0320BB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19748 pso_num=25 pso_serial#=43 2025-08-01 19:38:50.139697 :BA0320BC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19748 osp_idx=23 osp_ver=224300 osp_pg=0 (spawn #445475) 2025-08-01 19:38:50.139698 :BA0320BD: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-01 19:38:50.139699 :BA0320BE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=2757799439 2025-08-01 19:38:50.139699 :BA0320BF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 19:38:50.139699 :BA0320C0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 19:38:50.139700 :BA0320C1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 19:38:50.139700 :BA0320C2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 19:38:50.139700 :BA0320C3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 19:38:50.140242 :BA0320C4: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-01 19:38:50.140289 :BA0320CC: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-01 19:48:50.996853 :BA03E7B6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 19:48:51.283420 :BA03E839:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 19:48:51.278 2025-08-01 19:48:51.283422 :BA03E83A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19396 pso_num=25 pso_serial#=44 2025-08-01 19:48:51.283562 :BA03E83B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19396 osp_idx=23 osp_ver=224301 osp_pg=0 (spawn #445498) 2025-08-01 19:48:51.283563 :BA03E83C: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-01 19:48:51.283564 :BA03E83D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=2758400595 2025-08-01 19:48:51.283564 :BA03E83E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 19:48:51.283565 :BA03E83F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 19:48:51.283565 :BA03E840:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 19:48:51.283565 :BA03E841:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 19:48:51.283566 :BA03E842:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 19:48:51.284077 :BA03E843: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-01 19:48:51.284135 :BA03E84D: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-01 19:58:52.141788 :BA04B0A8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 19:58:52.428067 :BA04B107:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 19:58:52.423 2025-08-01 19:58:52.428069 :BA04B108:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6376 pso_num=25 pso_serial#=45 2025-08-01 19:58:52.428207 :BA04B109:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6376 osp_idx=23 osp_ver=224302 osp_pg=0 (spawn #445522) 2025-08-01 19:58:52.428208 :BA04B10A: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-01 19:58:52.428208 :BA04B10B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=2759001736 2025-08-01 19:58:52.428209 :BA04B10C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 19:58:52.428209 :BA04B10D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 19:58:52.428209 :BA04B10E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 19:58:52.428210 :BA04B10F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 19:58:52.428210 :BA04B110:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 19:58:52.428709 :BA04B111: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-01 19:58:52.428756 :BA04B11A: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-01 20:08:53.245331 :BA058217:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 20:08:53.515378 :BA058276:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 20:08:53.510 2025-08-01 20:08:53.515380 :BA058277:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12980 pso_num=25 pso_serial#=46 2025-08-01 20:08:53.515527 :BA058278:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12980 osp_idx=23 osp_ver=224303 osp_pg=0 (spawn #445547) 2025-08-01 20:08:53.515528 :BA058279: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-01 20:08:53.515528 :BA05827A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=2759602814 2025-08-01 20:08:53.515529 :BA05827B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 20:08:53.515529 :BA05827C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 20:08:53.515529 :BA05827D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 20:08:53.515530 :BA05827E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 20:08:53.515530 :BA05827F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 20:08:53.516059 :BA058280: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-01 20:08:53.516102 :BA058288: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-01 20:18:54.289824 :BA064DFD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 20:18:54.623172 :BA064E62:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 20:18:54.618 2025-08-01 20:18:54.623174 :BA064E63:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3292 pso_num=25 pso_serial#=47 2025-08-01 20:18:54.623311 :BA064E64:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3292 osp_idx=23 osp_ver=224304 osp_pg=0 (spawn #445570) 2025-08-01 20:18:54.623312 :BA064E65: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-01 20:18:54.623314 :BA064E66:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=2760203923 2025-08-01 20:18:54.623314 :BA064E67:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 20:18:54.623315 :BA064E68:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 20:18:54.623315 :BA064E69:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 20:18:54.623315 :BA064E6A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 20:18:54.623315 :BA064E6B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 20:18:54.623824 :BA064E6C: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-01 20:18:54.623868 :BA064E74: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-01 20:28:55.437062 :BA071535:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 20:28:55.738211 :BA07158F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 20:28:55.733 2025-08-01 20:28:55.738213 :BA071590:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19576 pso_num=25 pso_serial#=48 2025-08-01 20:28:55.738357 :BA071591:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19576 osp_idx=23 osp_ver=224305 osp_pg=0 (spawn #445593) 2025-08-01 20:28:55.738358 :BA071592: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-01 20:28:55.738358 :BA071593:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=251 time=2760805048 2025-08-01 20:28:55.738359 :BA071594:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 20:28:55.738359 :BA071595:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 20:28:55.738359 :BA071596:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 20:28:55.738360 :BA071597:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 20:28:55.738360 :BA071598:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 20:28:55.738882 :BA071599: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-01 20:28:55.738924 :BA0715A1: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-01 20:38:56.616149 :BA07DD8B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 20:38:56.902171 :BA07DDF1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 20:38:56.897 2025-08-01 20:38:56.902173 :BA07DDF2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14108 pso_num=25 pso_serial#=49 2025-08-01 20:38:56.902312 :BA07DDF3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14108 osp_idx=23 osp_ver=224306 osp_pg=0 (spawn #445615) 2025-08-01 20:38:56.902312 :BA07DDF4: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-01 20:38:56.902313 :BA07DDF5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=252 time=2761406204 2025-08-01 20:38:56.902313 :BA07DDF6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 20:38:56.902314 :BA07DDF7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 20:38:56.902314 :BA07DDF8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 20:38:56.902314 :BA07DDF9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 20:38:56.902315 :BA07DDFA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 20:38:56.902855 :BA07DDFB: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-01 20:38:56.902899 :BA07DE02: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-01 20:48:57.697484 :BA08A574:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 20:48:57.952152 :BA08A5CB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 20:48:57.947 2025-08-01 20:48:57.952155 :BA08A5CC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19356 pso_num=25 pso_serial#=50 2025-08-01 20:48:57.952293 :BA08A5CD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19356 osp_idx=23 osp_ver=224307 osp_pg=0 (spawn #445638) 2025-08-01 20:48:57.952294 :BA08A5CE: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-01 20:48:57.952294 :BA08A5CF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=2762007251 2025-08-01 20:48:57.952294 :BA08A5D0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 20:48:57.952295 :BA08A5D1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 20:48:57.952295 :BA08A5D2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 20:48:57.952296 :BA08A5D3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 20:48:57.952296 :BA08A5D4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 20:48:57.952804 :BA08A5D5: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-01 20:48:57.952851 :BA08A5DE: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-01 20:58:58.729587 :BA096E79:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 20:58:59.093694 :BA096EBF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 20:58:59.088 2025-08-01 20:58:59.093696 :BA096EC0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17124 pso_num=25 pso_serial#=51 2025-08-01 20:58:59.093831 :BA096EC1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17124 osp_idx=23 osp_ver=224308 osp_pg=0 (spawn #445661) 2025-08-01 20:58:59.093832 :BA096EC2: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-01 20:58:59.093832 :BA096EC3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=254 time=2762608392 2025-08-01 20:58:59.093833 :BA096EC4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 20:58:59.093833 :BA096EC5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 20:58:59.093834 :BA096EC6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 20:58:59.093834 :BA096EC7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 20:58:59.093834 :BA096EC8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 20:58:59.094342 :BA096EC9: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-01 20:58:59.094386 :BA096ED2: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-01 21:06:13.662350 :BA0A0915: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-01 21:09:00.191902 :BA0A3FAE: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-01 21:19:01.055580 :BA0B0A3D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 21:19:01.310095 :BA0B0AA2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 21:19:01.305 2025-08-01 21:19:01.310098 :BA0B0AA3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15644 pso_num=25 pso_serial#=52 2025-08-01 21:19:01.310236 :BA0B0AA4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15644 osp_idx=23 osp_ver=224309 osp_pg=0 (spawn #445706) 2025-08-01 21:19:01.310236 :BA0B0AA5: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-01 21:19:01.310237 :BA0B0AA6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=255 time=2763810611 2025-08-01 21:19:01.310237 :BA0B0AA7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:19:01.310238 :BA0B0AA8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:19:01.310238 :BA0B0AA9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:19:01.310238 :BA0B0AAA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:19:01.310238 :BA0B0AAB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:19:01.310749 :BA0B0AAC: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-01 21:19:01.310791 :BA0B0AB4: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-01 21:29:02.117585 :BA0BD15E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 21:29:02.419047 :BA0BD1B8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 21:29:02.414 2025-08-01 21:29:02.419049 :BA0BD1B9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10824 pso_num=25 pso_serial#=53 2025-08-01 21:29:02.419184 :BA0BD1BA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10824 osp_idx=23 osp_ver=224310 osp_pg=0 (spawn #445729) 2025-08-01 21:29:02.419185 :BA0BD1BB: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-01 21:29:02.419185 :BA0BD1BC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=0 time=2764411720 2025-08-01 21:29:02.419185 :BA0BD1BD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:29:02.419186 :BA0BD1BE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:29:02.419186 :BA0BD1BF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:29:02.419186 :BA0BD1C0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:29:02.419187 :BA0BD1C1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:29:02.419680 :BA0BD1C2: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-01 21:29:02.419725 :BA0BD1CA: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-01 21:39:03.251798 :BA0C98E8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 21:39:03.600246 :BA0C9949:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 21:39:03.595 2025-08-01 21:39:03.600248 :BA0C994A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10156 pso_num=25 pso_serial#=54 2025-08-01 21:39:03.600389 :BA0C994B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10156 osp_idx=23 osp_ver=224311 osp_pg=0 (spawn #445751) 2025-08-01 21:39:03.600390 :BA0C994C: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-01 21:39:03.600390 :BA0C994D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=1 time=2765012908 2025-08-01 21:39:03.600391 :BA0C994E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:39:03.600391 :BA0C994F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:39:03.600392 :BA0C9950:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:39:03.600392 :BA0C9951:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:39:03.600392 :BA0C9952:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:39:03.600916 :BA0C9953: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-01 21:39:03.600962 :BA0C995A: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-01 21:49:04.394040 :BA0D5E83:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 21:49:04.758227 :BA0D5EE8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 21:49:04.753 2025-08-01 21:49:04.758229 :BA0D5EE9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14940 pso_num=25 pso_serial#=55 2025-08-01 21:49:04.758415 :BA0D5EEA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14940 osp_idx=23 osp_ver=224312 osp_pg=0 (spawn #445774) 2025-08-01 21:49:04.758416 :BA0D5EEB: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-01 21:49:04.758416 :BA0D5EEC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=2 time=2765614064 2025-08-01 21:49:04.758417 :BA0D5EED:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:49:04.758417 :BA0D5EEE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:49:04.758417 :BA0D5EEF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:49:04.758418 :BA0D5EF0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:49:04.758418 :BA0D5EF1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:49:04.758927 :BA0D5EF2: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-01 21:49:04.758972 :BA0D5EFA: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-01 21:59:05.515658 :BA0E24C8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-01 21:59:05.989073 :BA0E252A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-01 21:59:05.984 2025-08-01 21:59:05.989075 :BA0E252B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19828 pso_num=25 pso_serial#=56 2025-08-01 21:59:05.989211 :BA0E252C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19828 osp_idx=23 osp_ver=224313 osp_pg=0 (spawn #445797) 2025-08-01 21:59:05.989212 :BA0E252D: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-01 21:59:05.989212 :BA0E252E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=3 time=2766215298 2025-08-01 21:59:05.989213 :BA0E252F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:59:05.989213 :BA0E2530:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:59:05.989213 :BA0E2531:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:59:05.989214 :BA0E2532:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:59:05.989214 :BA0E2533:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:59:05.989797 :BA0E2534: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-01 21:59:05.989843 :BA0E253B: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: 19828, W000)