Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250913082356\orcl_w000_20628_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:34797M/63366M, Ph+PgF:39447M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 20628, image: ORACLE.EXE (W000) *** 2025-09-13 08:23:56.142 *** SESSION ID:(201.61155) 2025-09-13 08:23:56.142 *** 2025-09-13 08:23:56.142 Process diagnostic dump for ORACLE.EXE (W000), OS id=20628, pid: 25, proc_ser: 197, sid: 201, sess_ser: 61155 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=100 seq_num=101 snap_id=1 wait times: snap=1.645235 sec, exc=1.645235 sec, total=1.645235 sec wait times: max=5.000000 sec, heur=8 min 17 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.000039 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=99 seq_num=100 snap_id=1 wait times: snap=5.000405 sec, exc=5.000405 sec, total=5.000405 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000092 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=98 seq_num=99 snap_id=1 wait times: snap=5.010926 sec, exc=5.010926 sec, total=5.010926 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=97 seq_num=98 snap_id=1 wait times: snap=5.004643 sec, exc=5.004643 sec, total=5.004643 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=5.004658 sec, exc=5.004658 sec, total=5.004658 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=95 seq_num=96 snap_id=1 wait times: snap=5.009679 sec, exc=5.009679 sec, total=5.009679 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=5.003245 sec, exc=5.003245 sec, total=5.003245 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 6: 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.000396 sec, exc=5.000396 sec, total=5.000396 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 7: 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.007573 sec, exc=5.007573 sec, total=5.007573 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000040 sec of elapsed time 8: 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.013859 sec, exc=5.013859 sec, total=5.013859 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000093 sec of elapsed time 9: 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.004646 sec, exc=5.004646 sec, total=5.004646 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time Sampled Session History of session 201 serial 61155 --------------------------------------------------- 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:21:56 - 08:23:56] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-13 08:23:56.142 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 20628, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-13 05:35:19.293521 :CCB90084: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-13 05:40:15.850107 :CCB9637B: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-09-13 05:45:20.513026 :CCB9CAE6: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-09-13 05:55:21.293846 :CCBA9508:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 05:55:21.689014 :CCBA9537:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 05:55:21.684 2025-09-13 05:55:21.689017 :CCBA9538:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25008 pso_num=25 pso_serial#=184 2025-09-13 05:55:21.689155 :CCBA9539:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25008 osp_idx=23 osp_ver=294467 osp_pg=0 (spawn #586927) 2025-09-13 05:55:21.689156 :CCBA953A: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-13 05:55:21.689156 :CCBA953B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=228 time=2128572987 2025-09-13 05:55:21.689157 :CCBA953C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 05:55:21.689158 :CCBA953D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 05:55:21.689158 :CCBA953E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 05:55:21.689158 :CCBA953F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 05:55:21.689159 :CCBA9540:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 05:55:21.689719 :CCBA9541: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-13 05:55:21.689764 :CCBA9549: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-13 06:05:22.523840 :CCBB6FA3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 06:05:22.965991 :CCBB6FD0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 06:05:22.961 2025-09-13 06:05:22.965993 :CCBB6FD1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25384 pso_num=25 pso_serial#=185 2025-09-13 06:05:22.966128 :CCBB6FD2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25384 osp_idx=23 osp_ver=294468 osp_pg=0 (spawn #586958) 2025-09-13 06:05:22.966129 :CCBB6FD3: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-13 06:05:22.966129 :CCBB6FD4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=229 time=2129174268 2025-09-13 06:05:22.966130 :CCBB6FD5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 06:05:22.966130 :CCBB6FD6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 06:05:22.966130 :CCBB6FD7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 06:05:22.966131 :CCBB6FD8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 06:05:22.966131 :CCBB6FD9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 06:05:22.966624 :CCBB6FDA: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-13 06:05:22.966666 :CCBB6FE2: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-13 06:15:23.864027 :CCBC4175:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 06:15:24.259450 :CCBC41AF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 06:15:24.255 2025-09-13 06:15:24.259452 :CCBC41B0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16984 pso_num=25 pso_serial#=186 2025-09-13 06:15:24.259598 :CCBC41B1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16984 osp_idx=23 osp_ver=294469 osp_pg=0 (spawn #586981) 2025-09-13 06:15:24.259598 :CCBC41B2: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-13 06:15:24.259599 :CCBC41B3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=230 time=2129775565 2025-09-13 06:15:24.259599 :CCBC41B4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 06:15:24.259600 :CCBC41B5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 06:15:24.259600 :CCBC41B6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 06:15:24.259600 :CCBC41B7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 06:15:24.259601 :CCBC41B8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 06:15:24.260109 :CCBC41B9: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-13 06:15:24.260165 :CCBC41BF: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-13 06:25:25.088969 :CCBD0FEA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 06:25:25.515586 :CCBD101F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 06:25:25.510 2025-09-13 06:25:25.515588 :CCBD1020:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24520 pso_num=25 pso_serial#=187 2025-09-13 06:25:25.515725 :CCBD1021:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24520 osp_idx=23 osp_ver=294470 osp_pg=0 (spawn #587004) 2025-09-13 06:25:25.515726 :CCBD1022: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-13 06:25:25.515727 :CCBD1023:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=231 time=2130376815 2025-09-13 06:25:25.515727 :CCBD1024:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 06:25:25.515728 :CCBD1025:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 06:25:25.515728 :CCBD1026:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 06:25:25.515728 :CCBD1027:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 06:25:25.515729 :CCBD1028:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 06:25:25.516275 :CCBD1029: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-13 06:25:25.516318 :CCBD1031: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-13 06:35:26.369978 :CCBDDC23:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 06:35:26.833021 :CCBDDC50:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 06:35:26.828 2025-09-13 06:35:26.833024 :CCBDDC51:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21120 pso_num=25 pso_serial#=188 2025-09-13 06:35:26.833188 :CCBDDC52:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21120 osp_idx=23 osp_ver=294471 osp_pg=0 (spawn #587026) 2025-09-13 06:35:26.833188 :CCBDDC53: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-13 06:35:26.833189 :CCBDDC54:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=232 time=2130978143 2025-09-13 06:35:26.833189 :CCBDDC55:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 06:35:26.833190 :CCBDDC56:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 06:35:26.833190 :CCBDDC57:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 06:35:26.833190 :CCBDDC58:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 06:35:26.833191 :CCBDDC59:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 06:35:26.833694 :CCBDDC5A: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-13 06:35:26.833742 :CCBDDC62: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-13 06:45:27.626475 :CCBEA762:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 06:45:28.132511 :CCBEA7A6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 06:45:28.127 2025-09-13 06:45:28.132512 :CCBEA7A7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24264 pso_num=25 pso_serial#=189 2025-09-13 06:45:28.132652 :CCBEA7A8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24264 osp_idx=23 osp_ver=294472 osp_pg=0 (spawn #587049) 2025-09-13 06:45:28.132653 :CCBEA7A9: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-13 06:45:28.132654 :CCBEA7AA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=2131579440 2025-09-13 06:45:28.132655 :CCBEA7AB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 06:45:28.132655 :CCBEA7AC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 06:45:28.132655 :CCBEA7AD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 06:45:28.132656 :CCBEA7AE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 06:45:28.132656 :CCBEA7AF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 06:45:28.133170 :CCBEA7B0: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-13 06:45:28.133215 :CCBEA7B7: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-13 06:55:28.963860 :CCBF71DC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 06:55:29.406199 :CCBF7212:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 06:55:29.401 2025-09-13 06:55:29.406201 :CCBF7213:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21304 pso_num=25 pso_serial#=190 2025-09-13 06:55:29.406335 :CCBF7214:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21304 osp_idx=23 osp_ver=294473 osp_pg=0 (spawn #587072) 2025-09-13 06:55:29.406335 :CCBF7215: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-13 06:55:29.406336 :CCBF7216:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=2132180705 2025-09-13 06:55:29.406336 :CCBF7217:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 06:55:29.406337 :CCBF7218:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 06:55:29.406337 :CCBF7219:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 06:55:29.406337 :CCBF721A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 06:55:29.406337 :CCBF721B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 06:55:29.406814 :CCBF721C: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-13 06:55:29.406863 :CCBF7223: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-13 07:05:30.097249 :CCC043D2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 07:05:30.648862 :CCC0440A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 07:05:30.644 2025-09-13 07:05:30.648865 :CCC0440B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23796 pso_num=25 pso_serial#=191 2025-09-13 07:05:30.649002 :CCC0440C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23796 osp_idx=23 osp_ver=294474 osp_pg=0 (spawn #587097) 2025-09-13 07:05:30.649003 :CCC0440D: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-13 07:05:30.649004 :CCC0440E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=235 time=2132781955 2025-09-13 07:05:30.649004 :CCC0440F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 07:05:30.649004 :CCC04410:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 07:05:30.649005 :CCC04411:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 07:05:30.649005 :CCC04412:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 07:05:30.649005 :CCC04413:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 07:05:30.649503 :CCC04414: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-13 07:05:30.649545 :CCC0441C: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-13 07:15:31.377463 :CCC10FF9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 07:15:31.788469 :CCC1103C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 07:15:31.783 2025-09-13 07:15:31.788471 :CCC1103D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24068 pso_num=25 pso_serial#=192 2025-09-13 07:15:31.788611 :CCC1103E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24068 osp_idx=23 osp_ver=294475 osp_pg=0 (spawn #587120) 2025-09-13 07:15:31.788612 :CCC1103F: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-13 07:15:31.788612 :CCC11040:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=236 time=2133383096 2025-09-13 07:15:31.788613 :CCC11041:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 07:15:31.788613 :CCC11042:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 07:15:31.788613 :CCC11043:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 07:15:31.788614 :CCC11044:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 07:15:31.788614 :CCC11045:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 07:15:31.789116 :CCC11046: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-13 07:15:31.789164 :CCC1104C: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-13 07:25:32.622089 :CCC1D994:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 07:25:32.925993 :CCC1D9CA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 07:25:32.921 2025-09-13 07:25:32.925995 :CCC1D9CB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20612 pso_num=25 pso_serial#=193 2025-09-13 07:25:32.926131 :CCC1D9CC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20612 osp_idx=23 osp_ver=294476 osp_pg=0 (spawn #587143) 2025-09-13 07:25:32.926131 :CCC1D9CD: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-13 07:25:32.926132 :CCC1D9CE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=237 time=2133984237 2025-09-13 07:25:32.926132 :CCC1D9CF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 07:25:32.926132 :CCC1D9D0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 07:25:32.926133 :CCC1D9D1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 07:25:32.926133 :CCC1D9D2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 07:25:32.926134 :CCC1D9D3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 07:25:32.926623 :CCC1D9D4: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-13 07:25:32.926669 :CCC1D9DB: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-13 07:35:33.742809 :CCC2A3BC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 07:35:34.106953 :CCC2A3F6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 07:35:34.102 2025-09-13 07:35:34.106955 :CCC2A3F7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22436 pso_num=25 pso_serial#=194 2025-09-13 07:35:34.107104 :CCC2A3F8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22436 osp_idx=23 osp_ver=294477 osp_pg=0 (spawn #587165) 2025-09-13 07:35:34.107105 :CCC2A3F9: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-13 07:35:34.107105 :CCC2A3FA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=238 time=2134585408 2025-09-13 07:35:34.107106 :CCC2A3FB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 07:35:34.107106 :CCC2A3FC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 07:35:34.107106 :CCC2A3FD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 07:35:34.107107 :CCC2A3FE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 07:35:34.107107 :CCC2A3FF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 07:35:34.107614 :CCC2A400: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-13 07:35:34.107662 :CCC2A406: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-13 07:45:16.668028 :CCC36668: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-09-13 07:45:35.333910 :CCC36D1D: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-09-13 07:55:36.189335 :CCC437C3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 07:55:36.506548 :CCC437FC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 07:55:36.501 2025-09-13 07:55:36.506550 :CCC437FD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25396 pso_num=25 pso_serial#=195 2025-09-13 07:55:36.506686 :CCC437FE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25396 osp_idx=23 osp_ver=294478 osp_pg=0 (spawn #587210) 2025-09-13 07:55:36.506687 :CCC437FF: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-13 07:55:36.506687 :CCC43800:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=239 time=2135787815 2025-09-13 07:55:36.506687 :CCC43801:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 07:55:36.506688 :CCC43802:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 07:55:36.506688 :CCC43803:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 07:55:36.506688 :CCC43804:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 07:55:36.506688 :CCC43805:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 07:55:36.507178 :CCC43806: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-13 07:55:36.507219 :CCC4380E: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-13 08:05:37.309854 :CCC50B8C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 08:05:37.691542 :CCC50BF2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 08:05:37.686 2025-09-13 08:05:37.691545 :CCC50BF3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22572 pso_num=25 pso_serial#=196 2025-09-13 08:05:37.691694 :CCC50BF4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22572 osp_idx=23 osp_ver=294479 osp_pg=0 (spawn #587235) 2025-09-13 08:05:37.691694 :CCC50BF5: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-13 08:05:37.691695 :CCC50BF6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=240 time=2136389002 2025-09-13 08:05:37.691695 :CCC50BF7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:05:37.691696 :CCC50BF8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:05:37.691696 :CCC50BF9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:05:37.691696 :CCC50BFA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:05:37.691697 :CCC50BFB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:05:37.692209 :CCC50BFC: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-13 08:05:37.692257 :CCC50C03: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-13 08:15:38.478471 :CCC5D563:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 08:15:38.837199 :CCC5D5CA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 08:15:38.832 2025-09-13 08:15:38.837200 :CCC5D5CB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20628 pso_num=25 pso_serial#=197 2025-09-13 08:15:38.837346 :CCC5D5CC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20628 osp_idx=23 osp_ver=294480 osp_pg=0 (spawn #587258) 2025-09-13 08:15:38.837347 :CCC5D5CD: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-13 08:15:38.837347 :CCC5D5CE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=241 time=2136990143 2025-09-13 08:15:38.837348 :CCC5D5CF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:15:38.837348 :CCC5D5D0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:15:38.837349 :CCC5D5D1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:15:38.837349 :CCC5D5D2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:15:38.837349 :CCC5D5D3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:15:38.837864 :CCC5D5D4: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-13 08:15:38.837914 :CCC5D5DB: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: 20628, W000)