Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250823140105\orcl_w000_15000_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:35298M/63366M, Ph+PgF:40459M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15000, image: ORACLE.EXE (W000) *** 2025-08-23 14:01:05.814 *** SESSION ID:(193.28123) 2025-08-23 14:01:05.814 *** 2025-08-23 14:01:05.814 Process diagnostic dump for ORACLE.EXE (W000), OS id=15000, pid: 24, proc_ser: 156, sid: 193, sess_ser: 28123 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=78 seq_num=79 snap_id=1 wait times: snap=1.115695 sec, exc=1.115695 sec, total=1.115695 sec wait times: max=5.000000 sec, heur=6 min 26 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=77 seq_num=78 snap_id=1 wait times: snap=5.014753 sec, exc=5.014753 sec, total=5.014753 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=76 seq_num=77 snap_id=1 wait times: snap=5.004647 sec, exc=5.004647 sec, total=5.004647 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=75 seq_num=76 snap_id=1 wait times: snap=5.014714 sec, exc=5.014714 sec, total=5.014714 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=74 seq_num=75 snap_id=1 wait times: snap=5.004625 sec, exc=5.004625 sec, total=5.004625 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=73 seq_num=74 snap_id=1 wait times: snap=5.004605 sec, exc=5.004605 sec, total=5.004605 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000058 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=72 seq_num=73 snap_id=1 wait times: snap=5.008338 sec, exc=5.008338 sec, total=5.008338 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000040 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=5.013636 sec, exc=5.013636 sec, total=5.013636 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000046 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=70 seq_num=71 snap_id=1 wait times: snap=5.014732 sec, exc=5.014732 sec, total=5.014732 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000054 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=69 seq_num=70 snap_id=1 wait times: snap=5.014721 sec, exc=5.014721 sec, total=5.014721 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000040 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=68 seq_num=69 snap_id=1 wait times: snap=5.015237 sec, exc=5.015237 sec, total=5.015237 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000041 sec of elapsed time Sampled Session History of session 193 serial 28123 --------------------------------------------------- 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, 13:59:05 - 14:01:05] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-23 14:01:05.814 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15000, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-23 11:24:19.131710 :C38C9133:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 11:24:19.131711 :C38C9134:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 11:24:19.132212 :C38C9135: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-08-23 11:24:19.132255 :C38C913C: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-08-23 11:34:20.057472 :C38D562E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 11:34:20.528614 :C38D5677:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 11:34:20.524 2025-08-23 11:34:20.528616 :C38D5678:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21156 pso_num=24 pso_serial#=143 2025-08-23 11:34:20.528764 :C38D5679:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21156 osp_idx=22 osp_ver=776883 osp_pg=0 (spawn #517706) 2025-08-23 11:34:20.528764 :C38D567A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 11:34:20.528765 :C38D567B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=0 time=334562533 2025-08-23 11:34:20.528765 :C38D567C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 11:34:20.528766 :C38D567D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 11:34:20.528766 :C38D567E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 11:34:20.528767 :C38D567F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 11:34:20.528768 :C38D5680:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 11:34:20.529295 :C38D5681: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-08-23 11:34:20.529344 :C38D5689: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-08-23 11:44:21.365906 :C38E1BE3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 11:44:21.792544 :C38E1C1E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 11:44:21.788 2025-08-23 11:44:21.792546 :C38E1C1F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4124 pso_num=24 pso_serial#=144 2025-08-23 11:44:21.792685 :C38E1C20:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4124 osp_idx=22 osp_ver=776884 osp_pg=0 (spawn #517729) 2025-08-23 11:44:21.792686 :C38E1C21:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 11:44:21.792686 :C38E1C22:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=1 time=335163799 2025-08-23 11:44:21.792687 :C38E1C23:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 11:44:21.792687 :C38E1C24:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 11:44:21.792687 :C38E1C25:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 11:44:21.792688 :C38E1C26:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 11:44:21.792688 :C38E1C27:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 11:44:21.793202 :C38E1C28: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-08-23 11:44:21.793245 :C38E1C30: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-08-23 11:54:22.639012 :C38EE04E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 11:54:23.268672 :C38EE0BA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 11:54:23.264 2025-08-23 11:54:23.268674 :C38EE0BB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6128 pso_num=24 pso_serial#=145 2025-08-23 11:54:23.268808 :C38EE0BC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=6128 osp_idx=22 osp_ver=776885 osp_pg=0 (spawn #517752) 2025-08-23 11:54:23.268809 :C38EE0BD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 11:54:23.268809 :C38EE0BE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=2 time=335765283 2025-08-23 11:54:23.268809 :C38EE0BF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 11:54:23.268810 :C38EE0C0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 11:54:23.268810 :C38EE0C1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 11:54:23.268810 :C38EE0C2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 11:54:23.268811 :C38EE0C3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 11:54:23.269297 :C38EE0C4: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-08-23 11:54:23.269341 :C38EE0CD: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-08-23 12:04:24.206262 :C38FA791:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 12:04:24.758112 :C38FA7CD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 12:04:24.753 2025-08-23 12:04:24.758114 :C38FA7CE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21840 pso_num=24 pso_serial#=146 2025-08-23 12:04:24.758256 :C38FA7CF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21840 osp_idx=22 osp_ver=776886 osp_pg=0 (spawn #517777) 2025-08-23 12:04:24.758257 :C38FA7D0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 12:04:24.758258 :C38FA7D1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=3 time=336366768 2025-08-23 12:04:24.758258 :C38FA7D2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 12:04:24.758259 :C38FA7D3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 12:04:24.758259 :C38FA7D4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 12:04:24.758259 :C38FA7D5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 12:04:24.758259 :C38FA7D6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 12:04:24.758772 :C38FA7D7: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-08-23 12:04:24.758818 :C38FA7DD: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-08-23 12:14:25.612371 :C3907CB7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 12:14:26.039401 :C3907CDD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 12:14:26.034 2025-08-23 12:14:26.039403 :C3907CDE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16876 pso_num=24 pso_serial#=147 2025-08-23 12:14:26.039550 :C3907CDF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16876 osp_idx=22 osp_ver=776887 osp_pg=0 (spawn #517800) 2025-08-23 12:14:26.039551 :C3907CE0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 12:14:26.039552 :C3907CE1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=4 time=336968049 2025-08-23 12:14:26.039552 :C3907CE2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 12:14:26.039552 :C3907CE3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 12:14:26.039553 :C3907CE4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 12:14:26.039553 :C3907CE5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 12:14:26.039554 :C3907CE6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 12:14:26.040080 :C3907CE7: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-08-23 12:14:26.040131 :C3907CEE: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-08-23 12:24:26.959049 :C391443A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 12:24:27.370181 :C3914454:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 12:24:27.365 2025-08-23 12:24:27.370183 :C3914455:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16116 pso_num=24 pso_serial#=148 2025-08-23 12:24:27.370318 :C3914456:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16116 osp_idx=22 osp_ver=776888 osp_pg=0 (spawn #517823) 2025-08-23 12:24:27.370319 :C3914457:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 12:24:27.370319 :C3914458:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=5 time=337569377 2025-08-23 12:24:27.370319 :C3914459:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 12:24:27.370320 :C391445A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 12:24:27.370320 :C391445B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 12:24:27.370320 :C391445C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 12:24:27.370321 :C391445D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 12:24:27.370807 :C391445E: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-08-23 12:24:27.370850 :C3914467: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-08-23 12:34:28.256238 :C392095E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 12:34:28.745403 :C3920999:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 12:34:28.741 2025-08-23 12:34:28.745405 :C392099A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21084 pso_num=24 pso_serial#=149 2025-08-23 12:34:28.745546 :C392099B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21084 osp_idx=22 osp_ver=776889 osp_pg=0 (spawn #517845) 2025-08-23 12:34:28.745547 :C392099C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 12:34:28.745547 :C392099D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=6 time=338170752 2025-08-23 12:34:28.745547 :C392099E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 12:34:28.745548 :C392099F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 12:34:28.745548 :C39209A0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 12:34:28.745548 :C39209A1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 12:34:28.745549 :C39209A2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 12:34:28.746044 :C39209A3: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-08-23 12:34:28.746091 :C39209AA: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-08-23 12:40:50.544921 :C392871F: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-08-23 12:44:30.052331 :C392CE96: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-08-23 12:54:30.936846 :C39393E5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 12:54:31.394542 :C3939421:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 12:54:31.390 2025-08-23 12:54:31.394545 :C3939422:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21736 pso_num=24 pso_serial#=150 2025-08-23 12:54:31.394688 :C3939423:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21736 osp_idx=22 osp_ver=776890 osp_pg=0 (spawn #517890) 2025-08-23 12:54:31.394689 :C3939424:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 12:54:31.394689 :C3939425:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=7 time=339373408 2025-08-23 12:54:31.394690 :C3939426:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 12:54:31.394690 :C3939427:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 12:54:31.394690 :C3939428:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 12:54:31.394691 :C3939429:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 12:54:31.394691 :C393942A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 12:54:31.395207 :C393942B: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-08-23 12:54:31.395256 :C3939432: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-08-23 13:04:32.334568 :C3945B37:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 13:04:32.745628 :C3945B71:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 13:04:32.741 2025-08-23 13:04:32.745630 :C3945B72:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21980 pso_num=24 pso_serial#=151 2025-08-23 13:04:32.745774 :C3945B73:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21980 osp_idx=22 osp_ver=776891 osp_pg=0 (spawn #517915) 2025-08-23 13:04:32.745774 :C3945B74:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 13:04:32.745775 :C3945B75:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=8 time=339974752 2025-08-23 13:04:32.745775 :C3945B76:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 13:04:32.745776 :C3945B77:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 13:04:32.745776 :C3945B78:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 13:04:32.745776 :C3945B79:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 13:04:32.745777 :C3945B7A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 13:04:32.746282 :C3945B7B: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-08-23 13:04:32.746329 :C3945B82: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-08-23 13:14:33.725431 :C3952919:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 13:14:34.120659 :C3952933:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 13:14:34.116 2025-08-23 13:14:34.120661 :C3952934:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22152 pso_num=24 pso_serial#=152 2025-08-23 13:14:34.120796 :C3952935:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22152 osp_idx=22 osp_ver=776892 osp_pg=0 (spawn #517938) 2025-08-23 13:14:34.120797 :C3952936:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 13:14:34.120797 :C3952937:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=9 time=340576127 2025-08-23 13:14:34.120797 :C3952938:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 13:14:34.120798 :C3952939:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 13:14:34.120798 :C395293A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 13:14:34.120798 :C395293B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 13:14:34.120798 :C395293C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 13:14:34.121342 :C395293D: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-08-23 13:14:34.121385 :C3952945: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-08-23 13:24:35.049534 :C395EDCA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 13:24:35.319874 :C395EDE4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 13:24:35.315 2025-08-23 13:24:35.319876 :C395EDE5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21700 pso_num=24 pso_serial#=153 2025-08-23 13:24:35.320014 :C395EDE6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21700 osp_idx=22 osp_ver=776893 osp_pg=0 (spawn #517961) 2025-08-23 13:24:35.320015 :C395EDE7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 13:24:35.320015 :C395EDE8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=10 time=341177330 2025-08-23 13:24:35.320015 :C395EDE9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 13:24:35.320015 :C395EDEA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 13:24:35.320016 :C395EDEB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 13:24:35.320016 :C395EDEC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 13:24:35.320016 :C395EDED:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 13:24:35.320513 :C395EDEE: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-08-23 13:24:35.320567 :C395EDF5: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-08-23 13:34:36.195353 :C396B3F6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 13:34:36.559882 :C396B44C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 13:34:36.554 2025-08-23 13:34:36.559884 :C396B44D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22036 pso_num=24 pso_serial#=154 2025-08-23 13:34:36.560040 :C396B44E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22036 osp_idx=22 osp_ver=776894 osp_pg=0 (spawn #517983) 2025-08-23 13:34:36.560041 :C396B44F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 13:34:36.560042 :C396B450:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=11 time=341778565 2025-08-23 13:34:36.560042 :C396B451:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 13:34:36.560043 :C396B452:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 13:34:36.560043 :C396B453:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 13:34:36.560043 :C396B454:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 13:34:36.560044 :C396B455:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 13:34:36.560684 :C396B456: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-08-23 13:34:36.560734 :C396B45D: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-08-23 13:44:37.413310 :C3977911:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 13:44:37.886864 :C397794D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 13:44:37.881 2025-08-23 13:44:37.886866 :C397794E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16236 pso_num=24 pso_serial#=155 2025-08-23 13:44:37.887001 :C397794F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16236 osp_idx=22 osp_ver=776895 osp_pg=0 (spawn #518006) 2025-08-23 13:44:37.887002 :C3977950:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 13:44:37.887002 :C3977951:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=12 time=342379893 2025-08-23 13:44:37.887003 :C3977952:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 13:44:37.887003 :C3977953:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 13:44:37.887004 :C3977954:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 13:44:37.887004 :C3977955:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 13:44:37.887004 :C3977956:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 13:44:37.887487 :C3977957: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-08-23 13:44:37.887533 :C3977961: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-08-23 13:54:38.786322 :C3983E1C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 13:54:39.066797 :C3983E5E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 13:54:39.062 2025-08-23 13:54:39.066799 :C3983E5F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15000 pso_num=24 pso_serial#=156 2025-08-23 13:54:39.066936 :C3983E60:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15000 osp_idx=22 osp_ver=776896 osp_pg=0 (spawn #518029) 2025-08-23 13:54:39.066936 :C3983E61:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 13:54:39.066936 :C3983E62:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=13 time=342981080 2025-08-23 13:54:39.066937 :C3983E63:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 13:54:39.066937 :C3983E64:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 13:54:39.066937 :C3983E65:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 13:54:39.066938 :C3983E66:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 13:54:39.066938 :C3983E67:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 13:54:39.067493 :C3983E68: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-08-23 13:54:39.067538 :C3983E6E: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: 15000, W000)