Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250906100320\orcl_w000_22836_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:35014M/63366M, Ph+PgF:39878M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 22836, image: ORACLE.EXE (W000) *** 2025-09-06 10:03:20.885 *** SESSION ID:(201.34883) 2025-09-06 10:03:20.885 *** 2025-09-06 10:03:20.885 Process diagnostic dump for ORACLE.EXE (W000), OS id=22836, pid: 25, proc_ser: 207, sid: 201, sess_ser: 34883 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=91 seq_num=92 snap_id=1 wait times: snap=4.220742 sec, exc=4.220742 sec, total=4.220742 sec wait times: max=5.000000 sec, heur=7 min 34 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.000035 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=90 seq_num=91 snap_id=1 wait times: snap=5.004569 sec, exc=5.004569 sec, total=5.004569 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000059 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=89 seq_num=90 snap_id=1 wait times: snap=5.004655 sec, exc=5.004655 sec, total=5.004655 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=88 seq_num=89 snap_id=1 wait times: snap=5.007251 sec, exc=5.007251 sec, total=5.007251 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=87 seq_num=88 snap_id=1 wait times: snap=5.001444 sec, exc=5.001444 sec, total=5.001444 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=86 seq_num=87 snap_id=1 wait times: snap=5.000798 sec, exc=5.000798 sec, total=5.000798 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=85 seq_num=86 snap_id=1 wait times: snap=5.012394 sec, exc=5.012394 sec, total=5.012394 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.004637 sec, exc=5.004637 sec, total=5.004637 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=83 seq_num=84 snap_id=1 wait times: snap=5.004619 sec, exc=5.004619 sec, total=5.004619 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=82 seq_num=83 snap_id=1 wait times: snap=5.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=81 seq_num=82 snap_id=1 wait times: snap=5.011814 sec, exc=5.011814 sec, total=5.011814 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time Sampled Session History of session 201 serial 34883 --------------------------------------------------- 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, 10:01:20 - 10:03:20] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-06 10:03:20.885 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 22836, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-06 07:35:29.815977 :C9ABD736:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 07:35:29.815977 :C9ABD737:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 07:35:29.816512 :C9ABD738:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 07:35:29.816562 :C9ABD73F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 07:45:30.596436 :C9AC9D99:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 07:45:30.718391 :C9AC9D9C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 07:45:30.706 2025-09-06 07:45:30.718393 :C9AC9D9D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23544 pso_num=25 pso_serial#=193 2025-09-06 07:45:30.718534 :C9AC9D9E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23544 (legacy spawn) 2025-09-06 07:45:30.725067 :C9AC9D9F: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-09-06 07:45:30.725254 :C9AC9DA4: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-09-06 07:45:30.726057 :C9AC9DA9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 07:45:30.734851 :C9AC9DAC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 07:45:30.721 2025-09-06 07:45:30.734853 :C9AC9DAD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24476 pso_num=25 pso_serial#=194 2025-09-06 07:45:30.734995 :C9AC9DAE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24476 (legacy spawn) 2025-09-06 07:45:30.741562 :C9AC9DAF: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-09-06 07:45:30.741729 :C9AC9DB8: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-09-06 07:45:30.742524 :C9AC9DB9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 07:45:30.976116 :C9AC9DF1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 07:45:30.971 2025-09-06 07:45:30.976118 :C9AC9DF2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4160 pso_num=25 pso_serial#=195 2025-09-06 07:45:30.976252 :C9AC9DF3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4160 osp_idx=23 osp_ver=282493 osp_pg=0 (spawn #563848) 2025-09-06 07:45:30.976252 :C9AC9DF4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 07:45:30.976253 :C9AC9DF5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=31 time=1530382283 2025-09-06 07:45:30.976253 :C9AC9DF6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 07:45:30.976253 :C9AC9DF7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 07:45:30.976254 :C9AC9DF8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 07:45:30.976254 :C9AC9DF9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 07:45:30.976254 :C9AC9DFA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 07:45:30.976732 :C9AC9DFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 07:45:30.976787 :C9AC9E05:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 07:55:31.845074 :C9AD62A6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 07:55:32.099809 :C9AD62E8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 07:55:32.094 2025-09-06 07:55:32.099811 :C9AD62E9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24420 pso_num=25 pso_serial#=196 2025-09-06 07:55:32.099953 :C9AD62EA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24420 osp_idx=23 osp_ver=282494 osp_pg=0 (spawn #563871) 2025-09-06 07:55:32.099953 :C9AD62EB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 07:55:32.099954 :C9AD62EC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=32 time=1530983408 2025-09-06 07:55:32.099954 :C9AD62ED:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 07:55:32.099954 :C9AD62EE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 07:55:32.099955 :C9AD62EF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 07:55:32.099955 :C9AD62F0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 07:55:32.099955 :C9AD62F1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 07:55:32.100449 :C9AD62F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 07:55:32.100495 :C9AD62F9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 08:05:32.946305 :C9AE2B3A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 08:05:33.232345 :C9AE2B6E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 08:05:33.227 2025-09-06 08:05:33.232347 :C9AE2B6F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19908 pso_num=25 pso_serial#=197 2025-09-06 08:05:33.232485 :C9AE2B70:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19908 osp_idx=23 osp_ver=282495 osp_pg=0 (spawn #563897) 2025-09-06 08:05:33.232485 :C9AE2B71:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 08:05:33.232486 :C9AE2B72:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=33 time=1531584533 2025-09-06 08:05:33.232486 :C9AE2B73:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 08:05:33.232487 :C9AE2B74:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 08:05:33.232487 :C9AE2B75:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 08:05:33.232487 :C9AE2B76:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 08:05:33.232488 :C9AE2B77:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 08:05:33.232994 :C9AE2B78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 08:05:33.233037 :C9AE2B7F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 08:15:33.997733 :C9AEF554:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 08:15:34.299426 :C9AEF58F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 08:15:34.295 2025-09-06 08:15:34.299428 :C9AEF590:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20988 pso_num=25 pso_serial#=198 2025-09-06 08:15:34.299563 :C9AEF591:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20988 osp_idx=23 osp_ver=282496 osp_pg=0 (spawn #563919) 2025-09-06 08:15:34.299563 :C9AEF592:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 08:15:34.299564 :C9AEF593:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=34 time=1532185612 2025-09-06 08:15:34.299564 :C9AEF594:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 08:15:34.299565 :C9AEF595:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 08:15:34.299565 :C9AEF596:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 08:15:34.299565 :C9AEF597:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 08:15:34.299566 :C9AEF598:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 08:15:34.300062 :C9AEF599:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 08:15:34.300107 :C9AEF5A1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 08:25:35.139821 :C9AFBC10:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 08:25:35.473034 :C9AFBC42:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 08:25:35.467 2025-09-06 08:25:35.473036 :C9AFBC43:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23116 pso_num=25 pso_serial#=199 2025-09-06 08:25:35.473186 :C9AFBC44:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23116 osp_idx=23 osp_ver=282497 osp_pg=0 (spawn #563942) 2025-09-06 08:25:35.473186 :C9AFBC45:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 08:25:35.473187 :C9AFBC46:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=35 time=1532786783 2025-09-06 08:25:35.473187 :C9AFBC47:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 08:25:35.473188 :C9AFBC48:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 08:25:35.473188 :C9AFBC49:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 08:25:35.473188 :C9AFBC4A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 08:25:35.473189 :C9AFBC4B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 08:25:35.473718 :C9AFBC4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 08:25:35.473766 :C9AFBC52:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 08:35:36.140773 :C9B07FD2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 08:35:36.593174 :C9B08014:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 08:35:36.588 2025-09-06 08:35:36.593177 :C9B08015:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21040 pso_num=25 pso_serial#=200 2025-09-06 08:35:36.593313 :C9B08016:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21040 osp_idx=23 osp_ver=282498 osp_pg=0 (spawn #563965) 2025-09-06 08:35:36.593314 :C9B08017:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 08:35:36.593314 :C9B08018:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=36 time=1533387893 2025-09-06 08:35:36.593315 :C9B08019:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 08:35:36.593315 :C9B0801A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 08:35:36.593315 :C9B0801B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 08:35:36.593316 :C9B0801C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 08:35:36.593316 :C9B0801D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 08:35:36.593829 :C9B0801E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 08:35:36.593877 :C9B08026:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 08:45:37.521724 :C9B1433C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 08:45:37.776959 :C9B14379:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 08:45:37.772 2025-09-06 08:45:37.776962 :C9B1437A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23272 pso_num=25 pso_serial#=201 2025-09-06 08:45:37.777108 :C9B1437B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23272 osp_idx=23 osp_ver=282499 osp_pg=0 (spawn #563987) 2025-09-06 08:45:37.777109 :C9B1437C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 08:45:37.777109 :C9B1437D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=37 time=1533989080 2025-09-06 08:45:37.777110 :C9B1437E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 08:45:37.777110 :C9B1437F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 08:45:37.777110 :C9B14380:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 08:45:37.777111 :C9B14381:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 08:45:37.777111 :C9B14382:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 08:45:37.777657 :C9B14383:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 08:45:37.777707 :C9B1438B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 08:55:38.609633 :C9B2065F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 08:55:38.952598 :C9B20697:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 08:55:38.948 2025-09-06 08:55:38.952600 :C9B20698:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21280 pso_num=25 pso_serial#=202 2025-09-06 08:55:38.952745 :C9B20699:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21280 osp_idx=23 osp_ver=282500 osp_pg=0 (spawn #564012) 2025-09-06 08:55:38.952746 :C9B2069A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 08:55:38.952747 :C9B2069B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=38 time=1534590252 2025-09-06 08:55:38.952747 :C9B2069C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 08:55:38.952748 :C9B2069D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 08:55:38.952748 :C9B2069E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 08:55:38.952748 :C9B2069F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 08:55:38.952749 :C9B206A0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 08:55:38.953252 :C9B206A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 08:55:38.953297 :C9B206A9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 09:05:39.701950 :C9B2D3DC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 09:05:40.066141 :C9B2D414:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 09:05:40.061 2025-09-06 09:05:40.066143 :C9B2D415:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24388 pso_num=25 pso_serial#=203 2025-09-06 09:05:40.066288 :C9B2D416:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24388 osp_idx=23 osp_ver=282501 osp_pg=0 (spawn #564038) 2025-09-06 09:05:40.066289 :C9B2D417:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 09:05:40.066289 :C9B2D418:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=39 time=1535191377 2025-09-06 09:05:40.066290 :C9B2D419:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 09:05:40.066290 :C9B2D41A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 09:05:40.066290 :C9B2D41B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 09:05:40.066291 :C9B2D41C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 09:05:40.066291 :C9B2D41D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 09:05:40.066798 :C9B2D41E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 09:05:40.066845 :C9B2D426:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 09:15:40.804722 :C9B39556:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 09:15:41.215750 :C9B39590:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 09:15:41.211 2025-09-06 09:15:41.215752 :C9B39591:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20372 pso_num=25 pso_serial#=204 2025-09-06 09:15:41.215888 :C9B39592:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20372 osp_idx=23 osp_ver=282502 osp_pg=0 (spawn #564060) 2025-09-06 09:15:41.215889 :C9B39593:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 09:15:41.215889 :C9B39594:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=40 time=1535792518 2025-09-06 09:15:41.215890 :C9B39595:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 09:15:41.215890 :C9B39596:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 09:15:41.215890 :C9B39597:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 09:15:41.215890 :C9B39598:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 09:15:41.215891 :C9B39599:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 09:15:41.216379 :C9B3959A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 09:15:41.216423 :C9B395A0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 09:23:49.450987 :C9B43564:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 09:25:42.479089 :C9B45A04:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 09:35:43.199556 :C9B51CAF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 09:35:43.657251 :C9B51CF1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 09:35:43.653 2025-09-06 09:35:43.657254 :C9B51CF2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24516 pso_num=25 pso_serial#=205 2025-09-06 09:35:43.657391 :C9B51CF3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24516 osp_idx=23 osp_ver=282503 osp_pg=0 (spawn #564105) 2025-09-06 09:35:43.657391 :C9B51CF4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 09:35:43.657392 :C9B51CF5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=41 time=1536994955 2025-09-06 09:35:43.657392 :C9B51CF6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 09:35:43.657393 :C9B51CF7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 09:35:43.657393 :C9B51CF8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 09:35:43.657393 :C9B51CF9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 09:35:43.657394 :C9B51CFA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 09:35:43.657878 :C9B51CFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 09:35:43.657932 :C9B51D02:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 09:45:44.535593 :C9B5E03D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 09:45:44.878896 :C9B5E076:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 09:45:44.874 2025-09-06 09:45:44.878898 :C9B5E077:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23752 pso_num=25 pso_serial#=206 2025-09-06 09:45:44.879036 :C9B5E078:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23752 osp_idx=23 osp_ver=282504 osp_pg=0 (spawn #564127) 2025-09-06 09:45:44.879037 :C9B5E079:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 09:45:44.879038 :C9B5E07A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=42 time=1537596190 2025-09-06 09:45:44.879038 :C9B5E07B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 09:45:44.879038 :C9B5E07C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 09:45:44.879039 :C9B5E07D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 09:45:44.879039 :C9B5E07E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 09:45:44.879039 :C9B5E07F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 09:45:44.879549 :C9B5E080:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 09:45:44.879603 :C9B5E08A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 09:55:45.619825 :C9B6A2F8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 09:55:46.141188 :C9B6A33A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 09:55:46.135 2025-09-06 09:55:46.141190 :C9B6A33B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22836 pso_num=25 pso_serial#=207 2025-09-06 09:55:46.141366 :C9B6A33C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22836 osp_idx=23 osp_ver=282505 osp_pg=0 (spawn #564150) 2025-09-06 09:55:46.141367 :C9B6A33D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 09:55:46.141367 :C9B6A33E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=43 time=1538197440 2025-09-06 09:55:46.141368 :C9B6A33F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 09:55:46.141368 :C9B6A340:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 09:55:46.141369 :C9B6A341:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 09:55:46.141369 :C9B6A342:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 09:55:46.141369 :C9B6A343:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 09:55:46.142021 :C9B6A344:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 09:55:46.142090 :C9B6A34A: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: 22836, W000)