Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250716081523\orcl_w000_11292_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:36299M/63366M, Ph+PgF:42259M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 11292, image: ORACLE.EXE (W000) *** 2025-07-16 08:15:24.087 *** SESSION ID:(201.48349) 2025-07-16 08:15:24.087 *** 2025-07-16 08:15:24.087 Process diagnostic dump for ORACLE.EXE (W000), OS id=11292, pid: 25, proc_ser: 24, sid: 201, sess_ser: 48349 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=1.782437 sec, exc=1.782437 sec, total=1.782437 sec wait times: max=5.000000 sec, heur=5 min 52 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000037 sec since current wait 0: 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.009703 sec, exc=5.009703 sec, total=5.009703 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 1: 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.002110 sec, exc=5.002110 sec, total=5.002110 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=68 seq_num=69 snap_id=1 wait times: snap=5.001355 sec, exc=5.001355 sec, total=5.001355 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000080 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=67 seq_num=68 snap_id=1 wait times: snap=5.012730 sec, exc=5.012730 sec, total=5.012730 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=66 seq_num=67 snap_id=1 wait times: snap=5.015043 sec, exc=5.015043 sec, total=5.015043 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000043 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=65 seq_num=66 snap_id=1 wait times: snap=5.004616 sec, exc=5.004616 sec, total=5.004616 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=64 seq_num=65 snap_id=1 wait times: snap=5.004191 sec, exc=5.004191 sec, total=5.004191 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=63 seq_num=64 snap_id=1 wait times: snap=5.002139 sec, exc=5.002139 sec, total=5.002139 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=62 seq_num=63 snap_id=1 wait times: snap=5.001380 sec, exc=5.001380 sec, total=5.001380 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=61 seq_num=62 snap_id=1 wait times: snap=5.000354 sec, exc=5.000354 sec, total=5.000354 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 201 serial 48349 --------------------------------------------------- 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, 08:13:24 - 08:15:24] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-16 08:15:24.087 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 11292, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-16 05:49:12.854413 :B2A257BD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 05:49:13.306686 :B2A257F9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 05:49:13.302 2025-07-16 05:49:13.306688 :B2A257FA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15576 pso_num=25 pso_serial#=11 2025-07-16 05:49:13.306828 :B2A257FB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15576 osp_idx=23 osp_ver=199533 osp_pg=0 (spawn #390255) 2025-07-16 05:49:13.306828 :B2A257FC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 05:49:13.306829 :B2A257FD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=40 time=1325729204 2025-07-16 05:49:13.306829 :B2A257FE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 05:49:13.306829 :B2A257FF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 05:49:13.306830 :B2A25800:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 05:49:13.306830 :B2A25801:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 05:49:13.306830 :B2A25802:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 05:49:13.307323 :B2A25803: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-07-16 05:49:13.307367 :B2A2580B: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-07-16 05:59:14.358016 :B2A31D04:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 05:59:14.612749 :B2A31D39:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 05:59:14.608 2025-07-16 05:59:14.612751 :B2A31D3A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17748 pso_num=25 pso_serial#=12 2025-07-16 05:59:14.612898 :B2A31D3B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17748 osp_idx=23 osp_ver=199534 osp_pg=0 (spawn #390278) 2025-07-16 05:59:14.612898 :B2A31D3C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 05:59:14.612899 :B2A31D3D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=41 time=1326330501 2025-07-16 05:59:14.612899 :B2A31D3E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 05:59:14.612899 :B2A31D3F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 05:59:14.612900 :B2A31D40:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 05:59:14.612900 :B2A31D41:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 05:59:14.612900 :B2A31D42:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 05:59:14.613394 :B2A31D43: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-07-16 05:59:14.613437 :B2A31D4B: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-07-16 06:09:15.509940 :B2A3ECFA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 06:09:15.827294 :B2A3ED35:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 06:09:15.823 2025-07-16 06:09:15.827295 :B2A3ED36:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17008 pso_num=25 pso_serial#=13 2025-07-16 06:09:15.827437 :B2A3ED37:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17008 osp_idx=23 osp_ver=199535 osp_pg=0 (spawn #390304) 2025-07-16 06:09:15.827437 :B2A3ED38:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 06:09:15.827438 :B2A3ED39:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=42 time=1326931720 2025-07-16 06:09:15.827438 :B2A3ED3A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 06:09:15.827439 :B2A3ED3B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 06:09:15.827439 :B2A3ED3C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 06:09:15.827439 :B2A3ED3D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 06:09:15.827440 :B2A3ED3E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 06:09:15.827943 :B2A3ED3F: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-07-16 06:09:15.827989 :B2A3ED47: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-07-16 06:19:16.812576 :B2A4B1E7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 06:19:17.067594 :B2A4B225:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 06:19:17.062 2025-07-16 06:19:17.067597 :B2A4B226:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13440 pso_num=25 pso_serial#=14 2025-07-16 06:19:17.067745 :B2A4B227:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13440 osp_idx=23 osp_ver=199536 osp_pg=0 (spawn #390326) 2025-07-16 06:19:17.067746 :B2A4B228:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 06:19:17.067746 :B2A4B229:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=43 time=1327532954 2025-07-16 06:19:17.067747 :B2A4B22A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 06:19:17.067747 :B2A4B22B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 06:19:17.067748 :B2A4B22C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 06:19:17.067748 :B2A4B22D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 06:19:17.067749 :B2A4B22E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 06:19:17.068242 :B2A4B22F: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-07-16 06:19:17.068290 :B2A4B236: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-07-16 06:29:17.971068 :B2A576E8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 06:29:18.288229 :B2A5771D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 06:29:18.283 2025-07-16 06:29:18.288231 :B2A5771E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12508 pso_num=25 pso_serial#=15 2025-07-16 06:29:18.288366 :B2A5771F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12508 osp_idx=23 osp_ver=199537 osp_pg=0 (spawn #390349) 2025-07-16 06:29:18.288367 :B2A57720:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 06:29:18.288367 :B2A57721:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=44 time=1328134189 2025-07-16 06:29:18.288367 :B2A57722:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 06:29:18.288368 :B2A57723:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 06:29:18.288368 :B2A57724:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 06:29:18.288368 :B2A57725:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 06:29:18.288369 :B2A57726:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 06:29:18.288860 :B2A57727: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-07-16 06:29:18.288905 :B2A5772F: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-07-16 06:39:19.237484 :B2A63C8E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 06:39:19.554699 :B2A63CC9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 06:39:19.549 2025-07-16 06:39:19.554701 :B2A63CCA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7532 pso_num=25 pso_serial#=16 2025-07-16 06:39:19.554840 :B2A63CCB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7532 osp_idx=23 osp_ver=199538 osp_pg=0 (spawn #390372) 2025-07-16 06:39:19.554841 :B2A63CCC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 06:39:19.554841 :B2A63CCD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=45 time=1328735454 2025-07-16 06:39:19.554842 :B2A63CCE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 06:39:19.554842 :B2A63CCF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 06:39:19.554842 :B2A63CD0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 06:39:19.554843 :B2A63CD1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 06:39:19.554843 :B2A63CD2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 06:39:19.555336 :B2A63CD3: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-07-16 06:39:19.555384 :B2A63CDB: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-07-16 06:49:20.456591 :B2A70124:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 06:49:20.885320 :B2A7015C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 06:49:20.880 2025-07-16 06:49:20.885323 :B2A7015D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=5964 pso_num=25 pso_serial#=17 2025-07-16 06:49:20.885459 :B2A7015E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=5964 osp_idx=23 osp_ver=199539 osp_pg=0 (spawn #390394) 2025-07-16 06:49:20.885461 :B2A7015F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 06:49:20.885461 :B2A70160:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=46 time=1329336783 2025-07-16 06:49:20.885462 :B2A70161:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 06:49:20.885462 :B2A70162:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 06:49:20.885462 :B2A70163:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 06:49:20.885463 :B2A70164:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 06:49:20.885463 :B2A70165:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 06:49:20.885946 :B2A70166: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-07-16 06:49:20.885991 :B2A7016F: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-07-16 06:59:21.837157 :B2A7C66C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 06:59:22.248435 :B2A7C6B5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 06:59:22.243 2025-07-16 06:59:22.248437 :B2A7C6B6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17836 pso_num=25 pso_serial#=18 2025-07-16 06:59:22.248578 :B2A7C6B7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17836 osp_idx=23 osp_ver=199540 osp_pg=0 (spawn #390417) 2025-07-16 06:59:22.248579 :B2A7C6B8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 06:59:22.248579 :B2A7C6B9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=47 time=1329938142 2025-07-16 06:59:22.248580 :B2A7C6BA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 06:59:22.248580 :B2A7C6BB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 06:59:22.248580 :B2A7C6BC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 06:59:22.248581 :B2A7C6BD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 06:59:22.248582 :B2A7C6BE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 06:59:22.249095 :B2A7C6BF: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-07-16 06:59:22.249144 :B2A7C6C5: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-07-16 07:09:23.126868 :B2A89379:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 07:09:23.584682 :B2A893BF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 07:09:23.579 2025-07-16 07:09:23.584684 :B2A893C0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6512 pso_num=25 pso_serial#=19 2025-07-16 07:09:23.584820 :B2A893C1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6512 osp_idx=23 osp_ver=199541 osp_pg=0 (spawn #390443) 2025-07-16 07:09:23.584821 :B2A893C2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 07:09:23.584821 :B2A893C3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=48 time=1330539486 2025-07-16 07:09:23.584822 :B2A893C4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 07:09:23.584822 :B2A893C5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 07:09:23.584823 :B2A893C6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 07:09:23.584823 :B2A893C7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 07:09:23.584823 :B2A893C8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 07:09:23.585318 :B2A893C9: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-07-16 07:09:23.585363 :B2A893D0: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-07-16 07:19:24.540335 :B2A95A0D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 07:19:24.982625 :B2A95A40:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 07:19:24.977 2025-07-16 07:19:24.982628 :B2A95A41:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17404 pso_num=25 pso_serial#=20 2025-07-16 07:19:24.982763 :B2A95A42:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17404 osp_idx=23 osp_ver=199542 osp_pg=0 (spawn #390465) 2025-07-16 07:19:24.982763 :B2A95A43:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 07:19:24.982764 :B2A95A44:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=49 time=1331140876 2025-07-16 07:19:24.982764 :B2A95A45:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 07:19:24.982764 :B2A95A46:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 07:19:24.982764 :B2A95A47:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 07:19:24.982765 :B2A95A48:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 07:19:24.982765 :B2A95A49:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 07:19:24.983265 :B2A95A4A: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-07-16 07:19:24.983314 :B2A95A53: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-07-16 07:29:25.884125 :B2AA1E29:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 07:29:26.420285 :B2AA1E75:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 07:29:26.415 2025-07-16 07:29:26.420287 :B2AA1E76:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16516 pso_num=25 pso_serial#=21 2025-07-16 07:29:26.420429 :B2AA1E77:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16516 osp_idx=23 osp_ver=199543 osp_pg=0 (spawn #390488) 2025-07-16 07:29:26.420430 :B2AA1E78:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 07:29:26.420431 :B2AA1E79:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=50 time=1331742314 2025-07-16 07:29:26.420431 :B2AA1E7A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 07:29:26.420431 :B2AA1E7B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 07:29:26.420432 :B2AA1E7C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 07:29:26.420432 :B2AA1E7D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 07:29:26.420432 :B2AA1E7E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 07:29:26.420945 :B2AA1E7F: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-07-16 07:29:26.420997 :B2AA1E86: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-07-16 07:31:38.980958 :B2AA49D7: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-07-16 07:39:27.778894 :B2AAE3AA: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-07-16 07:39:27.778930 :B2AAE3AB:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1 2025-07-16 07:39:27.778941 :B2AAE3B0:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1 2025-07-16 07:39:27.778942 :B2AAE3B1:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=11, exc=11, tot=11 2025-07-16 07:49:28.669525 :B2ABA961:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 07:49:29.158461 :B2ABA9A1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 07:49:29.153 2025-07-16 07:49:29.158463 :B2ABA9A2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16156 pso_num=25 pso_serial#=22 2025-07-16 07:49:29.158603 :B2ABA9A3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16156 osp_idx=23 osp_ver=199544 osp_pg=0 (spawn #390533) 2025-07-16 07:49:29.158603 :B2ABA9A4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 07:49:29.158604 :B2ABA9A5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=51 time=1332945048 2025-07-16 07:49:29.158604 :B2ABA9A6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 07:49:29.158604 :B2ABA9A7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 07:49:29.158605 :B2ABA9A8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 07:49:29.158605 :B2ABA9A9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 07:49:29.158605 :B2ABA9AA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 07:49:29.159096 :B2ABA9AB: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-07-16 07:49:29.159140 :B2ABA9B3: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-07-16 07:59:30.052667 :B2AC6E5E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 07:59:30.526042 :B2AC6E9F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 07:59:30.521 2025-07-16 07:59:30.526043 :B2AC6EA0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13572 pso_num=25 pso_serial#=23 2025-07-16 07:59:30.526184 :B2AC6EA1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13572 osp_idx=23 osp_ver=199545 osp_pg=0 (spawn #390556) 2025-07-16 07:59:30.526185 :B2AC6EA2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 07:59:30.526185 :B2AC6EA3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=52 time=1333546423 2025-07-16 07:59:30.526186 :B2AC6EA4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 07:59:30.526186 :B2AC6EA5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 07:59:30.526187 :B2AC6EA6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 07:59:30.526187 :B2AC6EA7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 07:59:30.526187 :B2AC6EA8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 07:59:30.526694 :B2AC6EA9: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-07-16 07:59:30.526738 :B2AC6EB0: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-07-16 08:09:31.346617 :B2AD3B47:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-16 08:09:31.788817 :B2AD3B80:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-16 08:09:31.784 2025-07-16 08:09:31.788819 :B2AD3B81:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11292 pso_num=25 pso_serial#=24 2025-07-16 08:09:31.788959 :B2AD3B82:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11292 osp_idx=23 osp_ver=199546 osp_pg=0 (spawn #390582) 2025-07-16 08:09:31.788960 :B2AD3B83:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 08:09:31.788960 :B2AD3B84:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=53 time=1334147689 2025-07-16 08:09:31.788961 :B2AD3B85:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 08:09:31.788961 :B2AD3B86:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 08:09:31.788961 :B2AD3B87:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 08:09:31.788962 :B2AD3B88:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 08:09:31.788962 :B2AD3B89:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 08:09:31.789500 :B2AD3B8A: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-07-16 08:09:31.789551 :B2AD3B92: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: 11292, W000)