Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250506220006\orcl_w000_10200_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:37457M/63366M, Ph+PgF:44928M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 10200, image: ORACLE.EXE (W000) *** 2025-05-06 22:00:06.740 *** SESSION ID:(177.46415) 2025-05-06 22:00:06.740 *** 2025-05-06 22:00:06.740 Process diagnostic dump for ORACLE.EXE (W000), OS id=10200, pid: 22, proc_ser: 60, sid: 177, sess_ser: 46415 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=91 seq_num=92 snap_id=1 wait times: snap=2.459018 sec, exc=2.459018 sec, total=2.459018 sec wait times: max=5.000000 sec, heur=7 min 33 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000024 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=90 seq_num=91 snap_id=1 wait times: snap=5.000295 sec, exc=5.000295 sec, total=5.000295 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 1: 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.004598 sec, exc=5.004598 sec, total=5.004598 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 2: 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.010336 sec, exc=5.010336 sec, total=5.010336 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=87 seq_num=88 snap_id=1 wait times: snap=5.001241 sec, exc=5.001241 sec, total=5.001241 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=86 seq_num=87 snap_id=1 wait times: snap=5.010854 sec, exc=5.010854 sec, total=5.010854 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=85 seq_num=86 snap_id=1 wait times: snap=5.006192 sec, exc=5.006192 sec, total=5.006192 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.001389 sec, exc=5.001389 sec, total=5.001389 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=83 seq_num=84 snap_id=1 wait times: snap=5.004609 sec, exc=5.004609 sec, total=5.004609 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=82 seq_num=83 snap_id=1 wait times: snap=5.014740 sec, exc=5.014740 sec, total=5.014740 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=81 seq_num=82 snap_id=1 wait times: snap=5.007234 sec, exc=5.007234 sec, total=5.007234 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 177 serial 46415 --------------------------------------------------- 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:06 - 22:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-06 22:00:06.740 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 10200, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-06 19:22:14.175761 :9394920F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 19:22:14.175761 :93949210:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 19:22:14.176265 :93949211:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 19:22:14.176309 :93949218:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 19:32:15.046931 :93955527:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 19:32:15.582803 :93955567:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 19:32:15.578 2025-05-06 19:32:15.582804 :93955568:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11976 pso_num=22 pso_serial#=47 2025-05-06 19:32:15.582940 :93955569:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11976 osp_idx=0 osp_ver=160952 osp_pg=0 (spawn #155161) 2025-05-06 19:32:15.582941 :9395556A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 19:32:15.582941 :9395556B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=241 time=3830712750 2025-05-06 19:32:15.582942 :9395556C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 19:32:15.582942 :9395556D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 19:32:15.582943 :9395556E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 19:32:15.582943 :9395556F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 19:32:15.582943 :93955570:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 19:32:15.583476 :93955571:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 19:32:15.583520 :93955578:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 19:42:16.498462 :93961714:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 19:42:16.956324 :9396174B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 19:42:16.951 2025-05-06 19:42:16.956326 :9396174C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10028 pso_num=22 pso_serial#=48 2025-05-06 19:42:16.956473 :9396174D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10028 osp_idx=0 osp_ver=160953 osp_pg=0 (spawn #155184) 2025-05-06 19:42:16.956474 :9396174E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 19:42:16.956474 :9396174F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=242 time=3831314125 2025-05-06 19:42:16.956475 :93961750:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 19:42:16.956475 :93961751:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 19:42:16.956475 :93961752:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 19:42:16.956476 :93961753:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 19:42:16.956476 :93961754:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 19:42:16.956980 :93961755:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 19:42:16.957024 :9396175D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 19:52:17.845322 :9396D84D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 19:52:18.287521 :9396D89E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 19:52:18.283 2025-05-06 19:52:18.287523 :9396D89F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11908 pso_num=22 pso_serial#=49 2025-05-06 19:52:18.287675 :9396D8A0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11908 osp_idx=0 osp_ver=160954 osp_pg=0 (spawn #155206) 2025-05-06 19:52:18.287676 :9396D8A1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 19:52:18.287677 :9396D8A2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=243 time=3831915453 2025-05-06 19:52:18.287677 :9396D8A3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 19:52:18.287677 :9396D8A4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 19:52:18.287678 :9396D8A5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 19:52:18.287678 :9396D8A6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 19:52:18.287679 :9396D8A7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 19:52:18.288226 :9396D8A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 19:52:18.288276 :9396D8B0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 20:02:19.165583 :93979BF2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 20:02:19.529769 :93979C2E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 20:02:19.525 2025-05-06 20:02:19.529771 :93979C2F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11512 pso_num=22 pso_serial#=50 2025-05-06 20:02:19.529916 :93979C30:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11512 osp_idx=0 osp_ver=160955 osp_pg=0 (spawn #155231) 2025-05-06 20:02:19.529917 :93979C31:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 20:02:19.529917 :93979C32:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=244 time=3832516703 2025-05-06 20:02:19.529918 :93979C33:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 20:02:19.529918 :93979C34:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 20:02:19.529919 :93979C35:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 20:02:19.529919 :93979C36:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 20:02:19.529920 :93979C37:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 20:02:19.530437 :93979C38:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 20:02:19.530485 :93979C40:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 20:10:43.722884 :9398474B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-06 20:12:20.890863 :939866D8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-06 20:22:21.788113 :9399278F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 20:22:22.177564 :939927C4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 20:22:22.173 2025-05-06 20:22:22.177566 :939927C5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11852 pso_num=22 pso_serial#=51 2025-05-06 20:22:22.177703 :939927C6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11852 osp_idx=0 osp_ver=160956 osp_pg=0 (spawn #155276) 2025-05-06 20:22:22.177705 :939927C7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 20:22:22.177706 :939927C8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=245 time=3833719343 2025-05-06 20:22:22.177706 :939927C9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 20:22:22.177706 :939927CA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 20:22:22.177707 :939927CB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 20:22:22.177707 :939927CC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 20:22:22.177708 :939927CD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 20:22:22.178189 :939927CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 20:22:22.178237 :939927D6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 20:32:23.026679 :9399EA7C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 20:32:23.468776 :9399EAC0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 20:32:23.464 2025-05-06 20:32:23.468779 :9399EAC1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11364 pso_num=22 pso_serial#=52 2025-05-06 20:32:23.468916 :9399EAC2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11364 osp_idx=0 osp_ver=160957 osp_pg=0 (spawn #155301) 2025-05-06 20:32:23.468917 :9399EAC3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 20:32:23.468918 :9399EAC4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=246 time=3834320640 2025-05-06 20:32:23.468918 :9399EAC5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 20:32:23.468918 :9399EAC6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 20:32:23.468919 :9399EAC7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 20:32:23.468919 :9399EAC8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 20:32:23.468919 :9399EAC9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 20:32:23.469403 :9399EACA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 20:32:23.469447 :9399EAD2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 20:42:24.315490 :939AAE65:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 20:42:24.663971 :939AAE9B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 20:42:24.659 2025-05-06 20:42:24.663973 :939AAE9C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9576 pso_num=22 pso_serial#=53 2025-05-06 20:42:24.664114 :939AAE9D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9576 osp_idx=0 osp_ver=160958 osp_pg=0 (spawn #155324) 2025-05-06 20:42:24.664115 :939AAE9E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 20:42:24.664115 :939AAE9F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=247 time=3834921828 2025-05-06 20:42:24.664116 :939AAEA0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 20:42:24.664116 :939AAEA1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 20:42:24.664117 :939AAEA2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 20:42:24.664117 :939AAEA3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 20:42:24.664117 :939AAEA4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 20:42:24.664604 :939AAEA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 20:42:24.664649 :939AAEAD:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 20:52:25.623591 :939B6FCF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 20:52:25.956320 :939B7012:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 20:52:25.952 2025-05-06 20:52:25.956322 :939B7013:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8784 pso_num=22 pso_serial#=54 2025-05-06 20:52:25.956459 :939B7014:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8784 osp_idx=0 osp_ver=160959 osp_pg=0 (spawn #155346) 2025-05-06 20:52:25.956459 :939B7015:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 20:52:25.956460 :939B7016:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=248 time=3835523125 2025-05-06 20:52:25.956460 :939B7017:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 20:52:25.956460 :939B7018:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 20:52:25.956461 :939B7019:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 20:52:25.956463 :939B701A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 20:52:25.956463 :939B701B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 20:52:25.956995 :939B701C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 20:52:25.957041 :939B7025:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 21:02:26.914880 :939C3377:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 21:02:27.232054 :939C33AF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 21:02:27.227 2025-05-06 21:02:27.232056 :939C33B0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10712 pso_num=22 pso_serial#=55 2025-05-06 21:02:27.232190 :939C33B1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10712 osp_idx=0 osp_ver=160960 osp_pg=0 (spawn #155371) 2025-05-06 21:02:27.232191 :939C33B2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 21:02:27.232191 :939C33B3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=249 time=3836124406 2025-05-06 21:02:27.232192 :939C33B4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 21:02:27.232192 :939C33B5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 21:02:27.232192 :939C33B6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 21:02:27.232193 :939C33B7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 21:02:27.232193 :939C33B8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 21:02:27.232689 :939C33B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 21:02:27.232737 :939C33C1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 21:12:28.062066 :939CFADF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 21:12:28.410380 :939CFB3D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 21:12:28.405 2025-05-06 21:12:28.410382 :939CFB3E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5976 pso_num=22 pso_serial#=56 2025-05-06 21:12:28.410513 :939CFB3F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=5976 osp_idx=0 osp_ver=160961 osp_pg=0 (spawn #155395) 2025-05-06 21:12:28.410514 :939CFB40:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 21:12:28.410514 :939CFB41:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=250 time=3836725578 2025-05-06 21:12:28.410515 :939CFB42:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 21:12:28.410515 :939CFB43:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 21:12:28.410515 :939CFB44:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 21:12:28.410516 :939CFB45:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 21:12:28.410516 :939CFB46:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 21:12:28.411081 :939CFB47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 21:12:28.411125 :939CFB4E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 21:22:29.187225 :939DBFB2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 21:22:29.655289 :939DC01A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 21:22:29.656 2025-05-06 21:22:29.655292 :939DC01B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11492 pso_num=22 pso_serial#=57 2025-05-06 21:22:29.655436 :939DC01C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11492 osp_idx=0 osp_ver=160962 osp_pg=0 (spawn #155419) 2025-05-06 21:22:29.655437 :939DC01D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 21:22:29.655437 :939DC01E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=251 time=3837326828 2025-05-06 21:22:29.655438 :939DC01F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 21:22:29.655438 :939DC020:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 21:22:29.655439 :939DC021:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 21:22:29.655439 :939DC022:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 21:22:29.655440 :939DC023:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 21:22:29.656018 :939DC024:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 21:22:29.656064 :939DC02C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 21:32:30.650286 :939E815F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 21:32:30.873706 :939E8192:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 21:32:30.869 2025-05-06 21:32:30.873708 :939E8193:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8780 pso_num=22 pso_serial#=58 2025-05-06 21:32:30.873850 :939E8194:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8780 osp_idx=0 osp_ver=160963 osp_pg=0 (spawn #155442) 2025-05-06 21:32:30.873851 :939E8195:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 21:32:30.873851 :939E8196:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=252 time=3837928046 2025-05-06 21:32:30.873852 :939E8197:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 21:32:30.873852 :939E8198:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 21:32:30.873853 :939E8199:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 21:32:30.873853 :939E819A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 21:32:30.873853 :939E819B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 21:32:30.874353 :939E819C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 21:32:30.874413 :939E81A3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 21:42:31.869550 :939F43AA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 21:42:32.280396 :939F43EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 21:42:32.276 2025-05-06 21:42:32.280398 :939F43ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4696 pso_num=22 pso_serial#=59 2025-05-06 21:42:32.280535 :939F43EE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4696 osp_idx=0 osp_ver=160964 osp_pg=0 (spawn #155465) 2025-05-06 21:42:32.280535 :939F43EF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 21:42:32.280536 :939F43F0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=253 time=3838529453 2025-05-06 21:42:32.280536 :939F43F1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 21:42:32.280536 :939F43F2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 21:42:32.280537 :939F43F3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 21:42:32.280537 :939F43F4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 21:42:32.280537 :939F43F5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 21:42:32.281045 :939F43F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 21:42:32.281090 :939F43FF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 21:52:33.267747 :93A0057F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-06 21:52:33.569167 :93A005B6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-06 21:52:33.565 2025-05-06 21:52:33.569171 :93A005B7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10200 pso_num=22 pso_serial#=60 2025-05-06 21:52:33.569313 :93A005B8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10200 osp_idx=0 osp_ver=160965 osp_pg=0 (spawn #155487) 2025-05-06 21:52:33.569314 :93A005B9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 21:52:33.569314 :93A005BA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=254 time=3839130734 2025-05-06 21:52:33.569315 :93A005BB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 21:52:33.569315 :93A005BC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 21:52:33.569316 :93A005BD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 21:52:33.569316 :93A005BE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 21:52:33.569316 :93A005BF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 21:52:33.569803 :93A005C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 21:52:33.569849 :93A005C8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 10200, W000)