Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250817100022\orcl_w000_21996_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:35407M/63366M, Ph+PgF:40677M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 21996, image: ORACLE.EXE (W000) *** 2025-08-17 10:00:22.609 *** SESSION ID:(177.4627) 2025-08-17 10:00:22.609 *** 2025-08-17 10:00:22.609 Process diagnostic dump for ORACLE.EXE (W000), OS id=21996, pid: 22, proc_ser: 202, sid: 177, sess_ser: 4627 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=62 seq_num=63 snap_id=1 wait times: snap=1.100745 sec, exc=1.100745 sec, total=1.100745 sec wait times: max=5.000000 sec, heur=5 min 6 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.000028 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=61 seq_num=62 snap_id=1 wait times: snap=5.014735 sec, exc=5.014735 sec, total=5.014735 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=60 seq_num=61 snap_id=1 wait times: snap=5.004609 sec, exc=5.004609 sec, total=5.004609 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=59 seq_num=60 snap_id=1 wait times: snap=5.004641 sec, exc=5.004641 sec, total=5.004641 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=58 seq_num=59 snap_id=1 wait times: snap=5.007227 sec, exc=5.007227 sec, total=5.007227 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=5.001541 sec, exc=5.001541 sec, total=5.001541 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=5.010193 sec, exc=5.010193 sec, total=5.010193 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=5.002319 sec, exc=5.002319 sec, total=5.002319 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=5.004613 sec, exc=5.004613 sec, total=5.004613 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=5.014767 sec, exc=5.014767 sec, total=5.014767 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=5.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time Sampled Session History of session 177 serial 4627 --------------------------------------------------- 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, 09:58:22 - 10:00:22] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-17 10:00:22.609 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 21996, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-17 07:44:59.158787 :C0D8F05D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21560 pso_num=22 pso_serial#=187 2025-08-17 07:44:59.158923 :C0D8F05E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21560 osp_idx=0 osp_ver=526324 osp_pg=0 (spawn #497174) 2025-08-17 07:44:59.158924 :C0D8F05F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 07:44:59.158925 :C0D8F060:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=221 time=4097368470 2025-08-17 07:44:59.158925 :C0D8F061:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 07:44:59.158925 :C0D8F062:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 07:44:59.158926 :C0D8F063:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 07:44:59.158926 :C0D8F064:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 07:44:59.158926 :C0D8F065:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 07:44:59.159417 :C0D8F066:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 07:44:59.159464 :C0D8F070:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 07:55:00.064078 :C0D9B8DC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 07:55:00.443868 :C0D9B934:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 07:55:00.438 2025-08-17 07:55:00.443870 :C0D9B935:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19156 pso_num=22 pso_serial#=188 2025-08-17 07:55:00.444009 :C0D9B936:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19156 osp_idx=0 osp_ver=526325 osp_pg=0 (spawn #497196) 2025-08-17 07:55:00.444010 :C0D9B937:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 07:55:00.444010 :C0D9B938:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=222 time=4097969751 2025-08-17 07:55:00.444010 :C0D9B939:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 07:55:00.444011 :C0D9B93A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 07:55:00.444011 :C0D9B93B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 07:55:00.444011 :C0D9B93C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 07:55:00.444012 :C0D9B93D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 07:55:00.444502 :C0D9B93E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 07:55:00.444548 :C0D9B945:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 08:05:01.316799 :C0DA8671:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 08:05:01.712262 :C0DA86BE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 08:05:01.707 2025-08-17 08:05:01.712265 :C0DA86BF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18472 pso_num=22 pso_serial#=189 2025-08-17 08:05:01.712413 :C0DA86C0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18472 osp_idx=0 osp_ver=526326 osp_pg=0 (spawn #497222) 2025-08-17 08:05:01.712414 :C0DA86C1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 08:05:01.712414 :C0DA86C2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=223 time=4098571017 2025-08-17 08:05:01.712415 :C0DA86C3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 08:05:01.712415 :C0DA86C4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 08:05:01.712415 :C0DA86C5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 08:05:01.712416 :C0DA86C6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 08:05:01.712416 :C0DA86C7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 08:05:01.712920 :C0DA86C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 08:05:01.712968 :C0DA86CF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 08:15:02.548341 :C0DB55DD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 08:15:02.912402 :C0DB5634:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 08:15:02.908 2025-08-17 08:15:02.912405 :C0DB5635:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14932 pso_num=22 pso_serial#=190 2025-08-17 08:15:02.912542 :C0DB5636:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14932 osp_idx=0 osp_ver=526327 osp_pg=0 (spawn #497246) 2025-08-17 08:15:02.912543 :C0DB5637:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 08:15:02.912544 :C0DB5638:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=224 time=4099172220 2025-08-17 08:15:02.912544 :C0DB5639:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 08:15:02.912545 :C0DB563A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 08:15:02.912545 :C0DB563B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 08:15:02.912546 :C0DB563C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 08:15:02.912546 :C0DB563D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 08:15:02.913036 :C0DB563E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 08:15:02.913079 :C0DB5646:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 08:24:24.227338 :C0DC128C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 08:25:04.148846 :C0DC1FB7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 08:35:05.030562 :C0DCE862:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 08:35:05.410471 :C0DCE8A8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 08:35:05.405 2025-08-17 08:35:05.410474 :C0DCE8A9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21264 pso_num=22 pso_serial#=191 2025-08-17 08:35:05.410608 :C0DCE8AA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21264 osp_idx=0 osp_ver=526328 osp_pg=0 (spawn #497290) 2025-08-17 08:35:05.410609 :C0DCE8AB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 08:35:05.410609 :C0DCE8AC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=225 time=4100374720 2025-08-17 08:35:05.410610 :C0DCE8AD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 08:35:05.410610 :C0DCE8AE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 08:35:05.410611 :C0DCE8AF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 08:35:05.410611 :C0DCE8B0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 08:35:05.410611 :C0DCE8B1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 08:35:05.411127 :C0DCE8B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 08:35:05.411180 :C0DCE8B9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 08:45:06.366407 :C0DDB259:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 08:45:06.730737 :C0DDB28B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 08:45:06.725 2025-08-17 08:45:06.730740 :C0DDB28C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8140 pso_num=22 pso_serial#=192 2025-08-17 08:45:06.730879 :C0DDB28D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8140 osp_idx=0 osp_ver=526329 osp_pg=0 (spawn #497313) 2025-08-17 08:45:06.730880 :C0DDB28E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 08:45:06.730880 :C0DDB28F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=226 time=4100976033 2025-08-17 08:45:06.730881 :C0DDB290:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 08:45:06.730881 :C0DDB291:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 08:45:06.730881 :C0DDB292:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 08:45:06.730881 :C0DDB293:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 08:45:06.730881 :C0DDB294:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 08:45:06.731389 :C0DDB295:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 08:45:06.731437 :C0DDB29B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 08:55:07.645334 :C0DE7A31:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 08:55:08.025129 :C0DE7A87:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 08:55:08.020 2025-08-17 08:55:08.025132 :C0DE7A88:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16876 pso_num=22 pso_serial#=193 2025-08-17 08:55:08.025270 :C0DE7A89:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16876 osp_idx=0 osp_ver=526330 osp_pg=0 (spawn #497335) 2025-08-17 08:55:08.025270 :C0DE7A8A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 08:55:08.025271 :C0DE7A8B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=227 time=4101577329 2025-08-17 08:55:08.025271 :C0DE7A8C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 08:55:08.025272 :C0DE7A8D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 08:55:08.025272 :C0DE7A8E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 08:55:08.025272 :C0DE7A8F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 08:55:08.025272 :C0DE7A90:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 08:55:08.025765 :C0DE7A91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 08:55:08.025811 :C0DE7A98:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 09:05:08.823708 :C0DF451A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 09:05:09.244894 :C0DF457B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 09:05:09.240 2025-08-17 09:05:09.244896 :C0DF457C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6744 pso_num=22 pso_serial#=194 2025-08-17 09:05:09.245037 :C0DF457D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6744 osp_idx=0 osp_ver=526331 osp_pg=0 (spawn #497361) 2025-08-17 09:05:09.245037 :C0DF457E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 09:05:09.245038 :C0DF457F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=228 time=4102178548 2025-08-17 09:05:09.245038 :C0DF4580:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 09:05:09.245038 :C0DF4581:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 09:05:09.245039 :C0DF4582:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 09:05:09.245039 :C0DF4583:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 09:05:09.245039 :C0DF4584:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 09:05:09.245535 :C0DF4585:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 09:05:09.245581 :C0DF458D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 09:15:10.130667 :C0E016AD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 09:15:10.510228 :C0E016DC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 09:15:10.505 2025-08-17 09:15:10.510230 :C0E016DD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18956 pso_num=22 pso_serial#=195 2025-08-17 09:15:10.510375 :C0E016DE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18956 osp_idx=0 osp_ver=526332 osp_pg=0 (spawn #497384) 2025-08-17 09:15:10.510375 :C0E016DF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 09:15:10.510376 :C0E016E0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=229 time=4102779814 2025-08-17 09:15:10.510376 :C0E016E1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 09:15:10.510376 :C0E016E2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 09:15:10.510377 :C0E016E3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 09:15:10.510377 :C0E016E4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 09:15:10.510378 :C0E016E5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 09:15:10.510863 :C0E016E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 09:15:10.510907 :C0E016EE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 09:25:11.476573 :C0E0DD88:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 09:25:11.856414 :C0E0DDC5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 09:25:11.851 2025-08-17 09:25:11.856416 :C0E0DDC6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14932 pso_num=22 pso_serial#=196 2025-08-17 09:25:11.856549 :C0E0DDC7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14932 osp_idx=0 osp_ver=526333 osp_pg=0 (spawn #497406) 2025-08-17 09:25:11.856550 :C0E0DDC8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 09:25:11.856551 :C0E0DDC9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=230 time=4103381158 2025-08-17 09:25:11.856551 :C0E0DDCA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 09:25:11.856552 :C0E0DDCB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 09:25:11.856552 :C0E0DDCC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 09:25:11.856552 :C0E0DDCD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 09:25:11.856553 :C0E0DDCE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 09:25:11.857043 :C0E0DDCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 09:25:11.857087 :C0E0DDD7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 09:35:12.803723 :C0E1A78E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 09:35:13.261609 :C0E1A7CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 09:35:13.257 2025-08-17 09:35:13.261611 :C0E1A7CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14304 pso_num=22 pso_serial#=197 2025-08-17 09:35:13.261783 :C0E1A7CC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14304 osp_idx=0 osp_ver=526334 osp_pg=0 (spawn #497429) 2025-08-17 09:35:13.261783 :C0E1A7CD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 09:35:13.261783 :C0E1A7CE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=231 time=4103982564 2025-08-17 09:35:13.261783 :C0E1A7CF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 09:35:13.261784 :C0E1A7D0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 09:35:13.261784 :C0E1A7D1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 09:35:13.261784 :C0E1A7D2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 09:35:13.261785 :C0E1A7D3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 09:35:13.262291 :C0E1A7D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 09:35:13.262342 :C0E1A7DC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 09:45:14.216202 :C0E270BF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 09:45:14.595947 :C0E270EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 09:45:14.591 2025-08-17 09:45:14.595949 :C0E270ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14924 pso_num=22 pso_serial#=198 2025-08-17 09:45:14.596090 :C0E270EE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14924 osp_idx=0 osp_ver=526335 osp_pg=0 (spawn #497452) 2025-08-17 09:45:14.596091 :C0E270EF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 09:45:14.596091 :C0E270F0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=232 time=4104583908 2025-08-17 09:45:14.596092 :C0E270F1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 09:45:14.596092 :C0E270F2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 09:45:14.596092 :C0E270F3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 09:45:14.596093 :C0E270F4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 09:45:14.596093 :C0E270F5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 09:45:14.596595 :C0E270F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 09:45:14.596657 :C0E270FC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-17 09:55:15.476976 :C0E33A11:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 09:55:15.693144 :C0E33A21:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 09:55:15.679 2025-08-17 09:55:15.693146 :C0E33A22:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20712 pso_num=22 pso_serial#=199 2025-08-17 09:55:15.693285 :C0E33A23:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20712 (legacy spawn) 2025-08-17 09:55:15.699843 :C0E33A24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 09:55:15.700028 :C0E33A29:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-17 09:55:15.700817 :C0E33A2E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 09:55:15.709428 :C0E33A31:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 09:55:15.695 2025-08-17 09:55:15.709430 :C0E33A32:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21068 pso_num=22 pso_serial#=200 2025-08-17 09:55:15.709572 :C0E33A33:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21068 (legacy spawn) 2025-08-17 09:55:15.716107 :C0E33A34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 09:55:15.716267 :C0E33A3C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-17 09:55:15.717046 :C0E33A3E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 09:55:15.923467 :C0E33A41:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 09:55:15.914 2025-08-17 09:55:15.923469 :C0E33A42:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21136 pso_num=22 pso_serial#=201 2025-08-17 09:55:15.923614 :C0E33A43:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21136 (legacy spawn) 2025-08-17 09:55:15.930464 :C0E33A44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 09:55:15.930639 :C0E33A4D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-17 09:55:15.931522 :C0E33A4E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 09:55:16.075275 :C0E33A89:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 09:55:16.070 2025-08-17 09:55:16.075277 :C0E33A8A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21996 pso_num=22 pso_serial#=202 2025-08-17 09:55:16.075416 :C0E33A8B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21996 osp_idx=0 osp_ver=526339 osp_pg=0 (spawn #497474) 2025-08-17 09:55:16.075417 :C0E33A8C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 09:55:16.075417 :C0E33A8D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=233 time=4105185376 2025-08-17 09:55:16.075418 :C0E33A8E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 09:55:16.075418 :C0E33A8F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 09:55:16.075419 :C0E33A90:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 09:55:16.075419 :C0E33A91:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 09:55:16.075420 :C0E33A92:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 09:55:16.075899 :C0E33A93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 09:55:16.075943 :C0E33A9B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 21996, W000)