Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250726222249\orcl_w000_11328_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:36035M/63366M, Ph+PgF:41818M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 11328, image: ORACLE.EXE (W000) *** 2025-07-26 22:22:49.365 *** SESSION ID:(209.5291) 2025-07-26 22:22:49.365 *** 2025-07-26 22:22:49.365 Process diagnostic dump for ORACLE.EXE (W000), OS id=11328, pid: 26, proc_ser: 187, sid: 209, sess_ser: 5291 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=130 seq_num=131 snap_id=1 wait times: snap=4.577411 sec, exc=4.577411 sec, total=4.577411 sec wait times: max=5.000000 sec, heur=1 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.000021 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=129 seq_num=130 snap_id=1 wait times: snap=5.008013 sec, exc=5.008013 sec, total=5.008013 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=128 seq_num=129 snap_id=1 wait times: snap=5.004640 sec, exc=5.004640 sec, total=5.004640 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=127 seq_num=128 snap_id=1 wait times: snap=5.004654 sec, exc=5.004654 sec, total=5.004654 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=126 seq_num=127 snap_id=1 wait times: snap=5.014719 sec, exc=5.014719 sec, total=5.014719 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=125 seq_num=126 snap_id=1 wait times: snap=5.010174 sec, exc=5.010174 sec, total=5.010174 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=124 seq_num=125 snap_id=1 wait times: snap=5.008437 sec, exc=5.008437 sec, total=5.008437 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=123 seq_num=124 snap_id=1 wait times: snap=5.000734 sec, exc=5.000734 sec, total=5.000734 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000431 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=122 seq_num=123 snap_id=1 wait times: snap=3.200667 sec, exc=3.200667 sec, total=3.200667 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=121 seq_num=122 snap_id=1 wait times: snap=5.004640 sec, exc=5.004640 sec, total=5.004640 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=120 seq_num=121 snap_id=1 wait times: snap=5.004648 sec, exc=5.004648 sec, total=5.004648 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 209 serial 5291 --------------------------------------------------- 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, 22:20:49 - 22:22:49] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-26 22:22:49.365 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 11328, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-26 19:41:51.465223 :B758D2DD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 19:51:52.210032 :B759978A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 19:51:52.646929 :B75997DA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 19:51:52.642 2025-07-26 19:51:52.646932 :B75997DB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17956 pso_num=26 pso_serial#=174 2025-07-26 19:51:52.647069 :B75997DC:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17956 osp_idx=24 osp_ver=688929 osp_pg=0 (spawn #425527) 2025-07-26 19:51:52.647070 :B75997DD:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 19:51:52.647070 :B75997DE:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=160 time=2240288548 2025-07-26 19:51:52.647071 :B75997DF:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 19:51:52.647071 :B75997E0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 19:51:52.647071 :B75997E1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 19:51:52.647072 :B75997E2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 19:51:52.647072 :B75997E3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 19:51:52.647571 :B75997E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 19:51:52.647617 :B75997EB:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 20:01:53.324047 :B75A608C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:01:53.688216 :B75A60E8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:01:53.683 2025-07-26 20:01:53.688218 :B75A60E9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=316 pso_num=26 pso_serial#=175 2025-07-26 20:01:53.688350 :B75A60EA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=316 osp_idx=24 osp_ver=688930 osp_pg=0 (spawn #425554) 2025-07-26 20:01:53.688351 :B75A60EB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:01:53.688351 :B75A60EC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=161 time=2240889579 2025-07-26 20:01:53.688352 :B75A60ED:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:01:53.688352 :B75A60EE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:01:53.688352 :B75A60EF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:01:53.688353 :B75A60F0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:01:53.688353 :B75A60F1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:01:53.688861 :B75A60F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:01:53.688915 :B75A60FC:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 20:11:54.389261 :B75B2BAA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:11:54.862776 :B75B2C2F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:11:54.858 2025-07-26 20:11:54.862778 :B75B2C30:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=1876 pso_num=26 pso_serial#=176 2025-07-26 20:11:54.862916 :B75B2C31:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=1876 osp_idx=24 osp_ver=688931 osp_pg=0 (spawn #425577) 2025-07-26 20:11:54.862917 :B75B2C32:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:11:54.862917 :B75B2C33:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=162 time=2241490751 2025-07-26 20:11:54.862918 :B75B2C34:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:11:54.862918 :B75B2C35:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:11:54.862918 :B75B2C36:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:11:54.862919 :B75B2C37:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:11:54.862919 :B75B2C38:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:11:54.863407 :B75B2C39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:11:54.863452 :B75B2C40:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 20:16:30.827644 :B75B84C1:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 20:21:56.114223 :B75BF038:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 20:31:56.808242 :B75CB3A6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:31:57.313900 :B75CB45B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:31:57.309 2025-07-26 20:31:57.313902 :B75CB45C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19276 pso_num=26 pso_serial#=177 2025-07-26 20:31:57.314041 :B75CB45D:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19276 osp_idx=24 osp_ver=688932 osp_pg=0 (spawn #425619) 2025-07-26 20:31:57.314041 :B75CB45E:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:31:57.314042 :B75CB45F:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=163 time=2242693204 2025-07-26 20:31:57.314042 :B75CB460:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:31:57.314043 :B75CB461:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:31:57.314043 :B75CB462:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:31:57.314043 :B75CB463:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:31:57.314044 :B75CB464:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:31:57.314538 :B75CB465:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:31:57.314584 :B75CB46C:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 20:41:58.127651 :B75D78E0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:41:58.548764 :B75D798A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:41:58.544 2025-07-26 20:41:58.548766 :B75D798B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16524 pso_num=26 pso_serial#=178 2025-07-26 20:41:58.548912 :B75D798C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16524 osp_idx=24 osp_ver=688933 osp_pg=0 (spawn #425642) 2025-07-26 20:41:58.548912 :B75D798D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:41:58.548913 :B75D798E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=164 time=2243294439 2025-07-26 20:41:58.548913 :B75D798F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:41:58.548914 :B75D7990:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:41:58.548914 :B75D7991:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:41:58.548914 :B75D7992:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:41:58.548915 :B75D7993:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:41:58.549418 :B75D7994:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:41:58.549458 :B75D799E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 20:51:59.367132 :B75E3E8D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:51:59.856413 :B75E3F38:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:51:59.851 2025-07-26 20:51:59.856415 :B75E3F39:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7996 pso_num=26 pso_serial#=179 2025-07-26 20:51:59.856588 :B75E3F3A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7996 osp_idx=24 osp_ver=688934 osp_pg=0 (spawn #425664) 2025-07-26 20:51:59.856589 :B75E3F3B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:51:59.856589 :B75E3F3C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=165 time=2243895751 2025-07-26 20:51:59.856590 :B75E3F3D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:51:59.856590 :B75E3F3E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:51:59.856590 :B75E3F3F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:51:59.856591 :B75E3F40:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:51:59.856591 :B75E3F41:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:51:59.857108 :B75E3F42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:51:59.857154 :B75E3F4A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 21:02:00.558710 :B75F045A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:02:00.923123 :B75F04E7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:02:00.918 2025-07-26 21:02:00.923125 :B75F04E8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19264 pso_num=26 pso_serial#=180 2025-07-26 21:02:00.923272 :B75F04E9:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19264 osp_idx=24 osp_ver=688935 osp_pg=0 (spawn #425690) 2025-07-26 21:02:00.923273 :B75F04EA:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:02:00.923274 :B75F04EB:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=166 time=2244496814 2025-07-26 21:02:00.923274 :B75F04EC:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:02:00.923275 :B75F04ED:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:02:00.923275 :B75F04EE:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:02:00.923276 :B75F04EF:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:02:00.923276 :B75F04F0:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:02:00.923768 :B75F04F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:02:00.923816 :B75F04F9:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 21:12:01.689861 :B75FCE89:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:12:01.960343 :B75FCEEA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:12:01.955 2025-07-26 21:12:01.960344 :B75FCEEB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17752 pso_num=26 pso_serial#=181 2025-07-26 21:12:01.960488 :B75FCEEC:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17752 osp_idx=24 osp_ver=688936 osp_pg=0 (spawn #425713) 2025-07-26 21:12:01.960489 :B75FCEED:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:12:01.960489 :B75FCEEE:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=167 time=2245097861 2025-07-26 21:12:01.960490 :B75FCEEF:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:12:01.960490 :B75FCEF0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:12:01.960491 :B75FCEF1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:12:01.960491 :B75FCEF2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:12:01.960491 :B75FCEF3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:12:01.960991 :B75FCEF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:12:01.961043 :B75FCEFE:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 21:22:02.727004 :B7609542:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:22:03.184865 :B76095A7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:22:03.180 2025-07-26 21:22:03.184867 :B76095A8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17080 pso_num=26 pso_serial#=182 2025-07-26 21:22:03.185007 :B76095A9:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17080 osp_idx=24 osp_ver=688937 osp_pg=0 (spawn #425735) 2025-07-26 21:22:03.185008 :B76095AA:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:22:03.185009 :B76095AB:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=168 time=2245699079 2025-07-26 21:22:03.185009 :B76095AC:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:22:03.185009 :B76095AD:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:22:03.185010 :B76095AE:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:22:03.185010 :B76095AF:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:22:03.185010 :B76095B0:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:22:03.185506 :B76095B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:22:03.185550 :B76095B8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 21:32:03.870065 :B76158E8:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:32:04.312329 :B761595E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:32:04.307 2025-07-26 21:32:04.312332 :B761595F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6512 pso_num=26 pso_serial#=183 2025-07-26 21:32:04.312478 :B7615960:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=6512 osp_idx=24 osp_ver=688938 osp_pg=0 (spawn #425756) 2025-07-26 21:32:04.312479 :B7615961:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:32:04.312479 :B7615962:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=169 time=2246300204 2025-07-26 21:32:04.312480 :B7615963:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:32:04.312480 :B7615964:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:32:04.312481 :B7615965:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:32:04.312481 :B7615966:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:32:04.312482 :B7615967:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:32:04.313029 :B7615968:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:32:04.313077 :B7615970:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 21:42:05.102292 :B7621D93:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:42:05.372776 :B7621DF8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:42:05.367 2025-07-26 21:42:05.372779 :B7621DF9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18136 pso_num=26 pso_serial#=184 2025-07-26 21:42:05.372917 :B7621DFA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18136 osp_idx=24 osp_ver=688939 osp_pg=0 (spawn #425779) 2025-07-26 21:42:05.372918 :B7621DFB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:42:05.372919 :B7621DFC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=170 time=2246901267 2025-07-26 21:42:05.372919 :B7621DFD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:42:05.372920 :B7621DFE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:42:05.372920 :B7621DFF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:42:05.372920 :B7621E00:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:42:05.372921 :B7621E01:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:42:05.373433 :B7621E02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:42:05.373476 :B7621E09:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 21:52:06.127235 :B762E10D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:52:06.507295 :B762E16B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:52:06.502 2025-07-26 21:52:06.507297 :B762E16C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19200 pso_num=26 pso_serial#=185 2025-07-26 21:52:06.507433 :B762E16D:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19200 osp_idx=24 osp_ver=688940 osp_pg=0 (spawn #425801) 2025-07-26 21:52:06.507434 :B762E16E:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:52:06.507435 :B762E16F:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=171 time=2247502408 2025-07-26 21:52:06.507435 :B762E170:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:52:06.507435 :B762E171:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:52:06.507436 :B762E172:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:52:06.507436 :B762E173:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:52:06.507436 :B762E174:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:52:06.507933 :B762E175:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:52:06.507986 :B762E17D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 22:02:07.361305 :B763A829:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 22:02:07.657402 :B763A889:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 22:02:07.652 2025-07-26 22:02:07.657403 :B763A88A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18924 pso_num=26 pso_serial#=186 2025-07-26 22:02:07.657540 :B763A88B:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18924 osp_idx=24 osp_ver=688941 osp_pg=0 (spawn #425827) 2025-07-26 22:02:07.657541 :B763A88C:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:02:07.657541 :B763A88D:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=172 time=2248103548 2025-07-26 22:02:07.657542 :B763A88E:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:02:07.657542 :B763A88F:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:02:07.657543 :B763A890:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:02:07.657543 :B763A891:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:02:07.657543 :B763A892:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:02:07.658035 :B763A893:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 22:02:07.658081 :B763A89A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 22:12:08.381581 :B7647C92:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 22:12:08.745821 :B7647CF3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 22:12:08.740 2025-07-26 22:12:08.745823 :B7647CF4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11328 pso_num=26 pso_serial#=187 2025-07-26 22:12:08.745964 :B7647CF5:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11328 osp_idx=24 osp_ver=688942 osp_pg=0 (spawn #425852) 2025-07-26 22:12:08.745965 :B7647CF6:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:12:08.745965 :B7647CF7:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=173 time=2248704642 2025-07-26 22:12:08.745966 :B7647CF8:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:12:08.745966 :B7647CF9:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:12:08.745966 :B7647CFA:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:12:08.745967 :B7647CFB:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:12:08.745967 :B7647CFC:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:12:08.746463 :B7647CFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 22:12:08.746512 :B7647D04:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 22:21:31.498132 :B76534B3:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 22:22:09.747749 :B76541D5:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 11328, W000)