Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250510082513\orcl_w000_2804_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:37490M/63366M, Ph+PgF:44845M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 2804, image: ORACLE.EXE (W000) *** 2025-05-10 08:25:13.345 *** SESSION ID:(209.32945) 2025-05-10 08:25:13.345 *** 2025-05-10 08:25:13.345 Process diagnostic dump for ORACLE.EXE (W000), OS id=2804, pid: 26, proc_ser: 42, sid: 209, sess_ser: 32945 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=4.491082 sec, exc=4.491082 sec, total=4.491082 sec wait times: max=5.000000 sec, heur=1 min 44 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.000030 sec since current wait 0: 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.013206 sec, exc=5.013206 sec, total=5.013206 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000088 sec of elapsed time 1: 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.004610 sec, exc=5.004610 sec, total=5.004610 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 2: 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.004628 sec, exc=5.004628 sec, total=5.004628 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 3: 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.001704 sec, exc=5.001704 sec, total=5.001704 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=16 seq_num=17 snap_id=1 wait times: snap=5.001274 sec, exc=5.001274 sec, total=5.001274 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 5: 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.010277 sec, exc=5.010277 sec, total=5.010277 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000046 sec of elapsed time 6: 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.007682 sec, exc=5.007682 sec, total=5.007682 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: 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.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 8: 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.014700 sec, exc=5.014700 sec, total=5.014700 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=5.014748 sec, exc=5.014748 sec, total=5.014748 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time Sampled Session History of session 209 serial 32945 --------------------------------------------------- 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 --------------------------------------------------- [105 samples, 08:23:28 - 08:25:13] idle wait at each sample [session created at: 08:23:28] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-10 08:25:13.345 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 2804, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-10 07:53:24.956099 :95217323:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8172 pso_num=26 pso_serial#=26 2025-05-10 07:53:24.956232 :95217324:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8172 osp_idx=24 osp_ver=244825 osp_pg=0 (spawn #167069) 2025-05-10 07:53:24.956233 :95217325:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 07:53:24.956234 :95217326:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=217 time=4134382109 2025-05-10 07:53:24.956234 :95217327:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 07:53:24.956234 :95217328:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 07:53:24.956235 :95217329:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 07:53:24.956235 :9521732A:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 07:53:24.956235 :9521732B:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 07:53:24.956714 :9521732C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 07:53:55.057540 :95217CBB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 07:54:25.040277 :95218664:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 07:54:25.038 2025-05-10 07:54:25.040279 :95218665:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6516 pso_num=26 pso_serial#=27 2025-05-10 07:54:25.040412 :95218666:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=6516 osp_idx=24 osp_ver=244826 osp_pg=0 (spawn #167071) 2025-05-10 07:54:25.040412 :95218667:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 07:54:25.040413 :95218668:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=218 time=4134442203 2025-05-10 07:54:25.040413 :95218669:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 07:54:25.040414 :9521866A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 07:54:25.040414 :9521866B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 07:54:25.040414 :9521866C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 07:54:25.040414 :9521866D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 07:54:25.040893 :9521866E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 07:54:55.090666 :952190F4:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 07:55:25.115342 :95219AB9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 07:55:25.104 2025-05-10 07:55:25.115344 :95219ABA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8812 pso_num=26 pso_serial#=28 2025-05-10 07:55:25.115478 :95219ABB:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8812 osp_idx=24 osp_ver=244827 osp_pg=0 (spawn #167073) 2025-05-10 07:55:25.115479 :95219ABC:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 07:55:25.115479 :95219ABD:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=219 time=4134502281 2025-05-10 07:55:25.115480 :95219ABE:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 07:55:25.115481 :95219ABF:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 07:55:25.115481 :95219AC0:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 07:55:25.115481 :95219AC1:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 07:55:25.115482 :95219AC2:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 07:55:25.115961 :95219AC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 07:55:55.226667 :9521A4AE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 07:56:25.224703 :9521AE36:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 07:56:25.213 2025-05-10 07:56:25.224705 :9521AE37:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9008 pso_num=26 pso_serial#=29 2025-05-10 07:56:25.224834 :9521AE38:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9008 osp_idx=24 osp_ver=244828 osp_pg=0 (spawn #167075) 2025-05-10 07:56:25.224835 :9521AE39:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 07:56:25.224835 :9521AE3A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=220 time=4134562390 2025-05-10 07:56:25.224836 :9521AE3B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 07:56:25.224836 :9521AE3C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 07:56:25.224836 :9521AE3D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 07:56:25.224837 :9521AE3E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 07:56:25.224837 :9521AE3F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 07:56:25.225305 :9521AE40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 07:56:55.311444 :9521B7EE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 07:57:25.304450 :9521C187:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 07:57:25.293 2025-05-10 07:57:25.304452 :9521C188:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8696 pso_num=26 pso_serial#=30 2025-05-10 07:57:25.304588 :9521C189:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8696 osp_idx=24 osp_ver=244829 osp_pg=0 (spawn #167077) 2025-05-10 07:57:25.304589 :9521C18A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 07:57:25.304590 :9521C18B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=221 time=4134622468 2025-05-10 07:57:25.304590 :9521C18C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 07:57:25.304590 :9521C18D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 07:57:25.304591 :9521C18E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 07:57:25.304591 :9521C18F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 07:57:25.304591 :9521C190:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 07:57:25.305065 :9521C191:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 07:57:55.436742 :9521CBDA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 07:58:25.391442 :9521D573:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 07:58:25.381 2025-05-10 07:58:25.391444 :9521D574:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9460 pso_num=26 pso_serial#=31 2025-05-10 07:58:25.391578 :9521D575:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9460 osp_idx=24 osp_ver=244830 osp_pg=0 (spawn #167079) 2025-05-10 07:58:25.391580 :9521D576:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 07:58:25.391581 :9521D577:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=222 time=4134682546 2025-05-10 07:58:25.391581 :9521D578:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 07:58:25.391581 :9521D579:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 07:58:25.391582 :9521D57A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 07:58:25.391582 :9521D57B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 07:58:25.391583 :9521D57C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 07:58:25.392051 :9521D57D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 07:58:55.479421 :9521DF3A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 07:59:20.459874 :9521E76C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 07:59:20.448 2025-05-10 07:59:20.459877 :9521E76D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11904 pso_num=26 pso_serial#=32 2025-05-10 07:59:20.460018 :9521E76E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11904 osp_idx=24 osp_ver=244831 osp_pg=0 (spawn #167081) 2025-05-10 07:59:20.460019 :9521E76F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 07:59:20.460019 :9521E770:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=223 time=4134737625 2025-05-10 07:59:20.460020 :9521E771:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 07:59:20.460020 :9521E772:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 07:59:20.460021 :9521E773:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 07:59:20.460021 :9521E774:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 07:59:20.460022 :9521E775:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 07:59:20.460495 :9521E776:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 07:59:25.462079 :9521E93D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-10 07:59:55.557538 :9521F375:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:00:25.567114 :9521FD40:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:00:25.556 2025-05-10 08:00:25.567116 :9521FD41:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13008 pso_num=26 pso_serial#=33 2025-05-10 08:00:25.567245 :9521FD42:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13008 osp_idx=24 osp_ver=244832 osp_pg=0 (spawn #167084) 2025-05-10 08:00:25.567246 :9521FD43:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 08:00:25.567246 :9521FD44:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=224 time=4134802734 2025-05-10 08:00:25.567247 :9521FD45:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 08:00:25.567247 :9521FD46:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 08:00:25.567247 :9521FD47:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 08:00:25.567248 :9521FD48:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 08:00:25.567248 :9521FD49:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 08:00:25.567714 :9521FD4A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 08:00:55.653277 :952209A1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:01:12.156829 :95220ECD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:01:12.151 2025-05-10 08:01:12.156831 :95220ECE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7608 pso_num=26 pso_serial#=34 2025-05-10 08:01:12.156966 :95220ECF:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7608 osp_idx=24 osp_ver=244833 osp_pg=0 (spawn #167088) 2025-05-10 08:01:12.156966 :95220ED0:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 08:01:12.156966 :95220ED1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=225 time=4134849328 2025-05-10 08:01:12.156967 :95220ED2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 08:01:12.156967 :95220ED3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 08:01:12.156967 :95220ED4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 08:01:12.156968 :95220ED5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 08:01:12.156968 :95220ED6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 08:01:12.157477 :95220ED7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 08:01:12.157518 :95220EDF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-10 08:01:12.163885 :95220EEB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:01:25.656145 :952213A5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:01:25.645 2025-05-10 08:01:25.656148 :952213A6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12348 pso_num=26 pso_serial#=35 2025-05-10 08:01:25.656288 :952213A7:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12348 osp_idx=24 osp_ver=244834 osp_pg=0 (spawn #167090) 2025-05-10 08:01:25.656289 :952213A8:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 08:01:25.656289 :952213A9:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=226 time=4134862812 2025-05-10 08:01:25.656290 :952213AA:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 08:01:25.656290 :952213AB:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 08:01:25.656290 :952213AC:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 08:01:25.656290 :952213AD:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 08:01:25.656291 :952213AE:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 08:01:25.656788 :952213AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 08:01:55.778654 :95221D33:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:02:25.730571 :952226C7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:02:25.719 2025-05-10 08:02:25.730573 :952226C8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9712 pso_num=26 pso_serial#=36 2025-05-10 08:02:25.730709 :952226C9:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9712 osp_idx=24 osp_ver=244835 osp_pg=0 (spawn #167092) 2025-05-10 08:02:25.730709 :952226CA:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 08:02:25.730710 :952226CB:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=227 time=4134922890 2025-05-10 08:02:25.730710 :952226CC:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 08:02:25.730711 :952226CD:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 08:02:25.730711 :952226CE:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 08:02:25.730712 :952226CF:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 08:02:25.730712 :952226D0:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 08:02:25.731179 :952226D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 08:02:55.804254 :9522308B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:03:25.910249 :95223AB3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:03:25.906 2025-05-10 08:03:25.910251 :95223AB4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12756 pso_num=26 pso_serial#=37 2025-05-10 08:03:25.910387 :95223AB5:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12756 osp_idx=24 osp_ver=244836 osp_pg=0 (spawn #167095) 2025-05-10 08:03:25.910387 :95223AB6:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 08:03:25.910388 :95223AB7:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=228 time=4134983078 2025-05-10 08:03:25.910388 :95223AB8:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 08:03:25.910388 :95223AB9:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 08:03:25.910389 :95223ABA:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 08:03:25.910389 :95223ABB:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 08:03:25.910391 :95223ABC:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 08:03:25.910955 :95223ABD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 08:03:25.911001 :95223AC6:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-10 08:13:26.804991 :952304FB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:13:26.917164 :95230509:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:13:26.914 2025-05-10 08:13:26.917166 :9523050A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11428 pso_num=26 pso_serial#=38 2025-05-10 08:13:26.917297 :9523050B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11428 (legacy spawn) 2025-05-10 08:13:26.923659 :9523050C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 08:13:26.923826 :95230515:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-10 08:13:26.924614 :95230516:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:13:26.933286 :95230519:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:13:26.930 2025-05-10 08:13:26.933289 :9523051A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12244 pso_num=26 pso_serial#=39 2025-05-10 08:13:26.933466 :9523051B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12244 (legacy spawn) 2025-05-10 08:13:26.939847 :9523051C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 08:13:26.939999 :95230525:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-10 08:13:26.940843 :95230526:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:13:27.247053 :95230593:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:13:27.242 2025-05-10 08:13:27.247055 :95230594:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13020 pso_num=26 pso_serial#=40 2025-05-10 08:13:27.247185 :95230595:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13020 osp_idx=24 osp_ver=244839 osp_pg=0 (spawn #167119) 2025-05-10 08:13:27.247186 :95230596:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 08:13:27.247186 :95230597:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=229 time=4135584406 2025-05-10 08:13:27.247187 :95230598:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 08:13:27.247187 :95230599:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 08:13:27.247188 :9523059A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 08:13:27.247188 :9523059B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 08:13:27.247188 :9523059C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 08:13:27.247683 :9523059D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 08:13:27.247724 :952305A4:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-10 08:23:28.201164 :9523CC9C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:23:28.209058 :9523CC9F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:23:28.201 2025-05-10 08:23:28.209060 :9523CCA0:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10320 pso_num=26 pso_serial#=41 2025-05-10 08:23:28.209194 :9523CCA1:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10320 (legacy spawn) 2025-05-10 08:23:28.215593 :9523CCA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 08:23:28.215765 :9523CCAA:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-10 08:23:28.216579 :9523CCC0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-10 08:23:28.690214 :9523CD42:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-10 08:23:28.685 2025-05-10 08:23:28.690216 :9523CD43:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=2804 pso_num=26 pso_serial#=42 2025-05-10 08:23:28.690353 :9523CD44:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=2804 osp_idx=24 osp_ver=244841 osp_pg=0 (spawn #167142) 2025-05-10 08:23:28.690354 :9523CD45:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 08:23:28.690354 :9523CD46:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=230 time=4136185859 2025-05-10 08:23:28.690355 :9523CD47:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 08:23:28.690355 :9523CD48:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 08:23:28.690355 :9523CD49:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 08:23:28.690356 :9523CD4A:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 08:23:28.690356 :9523CD4B:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 08:23:28.690850 :9523CD4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 08:23:28.690907 :9523CD52:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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 26 (osid: 2804, W000)