Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250430221731\orcl_w000_9208_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:37663M/63366M, Ph+PgF:45246M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 9208, image: ORACLE.EXE (W000) *** 2025-04-30 22:17:31.787 *** SESSION ID:(201.27783) 2025-04-30 22:17:31.787 *** 2025-04-30 22:17:31.787 Process diagnostic dump for ORACLE.EXE (W000), OS id=9208, pid: 25, proc_ser: 205, sid: 201, sess_ser: 27783 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=2.606734 sec, exc=2.606734 sec, total=2.606734 sec wait times: max=5.000000 sec, heur=3 min 52 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.000027 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=5.000319 sec, exc=5.000319 sec, total=5.000319 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=5.000311 sec, exc=5.000311 sec, total=5.000311 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=5.006350 sec, exc=5.006350 sec, total=5.006350 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=43 seq_num=44 snap_id=1 wait times: snap=5.009183 sec, exc=5.009183 sec, total=5.009183 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=5.010169 sec, exc=5.010169 sec, total=5.010169 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=41 seq_num=42 snap_id=1 wait times: snap=5.014720 sec, exc=5.014720 sec, total=5.014720 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=40 seq_num=41 snap_id=1 wait times: snap=5.004617 sec, exc=5.004617 sec, total=5.004617 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=39 seq_num=40 snap_id=1 wait times: snap=5.008295 sec, exc=5.008295 sec, total=5.008295 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=38 seq_num=39 snap_id=1 wait times: snap=5.007900 sec, exc=5.007900 sec, total=5.007900 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=37 seq_num=38 snap_id=1 wait times: snap=5.007091 sec, exc=5.007091 sec, total=5.007091 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 27783 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 22:15:31 - 22:17:31] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-30 22:17:31.787 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 9208, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-30 22:09:02.529416 :90F7DAAE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2752 pso_num=25 pso_serial#=181 2025-04-30 22:09:02.529559 :90F7DAAF:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=2752 (legacy spawn) 2025-04-30 22:09:02.536038 :90F7DAB0: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-04-30 22:09:02.536207 :90F7DAB8: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-04-30 22:09:02.537031 :90F7DAC6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:09:39.106965 :90F7E646:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:09:39.092 2025-04-30 22:09:39.106967 :90F7E647:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9904 pso_num=25 pso_serial#=182 2025-04-30 22:09:39.107103 :90F7E648:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9904 (legacy spawn) 2025-04-30 22:09:39.113801 :90F7E649: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-04-30 22:09:39.114014 :90F7E64E: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-04-30 22:09:39.114922 :90F7E653:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:09:39.123980 :90F7E656:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:09:39.123 2025-04-30 22:09:39.123982 :90F7E657:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11500 pso_num=25 pso_serial#=183 2025-04-30 22:09:39.124128 :90F7E658:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11500 (legacy spawn) 2025-04-30 22:09:39.130943 :90F7E659: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-04-30 22:09:39.131117 :90F7E662: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-04-30 22:09:39.132002 :90F7E663:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:09:40.154545 :90F7E6E1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:09:40.149 2025-04-30 22:09:40.154547 :90F7E6E2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12176 pso_num=25 pso_serial#=184 2025-04-30 22:09:40.154678 :90F7E6E3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12176 osp_idx=23 osp_ver=71073 osp_pg=0 (spawn #135189) 2025-04-30 22:09:40.154679 :90F7E6E4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:09:40.154679 :90F7E6E5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=212 time=3321769640 2025-04-30 22:09:40.154680 :90F7E6E6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:09:40.154680 :90F7E6E7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:09:40.154680 :90F7E6E8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:09:40.154681 :90F7E6E9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:09:40.154681 :90F7E6EA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:09:40.155165 :90F7E6EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-30 22:09:40.155209 :90F7E6F1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-30 22:09:40.159370 :90F7E712:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-30 22:09:40.159412 :90F7E713:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-30 22:09:40.159744 :90F7E714:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-30 22:09:40.159764 :90F7E715:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-30 22:09:40.159984 :90F7E716:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-30 22:09:40.159997 :90F7E717:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-30 22:09:40.160209 :90F7E718:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-30 22:09:40.160222 :90F7E719:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-30 22:09:40.160426 :90F7E71A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-30 22:09:40.160440 :90F7E71B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-30 22:09:40.160648 :90F7E71C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-30 22:09:40.160661 :90F7E71D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-30 22:09:40.163037 :90F7E71E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:09:42.334311 :90F7E79F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:09:42.321 2025-04-30 22:09:42.334313 :90F7E7A0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11944 pso_num=25 pso_serial#=185 2025-04-30 22:09:42.334455 :90F7E7A1:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11944 (legacy spawn) 2025-04-30 22:09:42.341005 :90F7E7A2: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-04-30 22:09:42.341170 :90F7E7A7: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-04-30 22:09:42.341970 :90F7E7AC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:09:42.350640 :90F7E7AF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:09:42.337 2025-04-30 22:09:42.350642 :90F7E7B0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9384 pso_num=25 pso_serial#=186 2025-04-30 22:09:42.350785 :90F7E7B1:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9384 (legacy spawn) 2025-04-30 22:09:42.357260 :90F7E7B2: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-04-30 22:09:42.357425 :90F7E7BB: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-04-30 22:09:42.358280 :90F7E7BC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:09:52.766626 :90F7EB33:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:09:52.757 2025-04-30 22:09:52.766628 :90F7EB34:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8672 pso_num=25 pso_serial#=187 2025-04-30 22:09:52.766760 :90F7EB35:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8672 (legacy spawn) 2025-04-30 22:09:52.773239 :90F7EB36: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-04-30 22:09:52.773407 :90F7EB3F: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-04-30 22:09:52.774211 :90F7EB40:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:10:03.761599 :90F7EE64:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:10:03.753 2025-04-30 22:10:03.761601 :90F7EE65:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10148 pso_num=25 pso_serial#=188 2025-04-30 22:10:03.761746 :90F7EE66:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10148 (legacy spawn) 2025-04-30 22:10:03.768254 :90F7EE67: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-04-30 22:10:03.768428 :90F7EE6C: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-04-30 22:10:03.769232 :90F7EE71:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:10:34.867419 :90F7F940:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:10:34.859 2025-04-30 22:10:34.867421 :90F7F941:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10588 pso_num=25 pso_serial#=189 2025-04-30 22:10:34.867558 :90F7F942:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10588 (legacy spawn) 2025-04-30 22:10:34.874045 :90F7F943: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-04-30 22:10:34.874232 :90F7F94B: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-04-30 22:10:34.875067 :90F7F94D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:10:51.821252 :90F7FE86:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:10:51.807 2025-04-30 22:10:51.821254 :90F7FE87:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7588 pso_num=25 pso_serial#=190 2025-04-30 22:10:51.821385 :90F7FE88:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=7588 (legacy spawn) 2025-04-30 22:10:51.827809 :90F7FE89: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-04-30 22:10:51.827988 :90F7FE8E: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-04-30 22:10:51.828775 :90F7FE93:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:10:51.837244 :90F7FE96:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:10:51.822 2025-04-30 22:10:51.837246 :90F7FE97:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3224 pso_num=25 pso_serial#=191 2025-04-30 22:10:51.837375 :90F7FE98:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=3224 (legacy spawn) 2025-04-30 22:10:51.843775 :90F7FE99: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-04-30 22:10:51.843937 :90F7FEA2: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-04-30 22:10:51.844710 :90F7FEA3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:10:55.009173 :90F7FFA6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:10:54.999 2025-04-30 22:10:55.009175 :90F7FFA7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11204 pso_num=25 pso_serial#=192 2025-04-30 22:10:55.009306 :90F7FFA8:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11204 (legacy spawn) 2025-04-30 22:10:55.015799 :90F7FFA9: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-04-30 22:10:55.015969 :90F7FFAE: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-04-30 22:10:55.016798 :90F7FFB3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:10:55.025342 :90F7FFB6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:10:55.015 2025-04-30 22:10:55.025344 :90F7FFB7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11540 pso_num=25 pso_serial#=193 2025-04-30 22:10:55.025481 :90F7FFB8:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11540 (legacy spawn) 2025-04-30 22:10:55.031981 :90F7FFB9: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-04-30 22:10:55.032165 :90F7FFC2: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-04-30 22:10:55.032980 :90F7FFC3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:11:01.028840 :90F801AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:11:01.020 2025-04-30 22:11:01.028842 :90F801AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11520 pso_num=25 pso_serial#=194 2025-04-30 22:11:01.028977 :90F801AD:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11520 (legacy spawn) 2025-04-30 22:11:01.035435 :90F801AE: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-04-30 22:11:01.035620 :90F801B3: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-04-30 22:11:01.036407 :90F801B8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:11:35.045428 :90F80CCE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:11:35.037 2025-04-30 22:11:35.045431 :90F80CCF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8944 pso_num=25 pso_serial#=195 2025-04-30 22:11:35.045585 :90F80CD0:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8944 (legacy spawn) 2025-04-30 22:11:35.052053 :90F80CD1: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-04-30 22:11:35.052231 :90F80CD9: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-04-30 22:11:35.053040 :90F80CDB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:11:37.088273 :90F80D51:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:11:37.080 2025-04-30 22:11:37.088275 :90F80D52:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9440 pso_num=25 pso_serial#=196 2025-04-30 22:11:37.088409 :90F80D53:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9440 (legacy spawn) 2025-04-30 22:11:37.094753 :90F80D54: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-04-30 22:11:37.094925 :90F80D59: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-04-30 22:11:37.095723 :90F80D5E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:12:39.294503 :90F82224:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:12:39.287 2025-04-30 22:12:39.294505 :90F82225:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10428 pso_num=25 pso_serial#=197 2025-04-30 22:12:39.294645 :90F82226:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10428 (legacy spawn) 2025-04-30 22:12:39.301054 :90F82227: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-04-30 22:12:39.301085 :90F8222A: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-04-30 22:12:39.301248 :90F82231: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-04-30 22:12:39.302070 :90F8223E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:12:41.149194 :90F822E8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:12:41.135 2025-04-30 22:12:41.149196 :90F822E9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9536 pso_num=25 pso_serial#=198 2025-04-30 22:12:41.149342 :90F822EA:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9536 (legacy spawn) 2025-04-30 22:12:41.155706 :90F822EB: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-04-30 22:12:41.155873 :90F822F4: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-04-30 22:12:41.156652 :90F822F5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:12:41.165243 :90F822F8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:12:41.151 2025-04-30 22:12:41.165245 :90F822F9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11328 pso_num=25 pso_serial#=199 2025-04-30 22:12:41.165378 :90F822FA:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11328 (legacy spawn) 2025-04-30 22:12:41.171799 :90F822FB: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-04-30 22:12:41.171964 :90F82304: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-04-30 22:12:41.172793 :90F82305:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:12:43.263998 :90F82389:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:12:43.257 2025-04-30 22:12:43.264001 :90F8238A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8868 pso_num=25 pso_serial#=200 2025-04-30 22:12:43.264149 :90F8238B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8868 (legacy spawn) 2025-04-30 22:12:43.270681 :90F8238C: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-04-30 22:12:43.270872 :90F82391: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-04-30 22:12:43.271669 :90F82396:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:12:44.285076 :90F82404:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:12:44.272 2025-04-30 22:12:44.285079 :90F82405:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11488 pso_num=25 pso_serial#=201 2025-04-30 22:12:44.285208 :90F82406:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11488 (legacy spawn) 2025-04-30 22:12:44.291691 :90F82407: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-04-30 22:12:44.291867 :90F82410: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-04-30 22:12:44.292657 :90F82411:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:12:44.310601 :90F82414:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:12:44.304 2025-04-30 22:12:44.310603 :90F82415:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11084 pso_num=25 pso_serial#=202 2025-04-30 22:12:44.310729 :90F82416:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11084 (legacy spawn) 2025-04-30 22:12:44.317718 :90F82417: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-04-30 22:12:44.317897 :90F8241E: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-04-30 22:12:44.318579 :90F82421:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:13:03.198253 :90F829B9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:13:03.185 2025-04-30 22:13:03.198255 :90F829BA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10640 pso_num=25 pso_serial#=203 2025-04-30 22:13:03.198389 :90F829BB:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10640 (legacy spawn) 2025-04-30 22:13:03.204825 :90F829BC: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-04-30 22:13:03.205015 :90F829C2: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-04-30 22:13:03.205833 :90F829C6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:13:03.214483 :90F829C9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:13:03.200 2025-04-30 22:13:03.214486 :90F829CA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9648 pso_num=25 pso_serial#=204 2025-04-30 22:13:03.214624 :90F829CB:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9648 (legacy spawn) 2025-04-30 22:13:03.221121 :90F829CC: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-04-30 22:13:03.221290 :90F829D4: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-04-30 22:13:03.222091 :90F829D6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-30 22:13:38.913532 :90F83567:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-30 22:13:38.908 2025-04-30 22:13:38.913535 :90F83568:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9208 pso_num=25 pso_serial#=205 2025-04-30 22:13:38.913687 :90F83569:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9208 osp_idx=23 osp_ver=71094 osp_pg=0 (spawn #135198) 2025-04-30 22:13:38.913687 :90F8356A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:13:38.913688 :90F8356B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=213 time=3322008390 2025-04-30 22:13:38.913688 :90F8356C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:13:38.913688 :90F8356D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:13:38.913689 :90F8356E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:13:38.913689 :90F8356F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:13:38.913690 :90F83570:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:13:38.914214 :90F83571: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-04-30 22:13:38.914262 :90F83579: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: 9208, W000)