Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250714081349\orcl_w000_7020_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:36350M/63366M, Ph+PgF:42326M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 7020, image: ORACLE.EXE (W000) *** 2025-07-14 08:13:49.833 *** SESSION ID:(201.40419) 2025-07-14 08:13:49.833 *** 2025-07-14 08:13:49.833 Process diagnostic dump for ORACLE.EXE (W000), OS id=7020, pid: 25, proc_ser: 47, sid: 201, sess_ser: 40419 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=3.613720 sec, exc=3.613720 sec, total=3.613720 sec wait times: max=5.000000 sec, heur=38.664854 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.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=5.014592 sec, exc=5.014592 sec, total=5.014592 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=5.010145 sec, exc=5.010145 sec, total=5.010145 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=5 seq_num=6 snap_id=1 wait times: snap=5.009418 sec, exc=5.009418 sec, total=5.009418 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=5.001726 sec, exc=5.001726 sec, total=5.001726 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=5.001292 sec, exc=5.001292 sec, total=5.001292 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=5.014034 sec, exc=5.014034 sec, total=5.014034 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=4.999759 sec, exc=4.999759 sec, total=4.999759 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.003976 sec of elapsed time 7: waited for 'class slave wait' slave id=0x88727a0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000039 sec, exc=0.000039 sec, total=0.000039 sec wait times: max=infinite wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 201 serial 40419 --------------------------------------------------- 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 --------------------------------------------------- [39 samples, 08:13:11 - 08:13:49] idle wait at each sample [session created at: 08:13:11] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-14 08:13:49.833 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 7020, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-14 05:42:50.694170 :B1BEC273:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 05:42:50.694171 :B1BEC274:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 05:42:50.694663 :B1BEC275: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-07-14 05:42:50.694705 :B1BEC27D: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-07-14 05:52:51.680828 :B1BF8B3C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 05:52:52.091759 :B1BF8B81:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 05:52:52.087 2025-07-14 05:52:52.091761 :B1BF8B82:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7960 pso_num=25 pso_serial#=34 2025-07-14 05:52:52.091904 :B1BF8B83:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7960 osp_idx=23 osp_ver=195986 osp_pg=0 (spawn #383594) 2025-07-14 05:52:52.091905 :B1BF8B84:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 05:52:52.091906 :B1BF8B85:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=209 time=1153147986 2025-07-14 05:52:52.091906 :B1BF8B86:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 05:52:52.091907 :B1BF8B87:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 05:52:52.091907 :B1BF8B88:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 05:52:52.091907 :B1BF8B89:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 05:52:52.091908 :B1BF8B8A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 05:52:52.092399 :B1BF8B8B: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-07-14 05:52:52.092446 :B1BF8B93: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-07-14 06:02:53.026809 :B1C05651:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 06:02:53.437695 :B1C05687:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 06:02:53.432 2025-07-14 06:02:53.437697 :B1C05688:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17352 pso_num=25 pso_serial#=35 2025-07-14 06:02:53.437841 :B1C05689:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17352 osp_idx=23 osp_ver=195987 osp_pg=0 (spawn #383620) 2025-07-14 06:02:53.437842 :B1C0568A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 06:02:53.437842 :B1C0568B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=210 time=1153749329 2025-07-14 06:02:53.437843 :B1C0568C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 06:02:53.437843 :B1C0568D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 06:02:53.437843 :B1C0568E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 06:02:53.437844 :B1C0568F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 06:02:53.437844 :B1C05690:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 06:02:53.438339 :B1C05691: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-07-14 06:02:53.438387 :B1C05699: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-07-14 06:12:54.437701 :B1C124FD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 06:12:54.895514 :B1C1253F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 06:12:54.890 2025-07-14 06:12:54.895517 :B1C12540:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15328 pso_num=25 pso_serial#=36 2025-07-14 06:12:54.895657 :B1C12541:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15328 osp_idx=23 osp_ver=195988 osp_pg=0 (spawn #383642) 2025-07-14 06:12:54.895658 :B1C12542:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 06:12:54.895658 :B1C12543:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=211 time=1154350783 2025-07-14 06:12:54.895658 :B1C12544:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 06:12:54.895658 :B1C12545:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 06:12:54.895659 :B1C12546:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 06:12:54.895659 :B1C12547:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 06:12:54.895659 :B1C12548:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 06:12:54.896156 :B1C12549: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-07-14 06:12:54.896201 :B1C12553: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-07-14 06:22:55.825152 :B1C1F05B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 06:22:56.298761 :B1C1F0A0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 06:22:56.294 2025-07-14 06:22:56.298764 :B1C1F0A1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18156 pso_num=25 pso_serial#=37 2025-07-14 06:22:56.298902 :B1C1F0A2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18156 osp_idx=23 osp_ver=195989 osp_pg=0 (spawn #383665) 2025-07-14 06:22:56.298902 :B1C1F0A3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 06:22:56.298903 :B1C1F0A4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=212 time=1154952189 2025-07-14 06:22:56.298903 :B1C1F0A5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 06:22:56.298904 :B1C1F0A6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 06:22:56.298904 :B1C1F0A7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 06:22:56.298904 :B1C1F0A8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 06:22:56.298904 :B1C1F0A9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 06:22:56.299412 :B1C1F0AA: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-07-14 06:22:56.299462 :B1C1F0B4: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-07-14 06:32:57.165961 :B1C2B936:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 06:32:57.655174 :B1C2B979:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 06:32:57.650 2025-07-14 06:32:57.655176 :B1C2B97A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12140 pso_num=25 pso_serial#=38 2025-07-14 06:32:57.655313 :B1C2B97B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12140 osp_idx=23 osp_ver=195990 osp_pg=0 (spawn #383688) 2025-07-14 06:32:57.655313 :B1C2B97C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 06:32:57.655314 :B1C2B97D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=213 time=1155553548 2025-07-14 06:32:57.655314 :B1C2B97E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 06:32:57.655314 :B1C2B97F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 06:32:57.655315 :B1C2B980:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 06:32:57.655315 :B1C2B981:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 06:32:57.655315 :B1C2B982:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 06:32:57.655821 :B1C2B983: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-07-14 06:32:57.655872 :B1C2B989: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-07-14 06:35:17.820775 :B1C2E847: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-07-14 06:42:59.085394 :B1C38328: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-07-14 06:53:00.045124 :B1C44AF6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 06:53:00.471515 :B1C44B2B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 06:53:00.466 2025-07-14 06:53:00.471517 :B1C44B2C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11032 pso_num=25 pso_serial#=39 2025-07-14 06:53:00.471657 :B1C44B2D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11032 osp_idx=23 osp_ver=195991 osp_pg=0 (spawn #383732) 2025-07-14 06:53:00.471659 :B1C44B2E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 06:53:00.471659 :B1C44B2F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=1156756361 2025-07-14 06:53:00.471660 :B1C44B30:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 06:53:00.471660 :B1C44B31:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 06:53:00.471660 :B1C44B32:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 06:53:00.471661 :B1C44B33:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 06:53:00.471661 :B1C44B34:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 06:53:00.472150 :B1C44B35: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-07-14 06:53:00.472195 :B1C44B3E: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-07-14 07:03:01.416323 :B1C51457:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 07:03:01.827326 :B1C514B2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 07:03:01.823 2025-07-14 07:03:01.827328 :B1C514B3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14624 pso_num=25 pso_serial#=40 2025-07-14 07:03:01.827468 :B1C514B4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14624 osp_idx=23 osp_ver=195992 osp_pg=0 (spawn #383758) 2025-07-14 07:03:01.827469 :B1C514B5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 07:03:01.827469 :B1C514B6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=1157357720 2025-07-14 07:03:01.827469 :B1C514B7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 07:03:01.827470 :B1C514B8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 07:03:01.827470 :B1C514B9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 07:03:01.827470 :B1C514BA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 07:03:01.827471 :B1C514BB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 07:03:01.827970 :B1C514BC: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-07-14 07:03:01.828015 :B1C514C2: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-07-14 07:13:02.733338 :B1C5E416:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 07:13:03.206886 :B1C5E476:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 07:13:03.202 2025-07-14 07:13:03.206888 :B1C5E477:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17332 pso_num=25 pso_serial#=41 2025-07-14 07:13:03.207034 :B1C5E478:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17332 osp_idx=23 osp_ver=195993 osp_pg=0 (spawn #383780) 2025-07-14 07:13:03.207035 :B1C5E479:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 07:13:03.207036 :B1C5E47A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=1157959095 2025-07-14 07:13:03.207036 :B1C5E47B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 07:13:03.207037 :B1C5E47C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 07:13:03.207037 :B1C5E47D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 07:13:03.207037 :B1C5E47E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 07:13:03.207038 :B1C5E47F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 07:13:03.207557 :B1C5E480: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-07-14 07:13:03.207604 :B1C5E48A: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-07-14 07:23:04.165760 :B1C6AB2E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 07:23:04.561113 :B1C6AB93:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 07:23:04.556 2025-07-14 07:23:04.561115 :B1C6AB94:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4660 pso_num=25 pso_serial#=42 2025-07-14 07:23:04.561250 :B1C6AB95:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4660 osp_idx=23 osp_ver=195994 osp_pg=0 (spawn #383803) 2025-07-14 07:23:04.561251 :B1C6AB96:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 07:23:04.561251 :B1C6AB97:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=1158560454 2025-07-14 07:23:04.561252 :B1C6AB98:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 07:23:04.561252 :B1C6AB99:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 07:23:04.561252 :B1C6AB9A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 07:23:04.561253 :B1C6AB9B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 07:23:04.561253 :B1C6AB9C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 07:23:04.561747 :B1C6AB9D: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-07-14 07:23:04.561791 :B1C6ABA7: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-07-14 07:33:05.512956 :B1C7730A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 07:33:05.882048 :B1C77345:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 07:33:05.877 2025-07-14 07:33:05.882049 :B1C77346:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15708 pso_num=25 pso_serial#=43 2025-07-14 07:33:05.882188 :B1C77347:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15708 osp_idx=23 osp_ver=195995 osp_pg=0 (spawn #383826) 2025-07-14 07:33:05.882190 :B1C77348:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 07:33:05.882190 :B1C77349:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=218 time=1159161783 2025-07-14 07:33:05.882190 :B1C7734A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 07:33:05.882191 :B1C7734B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 07:33:05.882191 :B1C7734C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 07:33:05.882192 :B1C7734D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 07:33:05.882192 :B1C7734E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 07:33:05.882676 :B1C7734F: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-07-14 07:33:05.882731 :B1C77359: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-07-14 07:43:06.712035 :B1C83C24:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 07:43:07.216759 :B1C83C6A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 07:43:07.212 2025-07-14 07:43:07.216762 :B1C83C6B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15376 pso_num=25 pso_serial#=44 2025-07-14 07:43:07.216893 :B1C83C6C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15376 osp_idx=23 osp_ver=195996 osp_pg=0 (spawn #383848) 2025-07-14 07:43:07.216894 :B1C83C6D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 07:43:07.216894 :B1C83C6E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=219 time=1159763111 2025-07-14 07:43:07.216895 :B1C83C6F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 07:43:07.216895 :B1C83C70:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 07:43:07.216896 :B1C83C71:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 07:43:07.216896 :B1C83C72:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 07:43:07.216896 :B1C83C73:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 07:43:07.217383 :B1C83C74: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-07-14 07:43:07.217428 :B1C83C7C: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-07-14 07:53:08.154461 :B1C9050C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 07:53:08.549819 :B1C90542:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 07:53:08.544 2025-07-14 07:53:08.549821 :B1C90543:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4224 pso_num=25 pso_serial#=45 2025-07-14 07:53:08.549960 :B1C90544:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4224 osp_idx=23 osp_ver=195997 osp_pg=0 (spawn #383872) 2025-07-14 07:53:08.549961 :B1C90545:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 07:53:08.549961 :B1C90546:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=220 time=1160364439 2025-07-14 07:53:08.549962 :B1C90547:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 07:53:08.549962 :B1C90548:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 07:53:08.549962 :B1C90549:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 07:53:08.549963 :B1C9054A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 07:53:08.549963 :B1C9054B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 07:53:08.550471 :B1C9054C: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-07-14 07:53:08.550515 :B1C90555: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-07-14 08:03:09.536937 :B1C9D015:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 08:03:09.885576 :B1C9D05C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 08:03:09.881 2025-07-14 08:03:09.885578 :B1C9D05D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6512 pso_num=25 pso_serial#=46 2025-07-14 08:03:09.885726 :B1C9D05E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6512 osp_idx=23 osp_ver=195998 osp_pg=0 (spawn #383898) 2025-07-14 08:03:09.885727 :B1C9D05F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:03:09.885727 :B1C9D060:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=221 time=1160965783 2025-07-14 08:03:09.885728 :B1C9D061:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:03:09.885728 :B1C9D062:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:03:09.885729 :B1C9D063:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:03:09.885729 :B1C9D064:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:03:09.885729 :B1C9D065:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:03:09.886252 :B1C9D066: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-07-14 08:03:09.886302 :B1C9D070: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-07-14 08:13:10.790932 :B1CAA2A7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-14 08:13:11.170703 :B1CAA2E2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-14 08:13:11.165 2025-07-14 08:13:11.170706 :B1CAA2E3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7020 pso_num=25 pso_serial#=47 2025-07-14 08:13:11.170840 :B1CAA2E4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7020 osp_idx=23 osp_ver=195999 osp_pg=0 (spawn #383920) 2025-07-14 08:13:11.170841 :B1CAA2E5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:13:11.170841 :B1CAA2E6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=222 time=1161567064 2025-07-14 08:13:11.170842 :B1CAA2E7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:13:11.170842 :B1CAA2E8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:13:11.170842 :B1CAA2E9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:13:11.170843 :B1CAA2EA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:13:11.170843 :B1CAA2EB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:13:11.171332 :B1CAA2EC: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-07-14 08:13:11.171376 :B1CAA2F4: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: 7020, W000)