Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250914140701\orcl_w000_24980_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:34757M/63366M, Ph+PgF:39372M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 24980, image: ORACLE.EXE (W000) *** 2025-09-14 14:07:01.382 *** SESSION ID:(201.5403) 2025-09-14 14:07:01.382 *** 2025-09-14 14:07:01.382 Process diagnostic dump for ORACLE.EXE (W000), OS id=24980, pid: 25, proc_ser: 88, sid: 201, sess_ser: 5403 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=3.415372 sec, exc=3.415372 sec, total=3.415372 sec wait times: max=5.000000 sec, heur=1 min 3 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.000032 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=93 seq_num=94 snap_id=1 wait times: snap=5.015240 sec, exc=5.015240 sec, total=5.015240 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=92 seq_num=93 snap_id=1 wait times: snap=5.003388 sec, exc=5.003388 sec, total=5.003388 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=91 seq_num=92 snap_id=1 wait times: snap=5.007145 sec, exc=5.007145 sec, total=5.007145 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=90 seq_num=91 snap_id=1 wait times: snap=5.010104 sec, exc=5.010104 sec, total=5.010104 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=89 seq_num=90 snap_id=1 wait times: snap=5.010131 sec, exc=5.010131 sec, total=5.010131 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=88 seq_num=89 snap_id=1 wait times: snap=5.009171 sec, exc=5.009171 sec, total=5.009171 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=87 seq_num=88 snap_id=1 wait times: snap=5.015269 sec, exc=5.015269 sec, total=5.015269 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=86 seq_num=87 snap_id=1 wait times: snap=5.003222 sec, exc=5.003222 sec, total=5.003222 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=85 seq_num=86 snap_id=1 wait times: snap=5.012461 sec, exc=5.012461 sec, total=5.012461 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.003345 sec, exc=5.003345 sec, total=5.003345 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time Sampled Session History of session 201 serial 5403 --------------------------------------------------- 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, 14:05:01 - 14:07:01] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-14 14:07:01.382 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 24980, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-14 13:38:05.127635 :CD51573E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:38:05.116 2025-09-14 13:38:05.127638 :CD51573F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23448 pso_num=25 pso_serial#=71 2025-09-14 13:38:05.127751 :CD515740:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23448 osp_idx=23 osp_ver=298689 osp_pg=0 (spawn #591351) 2025-09-14 13:38:05.127752 :CD515741:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:38:05.127752 :CD515742:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=206 time=2242736424 2025-09-14 13:38:05.127753 :CD515743:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:38:05.127753 :CD515744:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:38:05.127753 :CD515745:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:38:05.127754 :CD515746:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:38:05.127754 :CD515747:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:38:05.128261 :CD515748:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:38:35.205442 :CD51611E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:39:05.219129 :CD516AFE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:39:05.207 2025-09-14 13:39:05.219131 :CD516AFF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16984 pso_num=25 pso_serial#=72 2025-09-14 13:39:05.219250 :CD516B00:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16984 osp_idx=23 osp_ver=298690 osp_pg=0 (spawn #591353) 2025-09-14 13:39:05.219251 :CD516B01:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:39:05.219251 :CD516B02:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=207 time=2242796518 2025-09-14 13:39:05.219252 :CD516B03:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:39:05.219253 :CD516B04:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:39:05.219253 :CD516B05:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:39:05.219253 :CD516B06:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:39:05.219254 :CD516B07:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:39:05.219787 :CD516B08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:39:35.283642 :CD517556:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:40:05.291149 :CD517F1C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:40:05.279 2025-09-14 13:40:05.291151 :CD517F1D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25412 pso_num=25 pso_serial#=73 2025-09-14 13:40:05.291266 :CD517F1E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25412 osp_idx=23 osp_ver=298691 osp_pg=0 (spawn #591356) 2025-09-14 13:40:05.291267 :CD517F1F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:40:05.291267 :CD517F20:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=208 time=2242856596 2025-09-14 13:40:05.291268 :CD517F21:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:40:05.291269 :CD517F22:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:40:05.291269 :CD517F23:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:40:05.291270 :CD517F24:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:40:05.291270 :CD517F25:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:40:05.291741 :CD517F26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:40:35.366508 :CD5188A7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:41:05.376868 :CD5192C5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:41:05.365 2025-09-14 13:41:05.376870 :CD5192C6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16164 pso_num=25 pso_serial#=74 2025-09-14 13:41:05.376990 :CD5192C7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16164 osp_idx=23 osp_ver=298692 osp_pg=0 (spawn #591358) 2025-09-14 13:41:05.376991 :CD5192C8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:41:05.376991 :CD5192C9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=209 time=2242916674 2025-09-14 13:41:05.376991 :CD5192CA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:41:05.376991 :CD5192CB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:41:05.376992 :CD5192CC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:41:05.376992 :CD5192CD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:41:05.376992 :CD5192CE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:41:05.377468 :CD5192CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:41:35.453373 :CD519D1F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:42:05.506160 :CD51A744:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:42:05.495 2025-09-14 13:42:05.506162 :CD51A745:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25064 pso_num=25 pso_serial#=75 2025-09-14 13:42:05.506286 :CD51A746:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25064 osp_idx=23 osp_ver=298693 osp_pg=0 (spawn #591361) 2025-09-14 13:42:05.506287 :CD51A747:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:42:05.506288 :CD51A748:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=210 time=2242976799 2025-09-14 13:42:05.506288 :CD51A749:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:42:05.506288 :CD51A74A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:42:05.506288 :CD51A74B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:42:05.506289 :CD51A74C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:42:05.506289 :CD51A74D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:42:05.506767 :CD51A74E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:42:35.562970 :CD51B0FE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:43:05.577702 :CD51BADC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:43:05.566 2025-09-14 13:43:05.577703 :CD51BADD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25100 pso_num=25 pso_serial#=76 2025-09-14 13:43:05.577836 :CD51BADE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25100 osp_idx=23 osp_ver=298694 osp_pg=0 (spawn #591363) 2025-09-14 13:43:05.577838 :CD51BADF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:43:05.577838 :CD51BAE0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=211 time=2243036877 2025-09-14 13:43:05.577838 :CD51BAE1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:43:05.577839 :CD51BAE2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:43:05.577839 :CD51BAE3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:43:05.577840 :CD51BAE4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:43:05.577840 :CD51BAE5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:43:05.578369 :CD51BAE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:43:35.657055 :CD51C45C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:43:50.652345 :CD51C9AD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:43:50.641 2025-09-14 13:43:50.652347 :CD51C9AE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20664 pso_num=25 pso_serial#=77 2025-09-14 13:43:50.652479 :CD51C9AF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20664 osp_idx=23 osp_ver=298695 osp_pg=0 (spawn #591365) 2025-09-14 13:43:50.652480 :CD51C9B0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:43:50.652480 :CD51C9B1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=212 time=2243081955 2025-09-14 13:43:50.652481 :CD51C9B2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:43:50.652481 :CD51C9B3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:43:50.652481 :CD51C9B4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:43:50.652482 :CD51C9B5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:43:50.652482 :CD51C9B6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:43:50.652954 :CD51C9B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:44:05.665525 :CD51CEAD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-14 13:44:35.778799 :CD51D899:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:45:05.772515 :CD51E267:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:45:05.760 2025-09-14 13:45:05.772517 :CD51E268:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23140 pso_num=25 pso_serial#=78 2025-09-14 13:45:05.772644 :CD51E269:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23140 osp_idx=23 osp_ver=298696 osp_pg=0 (spawn #591367) 2025-09-14 13:45:05.772645 :CD51E26A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:45:05.772645 :CD51E26B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=213 time=2243157065 2025-09-14 13:45:05.772646 :CD51E26C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:45:05.772648 :CD51E26D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:45:05.772648 :CD51E26E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:45:05.772648 :CD51E26F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:45:05.772649 :CD51E270:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:45:05.773148 :CD51E271:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:45:35.864793 :CD51EC25:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:46:05.863405 :CD51F5F7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:46:05.852 2025-09-14 13:46:05.863407 :CD51F5F8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25016 pso_num=25 pso_serial#=79 2025-09-14 13:46:05.863539 :CD51F5F9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25016 osp_idx=23 osp_ver=298697 osp_pg=0 (spawn #591369) 2025-09-14 13:46:05.863539 :CD51F5FA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:46:05.863540 :CD51F5FB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=2243217158 2025-09-14 13:46:05.863540 :CD51F5FC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:46:05.863541 :CD51F5FD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:46:05.863541 :CD51F5FE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:46:05.863541 :CD51F5FF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:46:05.863542 :CD51F600:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:46:05.864030 :CD51F601:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:46:35.940930 :CD52009F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:47:05.952153 :CD520AC3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:47:05.941 2025-09-14 13:47:05.952155 :CD520AC4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24940 pso_num=25 pso_serial#=80 2025-09-14 13:47:05.952299 :CD520AC5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24940 osp_idx=23 osp_ver=298698 osp_pg=0 (spawn #591372) 2025-09-14 13:47:05.952299 :CD520AC6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:47:05.952300 :CD520AC7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=2243277252 2025-09-14 13:47:05.952300 :CD520AC8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:47:05.952301 :CD520AC9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:47:05.952301 :CD520ACA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:47:05.952302 :CD520ACB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:47:05.952302 :CD520ACC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:47:05.952843 :CD520ACD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:47:36.036012 :CD521486:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:48:06.036216 :CD521E7B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:48:06.024 2025-09-14 13:48:06.036218 :CD521E7C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23632 pso_num=25 pso_serial#=81 2025-09-14 13:48:06.036344 :CD521E7D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23632 osp_idx=23 osp_ver=298699 osp_pg=0 (spawn #591374) 2025-09-14 13:48:06.036345 :CD521E7E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:48:06.036345 :CD521E7F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=2243337330 2025-09-14 13:48:06.036346 :CD521E80:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:48:06.036346 :CD521E81:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:48:06.036346 :CD521E82:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:48:06.036346 :CD521E83:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:48:06.036347 :CD521E84:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:48:06.036856 :CD521E85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:48:36.140791 :CD52283A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:49:06.122486 :CD5231A4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:49:06.120 2025-09-14 13:49:06.122486 :CD5231A5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25436 pso_num=25 pso_serial#=82 2025-09-14 13:49:06.122613 :CD5231A6:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=25436 (legacy spawn) 2025-09-14 13:49:06.129053 :CD5231CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 13:49:06.129195 :CD5231D7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-14 13:49:06.130045 :CD5231D8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:49:06.132333 :CD5231E5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:49:06.120 2025-09-14 13:49:06.132336 :CD5231E6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24532 pso_num=25 pso_serial#=83 2025-09-14 13:49:06.132598 :CD5231E7:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24532 (legacy spawn) 2025-09-14 13:49:06.139293 :CD523206:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 13:49:06.139438 :CD52320F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-14 13:49:06.140228 :CD523210:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:49:09.377269 :CD523356:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:49:09.364 2025-09-14 13:49:09.377271 :CD523357:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22828 pso_num=25 pso_serial#=84 2025-09-14 13:49:09.377417 :CD523358:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22828 (legacy spawn) 2025-09-14 13:49:09.384003 :CD52336C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 13:49:09.384164 :CD523375:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-14 13:49:09.384951 :CD523376:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:49:09.393756 :CD523379:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:49:09.379 2025-09-14 13:49:09.393758 :CD52337A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24556 pso_num=25 pso_serial#=85 2025-09-14 13:49:09.393906 :CD52337B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24556 (legacy spawn) 2025-09-14 13:49:09.400490 :CD52337C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 13:49:09.400666 :CD523385:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-14 13:49:09.401514 :CD523386:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:49:17.719970 :CD52363D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:49:17.711 2025-09-14 13:49:17.719972 :CD52363E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23308 pso_num=25 pso_serial#=86 2025-09-14 13:49:17.720110 :CD52363F:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23308 (legacy spawn) 2025-09-14 13:49:17.744182 :CD523640:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 13:49:17.744355 :CD523645:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-14 13:49:17.745275 :CD52364A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:49:19.965720 :CD523706:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:49:19.960 2025-09-14 13:49:19.965723 :CD523707:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19592 pso_num=25 pso_serial#=87 2025-09-14 13:49:19.965875 :CD523708:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19592 osp_idx=23 osp_ver=298705 osp_pg=0 (spawn #591377) 2025-09-14 13:49:19.965875 :CD523709:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:49:19.965875 :CD52370A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=2243411268 2025-09-14 13:49:19.965876 :CD52370B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:49:19.965876 :CD52370C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:49:19.965877 :CD52370D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:49:19.965877 :CD52370E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:49:19.965877 :CD52370F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:49:19.966399 :CD523710:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:49:19.966449 :CD523719:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-14 13:59:20.809222 :CD530CBF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 13:59:21.281050 :CD530D2D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 13:59:21.281 2025-09-14 13:59:21.281052 :CD530D2E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24980 pso_num=25 pso_serial#=88 2025-09-14 13:59:21.281222 :CD530D2F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24980 osp_idx=23 osp_ver=298706 osp_pg=0 (spawn #591400) 2025-09-14 13:59:21.281223 :CD530D30:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 13:59:21.281223 :CD530D31:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=218 time=2244012580 2025-09-14 13:59:21.281223 :CD530D32:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 13:59:21.281224 :CD530D33:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 13:59:21.281224 :CD530D34:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 13:59:21.281224 :CD530D35:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 13:59:21.281225 :CD530D36:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 13:59:21.281735 :CD530D37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 13:59:21.281777 :CD530D3E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-14 14:05:57.857068 :CD539323: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 24980, W000)