Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250802060009\orcl_w000_15292_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:35827M/63366M, Ph+PgF:41482M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 15292, image: ORACLE.EXE (W000) *** 2025-08-02 06:00:10.092 *** SESSION ID:(201.36769) 2025-08-02 06:00:10.092 *** 2025-08-02 06:00:10.092 Process diagnostic dump for ORACLE.EXE (W000), OS id=15292, pid: 25, proc_ser: 104, sid: 201, sess_ser: 36769 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=2.439498 sec, exc=2.439498 sec, total=2.439498 sec wait times: max=5.000000 sec, heur=7.432322 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=1 seq_num=2 snap_id=1 wait times: snap=4.992803 sec, exc=4.992803 sec, total=4.992803 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.003860 sec of elapsed time 1: waited for 'class slave wait' slave id=0x1231ead0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000040 sec, exc=0.000040 sec, total=0.000040 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 36769 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 06:00:02 - 06:00:10] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-02 06:00:10.092 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 15292, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-02 03:39:46.761742 :BA28E405:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12112 pso_num=25 pso_serial#=90 2025-08-02 03:39:46.761879 :BA28E406:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12112 osp_idx=23 osp_ver=224347 osp_pg=0 (spawn #446588) 2025-08-02 03:39:46.761880 :BA28E407:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 03:39:46.761880 :BA28E408:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=34 time=2786656064 2025-08-02 03:39:46.761881 :BA28E409:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 03:39:46.761881 :BA28E40A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 03:39:46.761882 :BA28E40B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 03:39:46.761882 :BA28E40C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 03:39:46.761882 :BA28E40D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 03:39:46.762376 :BA28E40E: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-08-02 03:39:46.762421 :BA28E416: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-08-02 03:49:47.579277 :BA29AB59:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 03:49:47.927792 :BA29AB8F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 03:49:47.923 2025-08-02 03:49:47.927794 :BA29AB90:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19692 pso_num=25 pso_serial#=91 2025-08-02 03:49:47.927935 :BA29AB91:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19692 osp_idx=23 osp_ver=224348 osp_pg=0 (spawn #446611) 2025-08-02 03:49:47.927936 :BA29AB92:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 03:49:47.927936 :BA29AB93:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=35 time=2787257236 2025-08-02 03:49:47.927937 :BA29AB94:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 03:49:47.927937 :BA29AB95:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 03:49:47.927937 :BA29AB96:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 03:49:47.927938 :BA29AB97:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 03:49:47.927939 :BA29AB98:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 03:49:47.928437 :BA29AB99: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-08-02 03:49:47.928482 :BA29ABA0: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-08-02 03:59:48.850312 :BA2A72D5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 03:59:49.151989 :BA2A730B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 03:59:49.147 2025-08-02 03:59:49.151991 :BA2A730C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19072 pso_num=25 pso_serial#=92 2025-08-02 03:59:49.152128 :BA2A730D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19072 osp_idx=23 osp_ver=224349 osp_pg=0 (spawn #446634) 2025-08-02 03:59:49.152128 :BA2A730E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 03:59:49.152129 :BA2A730F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=36 time=2787858454 2025-08-02 03:59:49.152129 :BA2A7310:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 03:59:49.152129 :BA2A7311:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 03:59:49.152130 :BA2A7312:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 03:59:49.152130 :BA2A7313:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 03:59:49.152130 :BA2A7314:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 03:59:49.152676 :BA2A7315: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-08-02 03:59:49.152719 :BA2A731D: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-08-02 04:09:49.937801 :BA2B419B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 04:09:49.977077 :BA2B419E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 04:09:49.968 2025-08-02 04:09:49.977079 :BA2B419F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17880 pso_num=25 pso_serial#=93 2025-08-02 04:09:49.977210 :BA2B41A0:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17880 (legacy spawn) 2025-08-02 04:09:49.983658 :BA2B41A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 04:09:49.983834 :BA2B41AA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 04:09:49.984647 :BA2B41AB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 04:09:50.317625 :BA2B41EC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 04:09:50.312 2025-08-02 04:09:50.317628 :BA2B41ED:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13028 pso_num=25 pso_serial#=94 2025-08-02 04:09:50.317772 :BA2B41EE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13028 osp_idx=23 osp_ver=224351 osp_pg=0 (spawn #446659) 2025-08-02 04:09:50.317772 :BA2B41EF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 04:09:50.317773 :BA2B41F0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=37 time=2788459626 2025-08-02 04:09:50.317773 :BA2B41F1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 04:09:50.317773 :BA2B41F2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 04:09:50.317774 :BA2B41F3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 04:09:50.317774 :BA2B41F4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 04:09:50.317774 :BA2B41F5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 04:09:50.318271 :BA2B41F6: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-08-02 04:09:50.318315 :BA2B41FE: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-08-02 04:19:51.270315 :BA2C0AC2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 04:19:51.556190 :BA2C0AF9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 04:19:51.551 2025-08-02 04:19:51.556192 :BA2C0AFA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20000 pso_num=25 pso_serial#=95 2025-08-02 04:19:51.556322 :BA2C0AFB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20000 osp_idx=23 osp_ver=224352 osp_pg=0 (spawn #446682) 2025-08-02 04:19:51.556323 :BA2C0AFC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 04:19:51.556323 :BA2C0AFD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=38 time=2789060861 2025-08-02 04:19:51.556324 :BA2C0AFE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 04:19:51.556324 :BA2C0AFF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 04:19:51.556324 :BA2C0B00:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 04:19:51.556325 :BA2C0B01:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 04:19:51.556325 :BA2C0B02:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 04:19:51.556867 :BA2C0B03: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-08-02 04:19:51.556920 :BA2C0B0A: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-08-02 04:29:52.285518 :BA2CD0FE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 04:29:52.635918 :BA2CD144:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 04:29:52.631 2025-08-02 04:29:52.635920 :BA2CD145:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20340 pso_num=25 pso_serial#=96 2025-08-02 04:29:52.636057 :BA2CD146:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20340 osp_idx=23 osp_ver=224353 osp_pg=0 (spawn #446705) 2025-08-02 04:29:52.636058 :BA2CD147:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 04:29:52.636059 :BA2CD148:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=39 time=2789661939 2025-08-02 04:29:52.636059 :BA2CD149:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 04:29:52.636059 :BA2CD14A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 04:29:52.636060 :BA2CD14B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 04:29:52.636060 :BA2CD14C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 04:29:52.636060 :BA2CD14D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 04:29:52.636548 :BA2CD14E: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-08-02 04:29:52.636591 :BA2CD157: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-08-02 04:39:53.425611 :BA2D9890:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 04:39:53.727248 :BA2D98CB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 04:39:53.723 2025-08-02 04:39:53.727249 :BA2D98CC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19376 pso_num=25 pso_serial#=97 2025-08-02 04:39:53.727388 :BA2D98CD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19376 osp_idx=23 osp_ver=224354 osp_pg=0 (spawn #446727) 2025-08-02 04:39:53.727389 :BA2D98CE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 04:39:53.727389 :BA2D98CF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=40 time=2790263033 2025-08-02 04:39:53.727390 :BA2D98D0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 04:39:53.727390 :BA2D98D1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 04:39:53.727390 :BA2D98D2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 04:39:53.727391 :BA2D98D3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 04:39:53.727391 :BA2D98D4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 04:39:53.727911 :BA2D98D5: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-08-02 04:39:53.727966 :BA2D98DE: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-08-02 04:49:54.499271 :BA2E5FF5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 04:49:54.863359 :BA2E603C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 04:49:54.859 2025-08-02 04:49:54.863361 :BA2E603D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19116 pso_num=25 pso_serial#=98 2025-08-02 04:49:54.863503 :BA2E603E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19116 osp_idx=23 osp_ver=224355 osp_pg=0 (spawn #446750) 2025-08-02 04:49:54.863503 :BA2E603F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 04:49:54.863504 :BA2E6040:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=41 time=2790864173 2025-08-02 04:49:54.863504 :BA2E6041:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 04:49:54.863504 :BA2E6042:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 04:49:54.863505 :BA2E6043:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 04:49:54.863505 :BA2E6044:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 04:49:54.863505 :BA2E6045:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 04:49:54.863995 :BA2E6046: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-08-02 04:49:54.864041 :BA2E604D: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-08-02 04:59:55.732197 :BA2F2693:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 04:59:55.986940 :BA2F26CF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 04:59:55.982 2025-08-02 04:59:55.986942 :BA2F26D0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19936 pso_num=25 pso_serial#=99 2025-08-02 04:59:55.987079 :BA2F26D1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19936 osp_idx=23 osp_ver=224356 osp_pg=0 (spawn #446773) 2025-08-02 04:59:55.987080 :BA2F26D2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 04:59:55.987080 :BA2F26D3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=42 time=2791465298 2025-08-02 04:59:55.987081 :BA2F26D4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 04:59:55.987081 :BA2F26D5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 04:59:55.987081 :BA2F26D6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 04:59:55.987082 :BA2F26D7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 04:59:55.987082 :BA2F26D8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 04:59:55.987593 :BA2F26D9: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-08-02 04:59:55.987638 :BA2F26E3: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-08-02 05:09:56.878374 :BA2FF589:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 05:09:57.055362 :BA2FF5C2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 05:09:57.050 2025-08-02 05:09:57.055364 :BA2FF5C3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15732 pso_num=25 pso_serial#=100 2025-08-02 05:09:57.055527 :BA2FF5C4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15732 osp_idx=23 osp_ver=224357 osp_pg=0 (spawn #446798) 2025-08-02 05:09:57.055528 :BA2FF5C5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:09:57.055528 :BA2FF5C6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=43 time=2792066361 2025-08-02 05:09:57.055529 :BA2FF5C7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:09:57.055529 :BA2FF5C8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:09:57.055530 :BA2FF5C9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:09:57.055530 :BA2FF5CA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:09:57.055530 :BA2FF5CB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:09:57.056058 :BA2FF5CC: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-08-02 05:09:57.056104 :BA2FF5D4: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-08-02 05:15:17.340991 :BA30628B: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-08-02 05:19:58.114246 :BA30C00C: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-08-02 05:29:58.896646 :BA3188DE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 05:29:59.167062 :BA31892C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 05:29:59.162 2025-08-02 05:29:59.167064 :BA31892D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19984 pso_num=25 pso_serial#=101 2025-08-02 05:29:59.167206 :BA31892E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19984 osp_idx=23 osp_ver=224358 osp_pg=0 (spawn #446843) 2025-08-02 05:29:59.167206 :BA31892F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:29:59.167207 :BA318930:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=44 time=2793268470 2025-08-02 05:29:59.167207 :BA318931:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:29:59.167208 :BA318932:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:29:59.167208 :BA318933:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:29:59.167208 :BA318934:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:29:59.167209 :BA318935:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:29:59.167710 :BA318936: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-08-02 05:29:59.167755 :BA31893D: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-08-02 05:39:59.971148 :BA3251F0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 05:40:00.273017 :BA325241:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 05:40:00.267 2025-08-02 05:40:00.273019 :BA325242:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7320 pso_num=25 pso_serial#=102 2025-08-02 05:40:00.273167 :BA325243:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7320 osp_idx=23 osp_ver=224359 osp_pg=0 (spawn #446865) 2025-08-02 05:40:00.273168 :BA325244:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:40:00.273168 :BA325245:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=45 time=2793869579 2025-08-02 05:40:00.273169 :BA325246:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:40:00.273169 :BA325247:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:40:00.273170 :BA325248:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:40:00.273170 :BA325249:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:40:00.273170 :BA32524A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:40:00.273722 :BA32524B: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-08-02 05:40:00.273768 :BA325253: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-08-02 05:50:01.081588 :BA331BE1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 05:50:01.399081 :BA331C30:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 05:50:01.393 2025-08-02 05:50:01.399083 :BA331C31:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15264 pso_num=25 pso_serial#=103 2025-08-02 05:50:01.399221 :BA331C32:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15264 osp_idx=23 osp_ver=224360 osp_pg=0 (spawn #446888) 2025-08-02 05:50:01.399222 :BA331C33:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:50:01.399222 :BA331C34:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=46 time=2794470704 2025-08-02 05:50:01.399223 :BA331C35:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:50:01.399223 :BA331C36:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:50:01.399224 :BA331C37:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:50:01.399224 :BA331C38:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:50:01.399224 :BA331C39:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:50:01.399730 :BA331C3A: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-08-02 05:50:01.399776 :BA331C42: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-08-02 06:00:02.219720 :BA33E972:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 06:00:02.661815 :BA33E9EC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 06:00:02.657 2025-08-02 06:00:02.661817 :BA33E9ED:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15292 pso_num=25 pso_serial#=104 2025-08-02 06:00:02.661951 :BA33E9EE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15292 osp_idx=23 osp_ver=224361 osp_pg=0 (spawn #446917) 2025-08-02 06:00:02.661951 :BA33E9EF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 06:00:02.661952 :BA33E9F0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=47 time=2795071970 2025-08-02 06:00:02.661952 :BA33E9F1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 06:00:02.661952 :BA33E9F2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 06:00:02.661953 :BA33E9F3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 06:00:02.661953 :BA33E9F4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 06:00:02.661953 :BA33E9F5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 06:00:02.662458 :BA33E9F6: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-08-02 06:00:02.662503 :BA33E9FE: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: 15292, W000)