Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250509082424\orcl_w000_12648_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:37507M/63366M, Ph+PgF:44908M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12648, image: ORACLE.EXE (W000) *** 2025-05-09 08:24:24.896 *** SESSION ID:(201.51783) 2025-05-09 08:24:24.896 *** 2025-05-09 08:24:24.896 Process diagnostic dump for ORACLE.EXE (W000), OS id=12648, pid: 25, proc_ser: 141, sid: 201, sess_ser: 51783 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=2.966906 sec, exc=2.966906 sec, total=2.966906 sec wait times: max=5.000000 sec, heur=4 min 8 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.000025 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=5.001270 sec, exc=5.001270 sec, total=5.001270 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=5.010659 sec, exc=5.010659 sec, total=5.010659 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=5.011817 sec, exc=5.011817 sec, total=5.011817 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=46 seq_num=47 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.000024 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=5.014712 sec, exc=5.014712 sec, total=5.014712 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=5.004626 sec, exc=5.004626 sec, total=5.004626 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=43 seq_num=44 snap_id=1 wait times: snap=5.007222 sec, exc=5.007222 sec, total=5.007222 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=5.011300 sec, exc=5.011300 sec, total=5.011300 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=41 seq_num=42 snap_id=1 wait times: snap=5.001759 sec, exc=5.001759 sec, total=5.001759 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=40 seq_num=41 snap_id=1 wait times: snap=5.010148 sec, exc=5.010148 sec, total=5.010148 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time Sampled Session History of session 201 serial 51783 --------------------------------------------------- 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, 08:22:24 - 08:24:24] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-09 08:24:24.896 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12648, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-09 07:01:36.540320 :94AD681F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:02:06.497003 :94AD71C1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:02:06.486 2025-05-09 07:02:06.497005 :94AD71C2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11868 pso_num=25 pso_serial#=127 2025-05-09 07:02:06.497128 :94AD71C3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11868 osp_idx=23 osp_ver=82236 osp_pg=0 (spawn #163558) 2025-05-09 07:02:06.497129 :94AD71C4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:02:06.497130 :94AD71C5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=145 time=4044903656 2025-05-09 07:02:06.497130 :94AD71C6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:02:06.497130 :94AD71C7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:02:06.497131 :94AD71C8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:02:06.497131 :94AD71C9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:02:06.497131 :94AD71CA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:02:06.497633 :94AD71CB: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-05-09 07:02:36.619436 :94AD7B59:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:03:06.581665 :94AD8531:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:03:06.570 2025-05-09 07:03:06.581667 :94AD8532:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10340 pso_num=25 pso_serial#=128 2025-05-09 07:03:06.581795 :94AD8533:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10340 osp_idx=23 osp_ver=82237 osp_pg=0 (spawn #163560) 2025-05-09 07:03:06.581796 :94AD8534:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:03:06.581796 :94AD8535:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=146 time=4044963734 2025-05-09 07:03:06.581797 :94AD8536:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:03:06.581797 :94AD8537:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:03:06.581798 :94AD8538:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:03:06.581798 :94AD8539:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:03:06.581798 :94AD853A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:03:06.582272 :94AD853B: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-05-09 07:03:36.682045 :94AD9202:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:04:06.703463 :94AD9B97:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:04:06.692 2025-05-09 07:04:06.703466 :94AD9B98:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11544 pso_num=25 pso_serial#=129 2025-05-09 07:04:06.703593 :94AD9B99:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11544 osp_idx=23 osp_ver=82238 osp_pg=0 (spawn #163562) 2025-05-09 07:04:06.703594 :94AD9B9A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:04:06.703595 :94AD9B9B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=147 time=4045023859 2025-05-09 07:04:06.703595 :94AD9B9C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:04:06.703595 :94AD9B9D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:04:06.703596 :94AD9B9E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:04:06.703596 :94AD9B9F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:04:06.703596 :94AD9BA0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:04:06.704051 :94AD9BA1: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-05-09 07:04:36.825566 :94ADA553:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:05:06.796247 :94ADAF04:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:05:06.786 2025-05-09 07:05:06.796249 :94ADAF05:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12732 pso_num=25 pso_serial#=130 2025-05-09 07:05:06.796377 :94ADAF06:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12732 osp_idx=23 osp_ver=82239 osp_pg=0 (spawn #163564) 2025-05-09 07:05:06.796379 :94ADAF07:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:05:06.796379 :94ADAF08:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=148 time=4045083953 2025-05-09 07:05:06.796379 :94ADAF09:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:05:06.796380 :94ADAF0A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:05:06.796380 :94ADAF0B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:05:06.796380 :94ADAF0C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:05:06.796381 :94ADAF0D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:05:06.796873 :94ADAF0E: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-05-09 07:05:36.890689 :94ADBB5A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:06:06.852931 :94ADC90D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:06:06.841 2025-05-09 07:06:06.852933 :94ADC90E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12336 pso_num=25 pso_serial#=131 2025-05-09 07:06:06.853067 :94ADC90F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12336 osp_idx=23 osp_ver=82240 osp_pg=0 (spawn #163566) 2025-05-09 07:06:06.853068 :94ADC910:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:06:06.853069 :94ADC911:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=149 time=4045144015 2025-05-09 07:06:06.853069 :94ADC912:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:06:06.853069 :94ADC913:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:06:06.853070 :94ADC914:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:06:06.853070 :94ADC915:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:06:06.853071 :94ADC916:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:06:06.853608 :94ADC917: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-05-09 07:06:36.964189 :94ADD2FC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:07:01.932845 :94ADDAEB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:07:01.922 2025-05-09 07:07:01.932847 :94ADDAEC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12672 pso_num=25 pso_serial#=132 2025-05-09 07:07:01.932987 :94ADDAED:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12672 osp_idx=23 osp_ver=82241 osp_pg=0 (spawn #163569) 2025-05-09 07:07:01.932988 :94ADDAEE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:07:01.932989 :94ADDAEF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=150 time=4045199093 2025-05-09 07:07:01.932989 :94ADDAF0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:07:01.932989 :94ADDAF1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:07:01.932990 :94ADDAF2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:07:01.932990 :94ADDAF3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:07:01.932990 :94ADDAF4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:07:01.933489 :94ADDAF5: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-05-09 07:07:06.925433 :94ADDCCB: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-05-09 07:07:37.046785 :94ADE69E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:08:07.054664 :94ADF042:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:08:07.043 2025-05-09 07:08:07.054666 :94ADF043:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12388 pso_num=25 pso_serial#=133 2025-05-09 07:08:07.054799 :94ADF044:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12388 osp_idx=23 osp_ver=82242 osp_pg=0 (spawn #163571) 2025-05-09 07:08:07.054800 :94ADF045:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:08:07.054801 :94ADF046:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=151 time=4045264218 2025-05-09 07:08:07.054801 :94ADF047:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:08:07.054801 :94ADF048:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:08:07.054802 :94ADF049:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:08:07.054802 :94ADF04A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:08:07.054802 :94ADF04B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:08:07.055265 :94ADF04C: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-05-09 07:08:37.104673 :94ADFA6C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:09:07.122197 :94AE03FD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:09:07.111 2025-05-09 07:09:07.122199 :94AE03FE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10872 pso_num=25 pso_serial#=134 2025-05-09 07:09:07.122327 :94AE03FF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10872 osp_idx=23 osp_ver=82243 osp_pg=0 (spawn #163573) 2025-05-09 07:09:07.122328 :94AE0400:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:09:07.122328 :94AE0401:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=152 time=4045324281 2025-05-09 07:09:07.122329 :94AE0402:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:09:07.122329 :94AE0403:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:09:07.122330 :94AE0404:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:09:07.122330 :94AE0405:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:09:07.122330 :94AE0406:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:09:07.122797 :94AE0407: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-05-09 07:09:37.217383 :94AE0DE5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:10:07.303836 :94AE189A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:10:07.299 2025-05-09 07:10:07.303837 :94AE189B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10120 pso_num=25 pso_serial#=135 2025-05-09 07:10:07.303974 :94AE189C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10120 osp_idx=23 osp_ver=82244 osp_pg=0 (spawn #163576) 2025-05-09 07:10:07.303974 :94AE189D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:10:07.303975 :94AE189E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=153 time=4045384468 2025-05-09 07:10:07.303975 :94AE189F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:10:07.303976 :94AE18A0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:10:07.303976 :94AE18A1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:10:07.303976 :94AE18A2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:10:07.303977 :94AE18A3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:10:07.304499 :94AE18A4: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-05-09 07:10:07.304545 :94AE18AE: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-05-09 07:20:08.102118 :94AEDCFA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:20:08.684872 :94AEDDC8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:20:08.680 2025-05-09 07:20:08.684874 :94AEDDC9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10296 pso_num=25 pso_serial#=136 2025-05-09 07:20:08.685019 :94AEDDCA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10296 osp_idx=23 osp_ver=82245 osp_pg=0 (spawn #163599) 2025-05-09 07:20:08.685020 :94AEDDCB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:20:08.685021 :94AEDDCC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=154 time=4045985843 2025-05-09 07:20:08.685021 :94AEDDCD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:20:08.685021 :94AEDDCE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:20:08.685022 :94AEDDCF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:20:08.685022 :94AEDDD0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:20:08.685022 :94AEDDD1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:20:08.685531 :94AEDDD2: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-05-09 07:20:08.685576 :94AEDDDB: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-05-09 07:23:32.551396 :94AF20A6: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-05-09 07:30:09.998508 :94AFA3FD: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-05-09 07:40:10.927675 :94B06A53:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:40:11.369783 :94B06A8B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:40:11.365 2025-05-09 07:40:11.369785 :94B06A8C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10492 pso_num=25 pso_serial#=137 2025-05-09 07:40:11.369929 :94B06A8D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10492 osp_idx=23 osp_ver=82246 osp_pg=0 (spawn #163643) 2025-05-09 07:40:11.369930 :94B06A8E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:40:11.369930 :94B06A8F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=155 time=4047188531 2025-05-09 07:40:11.369931 :94B06A90:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:40:11.369931 :94B06A91:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:40:11.369931 :94B06A92:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:40:11.369932 :94B06A93:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:40:11.369932 :94B06A94:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:40:11.370435 :94B06A95: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-05-09 07:40:11.370479 :94B06A9D: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-05-09 07:50:12.273940 :94B13049:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 07:50:12.684776 :94B1307E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 07:50:12.680 2025-05-09 07:50:12.684778 :94B1307F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12980 pso_num=25 pso_serial#=138 2025-05-09 07:50:12.684921 :94B13080:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12980 osp_idx=23 osp_ver=82247 osp_pg=0 (spawn #163666) 2025-05-09 07:50:12.684922 :94B13081:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:50:12.684922 :94B13082:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=156 time=4047789843 2025-05-09 07:50:12.684923 :94B13083:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:50:12.684924 :94B13084:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:50:12.684924 :94B13085:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:50:12.684925 :94B13086:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:50:12.684925 :94B13087:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:50:12.685417 :94B13088: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-05-09 07:50:12.685461 :94B13090: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-05-09 08:00:13.536690 :94B1F6B8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 08:00:13.994462 :94B1F6EF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 08:00:13.990 2025-05-09 08:00:13.994464 :94B1F6F0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12336 pso_num=25 pso_serial#=139 2025-05-09 08:00:13.994596 :94B1F6F1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12336 osp_idx=23 osp_ver=82248 osp_pg=0 (spawn #163688) 2025-05-09 08:00:13.994597 :94B1F6F2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 08:00:13.994597 :94B1F6F3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=157 time=4048391156 2025-05-09 08:00:13.994597 :94B1F6F4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 08:00:13.994597 :94B1F6F5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 08:00:13.994598 :94B1F6F6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 08:00:13.994598 :94B1F6F7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 08:00:13.994598 :94B1F6F8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 08:00:13.995082 :94B1F6F9: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-05-09 08:00:13.995129 :94B1F700: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-05-09 08:10:14.974226 :94B2C478:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 08:10:15.307080 :94B2C4AE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 08:10:15.302 2025-05-09 08:10:15.307082 :94B2C4AF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4696 pso_num=25 pso_serial#=140 2025-05-09 08:10:15.307217 :94B2C4B0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4696 osp_idx=23 osp_ver=82249 osp_pg=0 (spawn #163716) 2025-05-09 08:10:15.307218 :94B2C4B1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 08:10:15.307218 :94B2C4B2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=158 time=4048992468 2025-05-09 08:10:15.307218 :94B2C4B3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 08:10:15.307219 :94B2C4B4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 08:10:15.307219 :94B2C4B5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 08:10:15.307219 :94B2C4B6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 08:10:15.307220 :94B2C4B7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 08:10:15.307717 :94B2C4B8: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-05-09 08:10:15.307760 :94B2C4C0: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-05-09 08:20:16.252087 :94B38C5F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-09 08:20:16.600626 :94B38C99:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-09 08:20:16.595 2025-05-09 08:20:16.600628 :94B38C9A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12648 pso_num=25 pso_serial#=141 2025-05-09 08:20:16.600760 :94B38C9B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12648 osp_idx=23 osp_ver=82250 osp_pg=0 (spawn #163739) 2025-05-09 08:20:16.600761 :94B38C9C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 08:20:16.600761 :94B38C9D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=159 time=4049593765 2025-05-09 08:20:16.600762 :94B38C9E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 08:20:16.600762 :94B38C9F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 08:20:16.600762 :94B38CA0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 08:20:16.600762 :94B38CA1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 08:20:16.600763 :94B38CA2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 08:20:16.601281 :94B38CA3: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-05-09 08:20:16.601335 :94B38CAD: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: 12648, W000)