Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250825220009\orcl_w000_23340_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:35212M/63366M, Ph+PgF:40315M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 23340, image: ORACLE.EXE (W000) *** 2025-08-25 22:00:09.476 *** SESSION ID:(201.47439) 2025-08-25 22:00:09.476 *** 2025-08-25 22:00:09.476 Process diagnostic dump for ORACLE.EXE (W000), OS id=23340, pid: 25, proc_ser: 174, sid: 201, sess_ser: 47439 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=98 seq_num=99 snap_id=1 wait times: snap=4.870572 sec, exc=4.870572 sec, total=4.870572 sec wait times: max=5.000000 sec, heur=8 min 10 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.000039 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=97 seq_num=98 snap_id=1 wait times: snap=5.010171 sec, exc=5.010171 sec, total=5.010171 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=5.008427 sec, exc=5.008427 sec, total=5.008427 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=95 seq_num=96 snap_id=1 wait times: snap=5.015254 sec, exc=5.015254 sec, total=5.015254 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=5.003423 sec, exc=5.003423 sec, total=5.003423 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=93 seq_num=94 snap_id=1 wait times: snap=5.013374 sec, exc=5.013374 sec, total=5.013374 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=92 seq_num=93 snap_id=1 wait times: snap=5.001094 sec, exc=5.001094 sec, total=5.001094 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=91 seq_num=92 snap_id=1 wait times: snap=5.010143 sec, exc=5.010143 sec, total=5.010143 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000040 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=90 seq_num=91 snap_id=1 wait times: snap=5.011450 sec, exc=5.011450 sec, total=5.011450 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000088 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=89 seq_num=90 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.000031 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=88 seq_num=89 snap_id=1 wait times: snap=5.015242 sec, exc=5.015242 sec, total=5.015242 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time Sampled Session History of session 201 serial 47439 --------------------------------------------------- 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, 21:58:09 - 22:00:09] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-25 22:00:09.476 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 23340, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-25 19:21:38.581435 :C493982C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 19:29:26.394280 :C4942D72:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-25 19:31:39.948495 :C4945946:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-25 19:41:40.911176 :C49519FE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 19:41:41.347839 :C4951A37:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 19:41:41.342 2025-08-25 19:41:41.347841 :C4951A38:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22496 pso_num=25 pso_serial#=160 2025-08-25 19:41:41.347978 :C4951A39:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22496 osp_idx=23 osp_ver=259352 osp_pg=0 (spawn #525501) 2025-08-25 19:41:41.347979 :C4951A3A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 19:41:41.347979 :C4951A3B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=244 time=536556080 2025-08-25 19:41:41.347979 :C4951A3C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 19:41:41.347980 :C4951A3D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 19:41:41.347980 :C4951A3E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 19:41:41.347980 :C4951A3F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 19:41:41.347980 :C4951A40:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 19:41:41.348512 :C4951A41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 19:41:41.348564 :C4951A4A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 19:51:42.234577 :C495DC21:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 19:51:42.614399 :C495DC61:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 19:51:42.609 2025-08-25 19:51:42.614401 :C495DC62:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21896 pso_num=25 pso_serial#=161 2025-08-25 19:51:42.614542 :C495DC63:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21896 osp_idx=23 osp_ver=259353 osp_pg=0 (spawn #525524) 2025-08-25 19:51:42.614542 :C495DC64:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 19:51:42.614543 :C495DC65:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=245 time=537157346 2025-08-25 19:51:42.614543 :C495DC66:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 19:51:42.614543 :C495DC67:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 19:51:42.614544 :C495DC68:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 19:51:42.614545 :C495DC69:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 19:51:42.614545 :C495DC6A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 19:51:42.615050 :C495DC6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 19:51:42.615097 :C495DC73:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 20:01:43.497987 :C4969F29:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 20:01:43.940220 :C4969F5F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 20:01:43.935 2025-08-25 20:01:43.940222 :C4969F60:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20704 pso_num=25 pso_serial#=162 2025-08-25 20:01:43.940362 :C4969F61:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20704 osp_idx=23 osp_ver=259354 osp_pg=0 (spawn #525549) 2025-08-25 20:01:43.940363 :C4969F62:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 20:01:43.940363 :C4969F63:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=537758674 2025-08-25 20:01:43.940363 :C4969F64:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 20:01:43.940364 :C4969F65:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 20:01:43.940364 :C4969F66:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 20:01:43.940364 :C4969F67:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 20:01:43.940365 :C4969F68:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 20:01:43.940858 :C4969F69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 20:01:43.940913 :C4969F73:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 20:11:44.797274 :C497696B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 20:11:45.223885 :C49769AD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 20:11:45.219 2025-08-25 20:11:45.223888 :C49769AE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20840 pso_num=25 pso_serial#=163 2025-08-25 20:11:45.224025 :C49769AF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20840 osp_idx=23 osp_ver=259355 osp_pg=0 (spawn #525572) 2025-08-25 20:11:45.224027 :C49769B0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 20:11:45.224027 :C49769B1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=538359955 2025-08-25 20:11:45.224027 :C49769B2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 20:11:45.224028 :C49769B3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 20:11:45.224028 :C49769B4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 20:11:45.224028 :C49769B5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 20:11:45.224029 :C49769B6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 20:11:45.224570 :C49769B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 20:11:45.224614 :C49769C0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 20:21:46.159839 :C4982A9B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 20:21:46.631473 :C4982AE0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 20:21:46.626 2025-08-25 20:21:46.631476 :C4982AE1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20704 pso_num=25 pso_serial#=164 2025-08-25 20:21:46.631637 :C4982AE2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20704 osp_idx=23 osp_ver=259356 osp_pg=0 (spawn #525595) 2025-08-25 20:21:46.631638 :C4982AE3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 20:21:46.631638 :C4982AE4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=538961377 2025-08-25 20:21:46.631639 :C4982AE5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 20:21:46.631639 :C4982AE6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 20:21:46.631639 :C4982AE7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 20:21:46.631640 :C4982AE8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 20:21:46.631640 :C4982AE9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 20:21:46.632149 :C4982AEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 20:21:46.632197 :C4982AF2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 20:31:47.511084 :C498EC85:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 20:31:48.015784 :C498ECBE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 20:31:48.011 2025-08-25 20:31:48.015786 :C498ECBF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18072 pso_num=25 pso_serial#=165 2025-08-25 20:31:48.015931 :C498ECC0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18072 osp_idx=23 osp_ver=259357 osp_pg=0 (spawn #525618) 2025-08-25 20:31:48.015931 :C498ECC1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 20:31:48.015932 :C498ECC2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=539562752 2025-08-25 20:31:48.015932 :C498ECC3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 20:31:48.015933 :C498ECC4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 20:31:48.015933 :C498ECC5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 20:31:48.015933 :C498ECC6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 20:31:48.015933 :C498ECC7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 20:31:48.016445 :C498ECC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 20:31:48.016488 :C498ECD0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 20:41:48.965095 :C499AEDE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 20:41:49.454207 :C499AF2C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 20:41:49.450 2025-08-25 20:41:49.454209 :C499AF2D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22600 pso_num=25 pso_serial#=166 2025-08-25 20:41:49.454345 :C499AF2E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22600 osp_idx=23 osp_ver=259358 osp_pg=0 (spawn #525640) 2025-08-25 20:41:49.454346 :C499AF2F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 20:41:49.454346 :C499AF30:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=540164190 2025-08-25 20:41:49.454347 :C499AF31:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 20:41:49.454347 :C499AF32:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 20:41:49.454347 :C499AF33:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 20:41:49.454348 :C499AF34:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 20:41:49.454348 :C499AF35:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 20:41:49.454850 :C499AF36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 20:41:49.454902 :C499AF3E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 20:51:50.414324 :C49A7124:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 20:51:50.442915 :C49A7127:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 20:51:50.430 2025-08-25 20:51:50.442918 :C49A7128:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20296 pso_num=25 pso_serial#=167 2025-08-25 20:51:50.443052 :C49A7129:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20296 (legacy spawn) 2025-08-25 20:51:50.449473 :C49A712A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-25 20:51:50.449642 :C49A712F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-25 20:51:50.450424 :C49A7134:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 20:51:50.459225 :C49A7137:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 20:51:50.445 2025-08-25 20:51:50.459227 :C49A7138:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21000 pso_num=25 pso_serial#=168 2025-08-25 20:51:50.459369 :C49A7139:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21000 (legacy spawn) 2025-08-25 20:51:50.465920 :C49A713A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-25 20:51:50.466098 :C49A7143:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-25 20:51:50.466936 :C49A7144:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 20:51:50.903375 :C49A717D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 20:51:50.898 2025-08-25 20:51:50.903377 :C49A717E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19120 pso_num=25 pso_serial#=169 2025-08-25 20:51:50.903517 :C49A717F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19120 osp_idx=23 osp_ver=259361 osp_pg=0 (spawn #525664) 2025-08-25 20:51:50.903518 :C49A7180:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 20:51:50.903518 :C49A7181:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=251 time=540765643 2025-08-25 20:51:50.903519 :C49A7182:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 20:51:50.903519 :C49A7183:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 20:51:50.903519 :C49A7184:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 20:51:50.903520 :C49A7185:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 20:51:50.903520 :C49A7186:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 20:51:50.903999 :C49A7187:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 20:51:50.904046 :C49A718F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 21:01:51.777105 :C49B3469:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 21:01:52.203948 :C49B34AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 21:01:52.199 2025-08-25 21:01:52.203950 :C49B34AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23328 pso_num=25 pso_serial#=170 2025-08-25 21:01:52.204091 :C49B34AD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23328 osp_idx=23 osp_ver=259362 osp_pg=0 (spawn #525689) 2025-08-25 21:01:52.204092 :C49B34AE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 21:01:52.204093 :C49B34AF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=252 time=541366940 2025-08-25 21:01:52.204093 :C49B34B0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 21:01:52.204093 :C49B34B1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 21:01:52.204094 :C49B34B2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 21:01:52.204094 :C49B34B3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 21:01:52.204094 :C49B34B4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 21:01:52.204593 :C49B34B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 21:01:52.204635 :C49B34BD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 21:11:53.173579 :C49BFEA8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 21:11:53.569093 :C49BFEDE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 21:11:53.564 2025-08-25 21:11:53.569095 :C49BFEDF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22348 pso_num=25 pso_serial#=171 2025-08-25 21:11:53.569239 :C49BFEE0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22348 osp_idx=23 osp_ver=259363 osp_pg=0 (spawn #525712) 2025-08-25 21:11:53.569240 :C49BFEE1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 21:11:53.569240 :C49BFEE2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=541968315 2025-08-25 21:11:53.569241 :C49BFEE3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 21:11:53.569241 :C49BFEE4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 21:11:53.569241 :C49BFEE5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 21:11:53.569242 :C49BFEE6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 21:11:53.569242 :C49BFEE7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 21:11:53.569734 :C49BFEE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 21:11:53.569780 :C49BFEEE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 21:21:54.421776 :C49CBF46:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 21:21:54.848570 :C49CBFB0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 21:21:54.843 2025-08-25 21:21:54.848572 :C49CBFB1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19768 pso_num=25 pso_serial#=172 2025-08-25 21:21:54.848716 :C49CBFB2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19768 osp_idx=23 osp_ver=259364 osp_pg=0 (spawn #525735) 2025-08-25 21:21:54.848717 :C49CBFB3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 21:21:54.848718 :C49CBFB4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=254 time=542569580 2025-08-25 21:21:54.848719 :C49CBFB5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 21:21:54.848719 :C49CBFB6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 21:21:54.848720 :C49CBFB7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 21:21:54.848720 :C49CBFB8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 21:21:54.848721 :C49CBFB9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 21:21:54.849252 :C49CBFBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 21:21:54.849305 :C49CBFC2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 21:29:34.092295 :C49D5398:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-25 21:31:56.125871 :C49D8202:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-25 21:41:57.013822 :C49E4292:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 21:41:57.502887 :C49E42FD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 21:41:57.497 2025-08-25 21:41:57.502889 :C49E42FE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21580 pso_num=25 pso_serial#=173 2025-08-25 21:41:57.503028 :C49E42FF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21580 osp_idx=23 osp_ver=259365 osp_pg=0 (spawn #525779) 2025-08-25 21:41:57.503029 :C49E4300:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 21:41:57.503030 :C49E4301:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=255 time=543772237 2025-08-25 21:41:57.503030 :C49E4302:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 21:41:57.503030 :C49E4303:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 21:41:57.503031 :C49E4304:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 21:41:57.503031 :C49E4305:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 21:41:57.503031 :C49E4306:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 21:41:57.503552 :C49E4307:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 21:41:57.503601 :C49E430F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-25 21:51:58.444734 :C49F0444:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-25 21:51:58.840146 :C49F04A4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-25 21:51:58.835 2025-08-25 21:51:58.840148 :C49F04A5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23340 pso_num=25 pso_serial#=174 2025-08-25 21:51:58.840287 :C49F04A6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23340 osp_idx=23 osp_ver=259366 osp_pg=0 (spawn #525802) 2025-08-25 21:51:58.840288 :C49F04A7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 21:51:58.840288 :C49F04A8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=0 time=544373580 2025-08-25 21:51:58.840289 :C49F04A9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 21:51:58.840289 :C49F04AA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 21:51:58.840289 :C49F04AB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 21:51:58.840290 :C49F04AC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 21:51:58.840290 :C49F04AD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 21:51:58.840797 :C49F04AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-25 21:51:58.840845 :C49F04B5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 23340, W000)