Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250913140358\orcl_w000_23560_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:34780M/63366M, Ph+PgF:39426M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 23560, image: ORACLE.EXE (W000) *** 2025-09-13 14:03:58.141 *** SESSION ID:(201.61223) 2025-09-13 14:03:58.141 *** 2025-09-13 14:03:58.141 Process diagnostic dump for ORACLE.EXE (W000), OS id=23560, pid: 25, proc_ser: 231, sid: 201, sess_ser: 61223 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=214 seq_num=215 snap_id=1 wait times: snap=2.963923 sec, exc=2.963923 sec, total=2.963923 sec wait times: max=5.000000 sec, heur=8 min 32 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=213 seq_num=214 snap_id=1 wait times: snap=5.015252 sec, exc=5.015252 sec, total=5.015252 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=212 seq_num=213 snap_id=1 wait times: snap=5.004524 sec, exc=5.004524 sec, total=5.004524 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=211 seq_num=212 snap_id=1 wait times: snap=5.010313 sec, exc=5.010313 sec, total=5.010313 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=210 seq_num=211 snap_id=1 wait times: snap=5.014758 sec, exc=5.014758 sec, total=5.014758 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=209 seq_num=210 snap_id=1 wait times: snap=5.010159 sec, exc=5.010159 sec, total=5.010159 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=208 seq_num=209 snap_id=1 wait times: snap=5.009215 sec, exc=5.009215 sec, total=5.009215 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=207 seq_num=208 snap_id=1 wait times: snap=5.015254 sec, exc=5.015254 sec, total=5.015254 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=206 seq_num=207 snap_id=1 wait times: snap=5.003209 sec, exc=5.003209 sec, total=5.003209 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=205 seq_num=206 snap_id=1 wait times: snap=5.001943 sec, exc=5.001943 sec, total=5.001943 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=204 seq_num=205 snap_id=1 wait times: snap=5.014529 sec, exc=5.014529 sec, total=5.014529 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 61223 --------------------------------------------------- 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, 14:01:58 - 14:03:58] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-13 14:03:58.141 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 23560, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-13 11:26:01.800762 :CCD56463:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=3 time=2148413112 2025-09-13 11:26:01.800762 :CCD56464:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 11:26:01.800762 :CCD56465:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 11:26:01.800763 :CCD56466:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 11:26:01.800763 :CCD56467:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 11:26:01.800764 :CCD56468:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 11:26:01.801326 :CCD56469:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 11:26:01.801386 :CCD5646F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 11:36:02.541146 :CCD62E52:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 11:36:02.921063 :CCD62E8C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 11:36:02.916 2025-09-13 11:36:02.921065 :CCD62E8D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23940 pso_num=25 pso_serial#=218 2025-09-13 11:36:02.921212 :CCD62E8E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23940 osp_idx=23 osp_ver=294501 osp_pg=0 (spawn #587728) 2025-09-13 11:36:02.921213 :CCD62E8F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 11:36:02.921213 :CCD62E90:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=4 time=2149014221 2025-09-13 11:36:02.921214 :CCD62E91:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 11:36:02.921214 :CCD62E92:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 11:36:02.921214 :CCD62E93:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 11:36:02.921215 :CCD62E94:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 11:36:02.921216 :CCD62E95:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 11:36:02.921734 :CCD62E96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 11:36:02.921785 :CCD62E9D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 11:46:03.740354 :CCD6F700:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 11:46:04.151426 :CCD6F741:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 11:46:04.147 2025-09-13 11:46:04.151429 :CCD6F742:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25140 pso_num=25 pso_serial#=219 2025-09-13 11:46:04.151564 :CCD6F743:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25140 osp_idx=23 osp_ver=294502 osp_pg=0 (spawn #587751) 2025-09-13 11:46:04.151565 :CCD6F744:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 11:46:04.151566 :CCD6F745:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=5 time=2149615455 2025-09-13 11:46:04.151566 :CCD6F746:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 11:46:04.151566 :CCD6F747:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 11:46:04.151567 :CCD6F748:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 11:46:04.151567 :CCD6F749:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 11:46:04.151567 :CCD6F74A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 11:46:04.152060 :CCD6F74B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 11:46:04.152103 :CCD6F753:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 11:50:30.343678 :CCD74F18:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-13 11:56:05.291106 :CCD7C0E4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-13 12:06:06.141005 :CCD891D4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 12:06:06.410966 :CCD8920D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 12:06:06.406 2025-09-13 12:06:06.410968 :CCD8920E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21212 pso_num=25 pso_serial#=220 2025-09-13 12:06:06.411111 :CCD8920F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21212 osp_idx=23 osp_ver=294503 osp_pg=0 (spawn #587798) 2025-09-13 12:06:06.411112 :CCD89210:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 12:06:06.411112 :CCD89211:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=6 time=2150817721 2025-09-13 12:06:06.411113 :CCD89212:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 12:06:06.411113 :CCD89213:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 12:06:06.411113 :CCD89214:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 12:06:06.411114 :CCD89215:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 12:06:06.411114 :CCD89216:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 12:06:06.411611 :CCD89217:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 12:06:06.411657 :CCD8921F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 12:16:07.199609 :CCD95D30:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 12:16:07.548187 :CCD95D63:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 12:16:07.543 2025-09-13 12:16:07.548189 :CCD95D64:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24332 pso_num=25 pso_serial#=221 2025-09-13 12:16:07.548369 :CCD95D65:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24332 osp_idx=23 osp_ver=294504 osp_pg=0 (spawn #587821) 2025-09-13 12:16:07.548370 :CCD95D66:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 12:16:07.548370 :CCD95D67:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=7 time=2151418846 2025-09-13 12:16:07.548370 :CCD95D68:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 12:16:07.548371 :CCD95D69:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 12:16:07.548371 :CCD95D6A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 12:16:07.548371 :CCD95D6B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 12:16:07.548371 :CCD95D6C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 12:16:07.548873 :CCD95D6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 12:16:07.548920 :CCD95D74:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 12:26:08.370624 :CCDA25C3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 12:26:08.672317 :CCDA25FE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 12:26:08.667 2025-09-13 12:26:08.672319 :CCDA25FF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18824 pso_num=25 pso_serial#=222 2025-09-13 12:26:08.672461 :CCDA2600:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18824 osp_idx=23 osp_ver=294505 osp_pg=0 (spawn #587844) 2025-09-13 12:26:08.672462 :CCDA2601:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 12:26:08.672462 :CCDA2602:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=8 time=2152019971 2025-09-13 12:26:08.672463 :CCDA2603:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 12:26:08.672463 :CCDA2604:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 12:26:08.672464 :CCDA2605:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 12:26:08.672465 :CCDA2606:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 12:26:08.672465 :CCDA2607:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 12:26:08.672983 :CCDA2608:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 12:26:08.673029 :CCDA2611:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 12:36:09.564072 :CCDAEFB4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 12:36:09.744536 :CCDAEFB7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 12:36:09.736 2025-09-13 12:36:09.744539 :CCDAEFB8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21864 pso_num=25 pso_serial#=223 2025-09-13 12:36:09.744680 :CCDAEFB9:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21864 (legacy spawn) 2025-09-13 12:36:09.751200 :CCDAEFBA: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-13 12:36:09.751381 :CCDAEFBF: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-13 12:36:09.752244 :CCDAEFC4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 12:36:09.912496 :CCDAEFF7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 12:36:09.907 2025-09-13 12:36:09.912498 :CCDAEFF8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24924 pso_num=25 pso_serial#=224 2025-09-13 12:36:09.912640 :CCDAEFF9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24924 osp_idx=23 osp_ver=294507 osp_pg=0 (spawn #587866) 2025-09-13 12:36:09.912641 :CCDAEFFA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 12:36:09.912642 :CCDAEFFB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=9 time=2152621221 2025-09-13 12:36:09.912642 :CCDAEFFC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 12:36:09.912642 :CCDAEFFD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 12:36:09.912643 :CCDAEFFE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 12:36:09.912643 :CCDAEFFF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 12:36:09.912643 :CCDAF000:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 12:36:09.913137 :CCDAF001:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 12:36:09.913183 :CCDAF007:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 12:46:10.800013 :CCDBB89E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 12:46:11.070722 :CCDBB8D9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 12:46:11.065 2025-09-13 12:46:11.070724 :CCDBB8DA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20960 pso_num=25 pso_serial#=225 2025-09-13 12:46:11.070871 :CCDBB8DB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20960 osp_idx=23 osp_ver=294508 osp_pg=0 (spawn #587889) 2025-09-13 12:46:11.070872 :CCDBB8DC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 12:46:11.070873 :CCDBB8DD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=10 time=2153222377 2025-09-13 12:46:11.070873 :CCDBB8DE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 12:46:11.070873 :CCDBB8DF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 12:46:11.070874 :CCDBB8E0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 12:46:11.070874 :CCDBB8E1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 12:46:11.070875 :CCDBB8E2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 12:46:11.071389 :CCDBB8E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 12:46:11.071439 :CCDBB8EB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 12:56:11.887859 :CCDC8175:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 12:56:12.189398 :CCDC81AD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 12:56:12.184 2025-09-13 12:56:12.189400 :CCDC81AE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20852 pso_num=25 pso_serial#=226 2025-09-13 12:56:12.189546 :CCDC81AF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20852 osp_idx=23 osp_ver=294509 osp_pg=0 (spawn #587912) 2025-09-13 12:56:12.189547 :CCDC81B0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 12:56:12.189547 :CCDC81B1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=11 time=2153823487 2025-09-13 12:56:12.189548 :CCDC81B2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 12:56:12.189548 :CCDC81B3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 12:56:12.189549 :CCDC81B4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 12:56:12.189549 :CCDC81B5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 12:56:12.189549 :CCDC81B6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 12:56:12.190049 :CCDC81B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 12:56:12.190093 :CCDC81BF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 13:06:13.001054 :CCDD543E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 13:06:13.443435 :CCDD547C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 13:06:13.439 2025-09-13 13:06:13.443437 :CCDD547D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24968 pso_num=25 pso_serial#=227 2025-09-13 13:06:13.443573 :CCDD547E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24968 osp_idx=23 osp_ver=294510 osp_pg=0 (spawn #587937) 2025-09-13 13:06:13.443573 :CCDD547F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 13:06:13.443574 :CCDD5480:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=12 time=2154424752 2025-09-13 13:06:13.443574 :CCDD5481:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 13:06:13.443575 :CCDD5482:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 13:06:13.443575 :CCDD5483:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 13:06:13.443575 :CCDD5484:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 13:06:13.443575 :CCDD5485:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 13:06:13.444147 :CCDD5486:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 13:06:13.444199 :CCDD548D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 13:16:14.260072 :CCDE1D40:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 13:16:14.530679 :CCDE1D75:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 13:16:14.526 2025-09-13 13:16:14.530682 :CCDE1D76:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25384 pso_num=25 pso_serial#=228 2025-09-13 13:16:14.530827 :CCDE1D77:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25384 osp_idx=23 osp_ver=294511 osp_pg=0 (spawn #587960) 2025-09-13 13:16:14.530828 :CCDE1D78:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 13:16:14.530828 :CCDE1D79:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=13 time=2155025830 2025-09-13 13:16:14.530829 :CCDE1D7A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 13:16:14.530829 :CCDE1D7B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 13:16:14.530829 :CCDE1D7C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 13:16:14.530830 :CCDE1D7D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 13:16:14.530830 :CCDE1D7E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 13:16:14.531368 :CCDE1D7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 13:16:14.531423 :CCDE1D85:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 13:26:15.344809 :CCDEE8E0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 13:26:15.693308 :CCDEE924:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 13:26:15.688 2025-09-13 13:26:15.693310 :CCDEE925:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24200 pso_num=25 pso_serial#=229 2025-09-13 13:26:15.693460 :CCDEE926:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24200 osp_idx=23 osp_ver=294512 osp_pg=0 (spawn #587983) 2025-09-13 13:26:15.693461 :CCDEE927:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 13:26:15.693462 :CCDEE928:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=14 time=2155627002 2025-09-13 13:26:15.693462 :CCDEE929:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 13:26:15.693463 :CCDEE92A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 13:26:15.693463 :CCDEE92B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 13:26:15.693464 :CCDEE92C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 13:26:15.693464 :CCDEE92D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 13:26:15.694014 :CCDEE92E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 13:26:15.694060 :CCDEE936:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 13:36:16.563474 :CCDFB277:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 13:36:17.005799 :CCDFB2CC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 13:36:17.000 2025-09-13 13:36:17.005801 :CCDFB2CD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25220 pso_num=25 pso_serial#=230 2025-09-13 13:36:17.005944 :CCDFB2CE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25220 osp_idx=23 osp_ver=294513 osp_pg=0 (spawn #588005) 2025-09-13 13:36:17.005945 :CCDFB2CF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 13:36:17.005945 :CCDFB2D0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=15 time=2156228315 2025-09-13 13:36:17.005945 :CCDFB2D1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 13:36:17.005946 :CCDFB2D2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 13:36:17.005946 :CCDFB2D3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 13:36:17.005946 :CCDFB2D4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 13:36:17.005947 :CCDFB2D5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 13:36:17.006448 :CCDFB2D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 13:36:17.006495 :CCDFB2E0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 13:46:17.873213 :CCE07C08:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 13:46:18.237352 :CCE07C66:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 13:46:18.232 2025-09-13 13:46:18.237354 :CCE07C67:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23560 pso_num=25 pso_serial#=231 2025-09-13 13:46:18.237496 :CCE07C68:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23560 osp_idx=23 osp_ver=294514 osp_pg=0 (spawn #588028) 2025-09-13 13:46:18.237497 :CCE07C69:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 13:46:18.237497 :CCE07C6A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=16 time=2156829549 2025-09-13 13:46:18.237498 :CCE07C6B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 13:46:18.237498 :CCE07C6C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 13:46:18.237499 :CCE07C6D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 13:46:18.237499 :CCE07C6E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 13:46:18.237499 :CCE07C6F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 13:46:18.238009 :CCE07C70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 13:46:18.238056 :CCE07C79:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 13:55:25.912187 :CCE13297:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-13 13:56:19.504151 :CCE15070: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 23560, W000)