Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525140428\orcl_w000_13840_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:37329M/63366M, Ph+PgF:44388M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 13840, image: ORACLE.EXE (W000) *** 2025-05-25 14:04:28.815 *** SESSION ID:(193.18761) 2025-05-25 14:04:28.815 *** 2025-05-25 14:04:28.815 Process diagnostic dump for ORACLE.EXE (W000), OS id=13840, pid: 24, proc_ser: 206, sid: 193, sess_ser: 18761 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=0.626651 sec, exc=0.626651 sec, total=0.626651 sec wait times: max=5.000000 sec, heur=1 min 45 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.000024 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=5.013230 sec, exc=5.013230 sec, total=5.013230 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=5.011589 sec, exc=5.011589 sec, total=5.011589 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=19 seq_num=20 snap_id=1 wait times: snap=5.004606 sec, exc=5.004606 sec, total=5.004606 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=18 seq_num=19 snap_id=1 wait times: snap=5.014792 sec, exc=5.014792 sec, total=5.014792 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=5.010147 sec, exc=5.010147 sec, total=5.010147 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=16 seq_num=17 snap_id=1 wait times: snap=5.004181 sec, exc=5.004181 sec, total=5.004181 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=5.003214 sec, exc=5.003214 sec, total=5.003214 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=14 seq_num=15 snap_id=1 wait times: snap=5.001756 sec, exc=5.001756 sec, total=5.001756 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=5.004627 sec, exc=5.004627 sec, total=5.004627 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=5.009217 sec, exc=5.009217 sec, total=5.009217 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time Sampled Session History of session 193 serial 18761 --------------------------------------------------- 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 --------------------------------------------------- [106 samples, 14:02:43 - 14:04:28] idle wait at each sample [session created at: 14:02:43] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 14:04:28.815 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 13840, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 11:52:25.120280 :9BCA3904:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13544 osp_idx=22 osp_ver=319450 osp_pg=0 (spawn #217758) 2025-05-25 11:52:25.120281 :9BCA3905:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 11:52:25.120281 :9BCA3906:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=36 time=1149717375 2025-05-25 11:52:25.120282 :9BCA3907:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 11:52:25.120282 :9BCA3908:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 11:52:25.120282 :9BCA3909:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 11:52:25.120283 :9BCA390A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 11:52:25.120283 :9BCA390B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 11:52:25.120786 :9BCA390C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 11:52:25.120830 :9BCA3913:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 12:02:26.060840 :9BCAFEFB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 12:02:26.362447 :9BCAFF38:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 12:02:26.357 2025-05-25 12:02:26.362449 :9BCAFF39:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13904 pso_num=24 pso_serial#=192 2025-05-25 12:02:26.362581 :9BCAFF3A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13904 osp_idx=22 osp_ver=319451 osp_pg=0 (spawn #217783) 2025-05-25 12:02:26.362581 :9BCAFF3B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 12:02:26.362582 :9BCAFF3C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=37 time=1150318610 2025-05-25 12:02:26.362582 :9BCAFF3D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 12:02:26.362583 :9BCAFF3E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 12:02:26.362583 :9BCAFF3F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 12:02:26.362583 :9BCAFF40:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 12:02:26.362584 :9BCAFF41:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 12:02:26.363066 :9BCAFF42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 12:02:26.363112 :9BCAFF4A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 12:12:27.325927 :9BCBCD51:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 12:12:27.705634 :9BCBCD95:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 12:12:27.701 2025-05-25 12:12:27.705636 :9BCBCD96:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14136 pso_num=24 pso_serial#=193 2025-05-25 12:12:27.705770 :9BCBCD97:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14136 osp_idx=22 osp_ver=319452 osp_pg=0 (spawn #217806) 2025-05-25 12:12:27.705770 :9BCBCD98:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 12:12:27.705771 :9BCBCD99:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=38 time=1150919954 2025-05-25 12:12:27.705771 :9BCBCD9A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 12:12:27.705771 :9BCBCD9B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 12:12:27.705772 :9BCBCD9C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 12:12:27.705772 :9BCBCD9D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 12:12:27.705772 :9BCBCD9E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 12:12:27.706265 :9BCBCD9F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 12:12:27.706310 :9BCBCDA9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 12:22:28.551978 :9BCC935B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 12:22:29.035434 :9BCC9395:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 12:22:29.030 2025-05-25 12:22:29.035435 :9BCC9396:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=5044 pso_num=24 pso_serial#=194 2025-05-25 12:22:29.035571 :9BCC9397:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=5044 osp_idx=22 osp_ver=319453 osp_pg=0 (spawn #217829) 2025-05-25 12:22:29.035572 :9BCC9398:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 12:22:29.035572 :9BCC9399:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=39 time=1151521282 2025-05-25 12:22:29.035573 :9BCC939A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 12:22:29.035573 :9BCC939B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 12:22:29.035573 :9BCC939C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 12:22:29.035574 :9BCC939D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 12:22:29.035574 :9BCC939E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 12:22:29.036054 :9BCC939F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 12:22:29.036101 :9BCC93A6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 12:24:46.306142 :9BCCC0E7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 12:32:30.424720 :9BCD5A19:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 12:42:31.356711 :9BCE2023:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 12:42:31.767535 :9BCE205A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 12:42:31.763 2025-05-25 12:42:31.767537 :9BCE205B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13196 pso_num=24 pso_serial#=195 2025-05-25 12:42:31.767674 :9BCE205C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13196 osp_idx=22 osp_ver=319454 osp_pg=0 (spawn #217871) 2025-05-25 12:42:31.767674 :9BCE205D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 12:42:31.767675 :9BCE205E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=40 time=1152724016 2025-05-25 12:42:31.767675 :9BCE205F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 12:42:31.767675 :9BCE2060:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 12:42:31.767676 :9BCE2061:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 12:42:31.767676 :9BCE2062:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 12:42:31.767676 :9BCE2063:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 12:42:31.768175 :9BCE2064:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 12:42:31.768223 :9BCE206D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 12:52:32.728560 :9BCEE53E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 12:52:32.835116 :9BCEE541:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 12:52:32.822 2025-05-25 12:52:32.835118 :9BCEE542:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13740 pso_num=24 pso_serial#=196 2025-05-25 12:52:32.835256 :9BCEE543:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13740 (legacy spawn) 2025-05-25 12:52:32.841684 :9BCEE544:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 12:52:32.841868 :9BCEE549:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 12:52:32.842649 :9BCEE54E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 12:52:32.851208 :9BCEE551:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 12:52:32.838 2025-05-25 12:52:32.851210 :9BCEE552:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9532 pso_num=24 pso_serial#=197 2025-05-25 12:52:32.851341 :9BCEE553:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9532 (legacy spawn) 2025-05-25 12:52:32.870734 :9BCEE554:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 12:52:32.870893 :9BCEE55D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 12:52:32.871714 :9BCEE55E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 12:52:33.154914 :9BCEE5A8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 12:52:33.150 2025-05-25 12:52:33.154916 :9BCEE5A9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15200 pso_num=24 pso_serial#=198 2025-05-25 12:52:33.155063 :9BCEE5AA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15200 osp_idx=22 osp_ver=319457 osp_pg=0 (spawn #217894) 2025-05-25 12:52:33.155063 :9BCEE5AB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 12:52:33.155064 :9BCEE5AC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=41 time=1153325407 2025-05-25 12:52:33.155064 :9BCEE5AD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 12:52:33.155065 :9BCEE5AE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 12:52:33.155065 :9BCEE5AF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 12:52:33.155065 :9BCEE5B0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 12:52:33.155066 :9BCEE5B1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 12:52:33.155558 :9BCEE5B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 12:52:33.155606 :9BCEE5BC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 13:02:34.060791 :9BCFAEF6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 13:02:34.503383 :9BCFAF59:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 13:02:34.498 2025-05-25 13:02:34.503384 :9BCFAF5A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11564 pso_num=24 pso_serial#=199 2025-05-25 13:02:34.503526 :9BCFAF5B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11564 osp_idx=22 osp_ver=319458 osp_pg=0 (spawn #217920) 2025-05-25 13:02:34.503526 :9BCFAF5C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:02:34.503527 :9BCFAF5D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=42 time=1153926750 2025-05-25 13:02:34.503527 :9BCFAF5E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:02:34.503527 :9BCFAF5F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:02:34.503527 :9BCFAF60:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:02:34.503528 :9BCFAF61:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:02:34.503528 :9BCFAF62:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:02:34.504101 :9BCFAF63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:02:34.504151 :9BCFAF6C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 13:12:35.457112 :9BD07B16:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 13:12:35.909337 :9BD07B5F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 13:12:35.904 2025-05-25 13:12:35.909339 :9BD07B60:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13472 pso_num=24 pso_serial#=200 2025-05-25 13:12:35.909474 :9BD07B61:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13472 osp_idx=22 osp_ver=319459 osp_pg=0 (spawn #217943) 2025-05-25 13:12:35.909474 :9BD07B62:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:12:35.909474 :9BD07B63:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=43 time=1154528157 2025-05-25 13:12:35.909475 :9BD07B64:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:12:35.909475 :9BD07B65:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:12:35.909475 :9BD07B66:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:12:35.909476 :9BD07B67:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:12:35.909476 :9BD07B68:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:12:35.909961 :9BD07B69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:12:35.910004 :9BD07B70:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 13:22:36.858195 :9BD1429F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 13:22:37.316076 :9BD142DB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 13:22:37.311 2025-05-25 13:22:37.316078 :9BD142DC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13808 pso_num=24 pso_serial#=201 2025-05-25 13:22:37.316259 :9BD142DD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13808 osp_idx=22 osp_ver=319460 osp_pg=0 (spawn #217966) 2025-05-25 13:22:37.316260 :9BD142DE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:22:37.316261 :9BD142DF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=44 time=1155129563 2025-05-25 13:22:37.316262 :9BD142E0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:22:37.316262 :9BD142E1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:22:37.316263 :9BD142E2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:22:37.316263 :9BD142E3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:22:37.316263 :9BD142E4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:22:37.316785 :9BD142E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:22:37.316830 :9BD142ED:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 13:32:38.286927 :9BD208F3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 13:32:38.778122 :9BD2092C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 13:32:38.773 2025-05-25 13:32:38.778124 :9BD2092D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9412 pso_num=24 pso_serial#=202 2025-05-25 13:32:38.778261 :9BD2092E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9412 osp_idx=22 osp_ver=319461 osp_pg=0 (spawn #217989) 2025-05-25 13:32:38.778262 :9BD2092F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:32:38.778263 :9BD20930:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=45 time=1155731032 2025-05-25 13:32:38.778263 :9BD20931:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:32:38.778263 :9BD20932:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:32:38.778264 :9BD20933:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:32:38.778264 :9BD20934:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:32:38.778265 :9BD20935:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:32:38.778772 :9BD20936:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:32:38.778820 :9BD2093E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 13:42:39.681965 :9BD2CFEF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 13:42:40.171151 :9BD2D041:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 13:42:40.166 2025-05-25 13:42:40.171155 :9BD2D042:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14732 pso_num=24 pso_serial#=203 2025-05-25 13:42:40.171293 :9BD2D043:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14732 osp_idx=22 osp_ver=319462 osp_pg=0 (spawn #218011) 2025-05-25 13:42:40.171294 :9BD2D044:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:42:40.171294 :9BD2D045:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=46 time=1156332422 2025-05-25 13:42:40.171295 :9BD2D046:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:42:40.171295 :9BD2D047:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:42:40.171295 :9BD2D048:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:42:40.171296 :9BD2D049:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:42:40.171296 :9BD2D04A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:42:40.171807 :9BD2D04B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:42:40.171849 :9BD2D053:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 13:52:41.119930 :9BD395B4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 13:52:41.572170 :9BD395ED:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 13:52:41.567 2025-05-25 13:52:41.572172 :9BD395EE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15240 pso_num=24 pso_serial#=204 2025-05-25 13:52:41.572304 :9BD395EF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15240 osp_idx=22 osp_ver=319463 osp_pg=0 (spawn #218034) 2025-05-25 13:52:41.572305 :9BD395F0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:52:41.572306 :9BD395F1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=47 time=1156933829 2025-05-25 13:52:41.572306 :9BD395F2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:52:41.572306 :9BD395F3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:52:41.572307 :9BD395F4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:52:41.572307 :9BD395F5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:52:41.572307 :9BD395F6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:52:41.572851 :9BD395F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:52:41.572906 :9BD395FF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 14:02:42.615815 :9BD45DB0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 14:02:42.999182 :9BD45DBF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 14:02:42.990 2025-05-25 14:02:42.999184 :9BD45DC0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15348 pso_num=24 pso_serial#=205 2025-05-25 14:02:42.999321 :9BD45DC1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15348 (legacy spawn) 2025-05-25 14:02:43.005732 :9BD45DC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:02:43.005909 :9BD45DC7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-25 14:02:43.006816 :9BD45DCC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 14:02:43.026715 :9BD45E0A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 14:02:43.021 2025-05-25 14:02:43.026716 :9BD45E0B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13840 pso_num=24 pso_serial#=206 2025-05-25 14:02:43.026852 :9BD45E0C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13840 osp_idx=22 osp_ver=319465 osp_pg=0 (spawn #218059) 2025-05-25 14:02:43.026853 :9BD45E0D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 14:02:43.026853 :9BD45E0E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=48 time=1157535282 2025-05-25 14:02:43.026854 :9BD45E0F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 14:02:43.026854 :9BD45E10:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 14:02:43.026854 :9BD45E11:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 14:02:43.026855 :9BD45E12:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 14:02:43.026855 :9BD45E13:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 14:02:43.027342 :9BD45E14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 14:02:43.027390 :9BD45E1D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 13840, W000)