Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250604220005\orcl_w000_15396_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:36947M/63366M, Ph+PgF:43664M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15396, image: ORACLE.EXE (W000) *** 2025-06-04 22:00:05.566 *** SESSION ID:(177.39543) 2025-06-04 22:00:05.566 *** 2025-06-04 22:00:05.566 Process diagnostic dump for ORACLE.EXE (W000), OS id=15396, pid: 22, proc_ser: 231, sid: 177, sess_ser: 39543 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=59 seq_num=60 snap_id=1 wait times: snap=2.916420 sec, exc=2.916420 sec, total=2.916420 sec wait times: max=5.000000 sec, heur=4 min 53 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.000019 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=58 seq_num=59 snap_id=1 wait times: snap=5.004629 sec, exc=5.004629 sec, total=5.004629 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=5.008314 sec, exc=5.008314 sec, total=5.008314 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=5.000362 sec, exc=5.000362 sec, total=5.000362 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=5.000063 sec, exc=5.000063 sec, total=5.000063 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=5.010159 sec, exc=5.010159 sec, total=5.010159 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=5.009213 sec, exc=5.009213 sec, total=5.009213 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=5.015250 sec, exc=5.015250 sec, total=5.015250 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=51 seq_num=52 snap_id=1 wait times: snap=5.003226 sec, exc=5.003226 sec, total=5.003226 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=50 seq_num=51 snap_id=1 wait times: snap=5.000267 sec, exc=5.000267 sec, total=5.000267 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=49 seq_num=50 snap_id=1 wait times: snap=5.008976 sec, exc=5.008976 sec, total=5.008976 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time Sampled Session History of session 177 serial 39543 --------------------------------------------------- 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:05 - 22:00:05] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-04 22:00:05.566 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15396, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-04 19:24:55.231342 :A050E7D0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 19:24:55.231343 :A050E7D1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 19:24:55.231838 :A050E7D2: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-06-04 19:24:55.231883 :A050E7DA: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-06-04 19:34:56.129670 :A051AA51:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 19:34:56.446614 :A051AADF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 19:34:56.442 2025-06-04 19:34:56.446616 :A051AAE0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11580 pso_num=22 pso_serial#=218 2025-06-04 19:34:56.446761 :A051AAE1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11580 osp_idx=0 osp_ver=263705 osp_pg=0 (spawn #252151) 2025-06-04 19:34:56.446761 :A051AAE2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 19:34:56.446762 :A051AAE3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=17 time=2041455094 2025-06-04 19:34:56.446762 :A051AAE4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 19:34:56.446763 :A051AAE5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 19:34:56.446763 :A051AAE6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 19:34:56.446763 :A051AAE7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 19:34:56.446765 :A051AAE8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 19:34:56.447280 :A051AAE9: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-06-04 19:34:56.447331 :A051AAEF: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-06-04 19:44:57.238923 :A0526BF6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 19:44:57.689687 :A0526C89:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 19:44:57.685 2025-06-04 19:44:57.689690 :A0526C8A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11536 pso_num=22 pso_serial#=219 2025-06-04 19:44:57.689833 :A0526C8B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11536 osp_idx=0 osp_ver=263706 osp_pg=0 (spawn #252173) 2025-06-04 19:44:57.689833 :A0526C8C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 19:44:57.689833 :A0526C8D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=18 time=2042056344 2025-06-04 19:44:57.689834 :A0526C8E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 19:44:57.689834 :A0526C8F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 19:44:57.689834 :A0526C90:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 19:44:57.689835 :A0526C91:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 19:44:57.689835 :A0526C92:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 19:44:57.690339 :A0526C93: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-06-04 19:44:57.690383 :A0526C9A: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-06-04 19:54:58.397820 :A0532DB9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 19:54:58.776904 :A0532E26:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 19:54:58.772 2025-06-04 19:54:58.776906 :A0532E27:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15948 pso_num=22 pso_serial#=220 2025-06-04 19:54:58.777054 :A0532E28:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15948 osp_idx=0 osp_ver=263707 osp_pg=0 (spawn #252196) 2025-06-04 19:54:58.777055 :A0532E29:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 19:54:58.777056 :A0532E2A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=19 time=2042657422 2025-06-04 19:54:58.777056 :A0532E2B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 19:54:58.777056 :A0532E2C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 19:54:58.777057 :A0532E2D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 19:54:58.777057 :A0532E2E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 19:54:58.777058 :A0532E2F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 19:54:58.777572 :A0532E30: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-06-04 19:54:58.777616 :A0532E39: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-06-04 20:04:59.574139 :A053F260:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 20:04:59.906871 :A053F2D0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 20:04:59.901 2025-06-04 20:04:59.906873 :A053F2D1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15832 pso_num=22 pso_serial#=221 2025-06-04 20:04:59.907010 :A053F2D2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15832 osp_idx=0 osp_ver=263708 osp_pg=0 (spawn #252224) 2025-06-04 20:04:59.907011 :A053F2D3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 20:04:59.907012 :A053F2D4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=20 time=2043258547 2025-06-04 20:04:59.907012 :A053F2D5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 20:04:59.907013 :A053F2D6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 20:04:59.907013 :A053F2D7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 20:04:59.907013 :A053F2D8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 20:04:59.907014 :A053F2D9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 20:04:59.907504 :A053F2DA: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-06-04 20:04:59.907551 :A053F2E2: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-06-04 20:15:00.838940 :A054BC7E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 20:15:01.061771 :A054BCEA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 20:15:01.057 2025-06-04 20:15:01.061773 :A054BCEB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12024 pso_num=22 pso_serial#=222 2025-06-04 20:15:01.061913 :A054BCEC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12024 osp_idx=0 osp_ver=263709 osp_pg=0 (spawn #252244) 2025-06-04 20:15:01.061913 :A054BCED:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 20:15:01.061914 :A054BCEE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=21 time=2043859704 2025-06-04 20:15:01.061914 :A054BCEF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 20:15:01.061914 :A054BCF0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 20:15:01.061915 :A054BCF1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 20:15:01.061915 :A054BCF2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 20:15:01.061915 :A054BCF3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 20:15:01.062412 :A054BCF4: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-06-04 20:15:01.062460 :A054BCFC: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-06-04 20:25:01.897724 :A0557E13:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 20:25:02.230487 :A0557E6E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 20:25:02.225 2025-06-04 20:25:02.230489 :A0557E6F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16008 pso_num=22 pso_serial#=223 2025-06-04 20:25:02.230637 :A0557E70:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16008 osp_idx=0 osp_ver=263710 osp_pg=0 (spawn #252267) 2025-06-04 20:25:02.230638 :A0557E71:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 20:25:02.230639 :A0557E72:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=22 time=2044460875 2025-06-04 20:25:02.230639 :A0557E73:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 20:25:02.230640 :A0557E74:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 20:25:02.230640 :A0557E75:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 20:25:02.230640 :A0557E76:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 20:25:02.230641 :A0557E77:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 20:25:02.231138 :A0557E78: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-06-04 20:25:02.231184 :A0557E80: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-06-04 20:35:03.143826 :A0563FAF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 20:35:03.399443 :A056400C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 20:35:03.394 2025-06-04 20:35:03.399445 :A056400D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14596 pso_num=22 pso_serial#=224 2025-06-04 20:35:03.399580 :A056400E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14596 osp_idx=0 osp_ver=263711 osp_pg=0 (spawn #252290) 2025-06-04 20:35:03.399581 :A056400F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 20:35:03.399581 :A0564010:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=23 time=2045062047 2025-06-04 20:35:03.399581 :A0564011:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 20:35:03.399582 :A0564012:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 20:35:03.399582 :A0564013:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 20:35:03.399582 :A0564014:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 20:35:03.399582 :A0564015:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 20:35:03.400083 :A0564016: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-06-04 20:35:03.400128 :A056401E: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-06-04 20:45:04.204348 :A0570176:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 20:45:04.468896 :A05701D1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 20:45:04.464 2025-06-04 20:45:04.468898 :A05701D2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12720 pso_num=22 pso_serial#=225 2025-06-04 20:45:04.469031 :A05701D3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12720 osp_idx=0 osp_ver=263712 osp_pg=0 (spawn #252312) 2025-06-04 20:45:04.469032 :A05701D4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 20:45:04.469032 :A05701D5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=24 time=2045663110 2025-06-04 20:45:04.469032 :A05701D6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 20:45:04.469033 :A05701D7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 20:45:04.469033 :A05701D8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 20:45:04.469033 :A05701D9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 20:45:04.469034 :A05701DA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 20:45:04.469529 :A05701DB: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-06-04 20:45:04.469577 :A05701E2: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-06-04 20:55:05.319799 :A057C349:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 20:55:05.558766 :A057C3AA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 20:55:05.554 2025-06-04 20:55:05.558768 :A057C3AB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12612 pso_num=22 pso_serial#=226 2025-06-04 20:55:05.558953 :A057C3AC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12612 osp_idx=0 osp_ver=263713 osp_pg=0 (spawn #252335) 2025-06-04 20:55:05.558954 :A057C3AD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 20:55:05.558955 :A057C3AE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=25 time=2046264204 2025-06-04 20:55:05.558955 :A057C3AF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 20:55:05.558955 :A057C3B0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 20:55:05.558956 :A057C3B1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 20:55:05.558956 :A057C3B2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 20:55:05.558956 :A057C3B3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 20:55:05.559455 :A057C3B4: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-06-04 20:55:05.559509 :A057C3BB: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-06-04 20:55:56.033559 :A057D40F: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-06-04 21:05:06.712766 :A05887CC: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-06-04 21:15:07.656153 :A0594EE2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 21:15:07.848211 :A0594F3A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 21:15:07.843 2025-06-04 21:15:07.848214 :A0594F3B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8352 pso_num=22 pso_serial#=227 2025-06-04 21:15:07.848350 :A0594F3C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8352 osp_idx=0 osp_ver=263714 osp_pg=0 (spawn #252382) 2025-06-04 21:15:07.848351 :A0594F3D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 21:15:07.848351 :A0594F3E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=26 time=2047466500 2025-06-04 21:15:07.848351 :A0594F3F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 21:15:07.848352 :A0594F40:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 21:15:07.848352 :A0594F41:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 21:15:07.848352 :A0594F42:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 21:15:07.848353 :A0594F43:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 21:15:07.848841 :A0594F44: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-06-04 21:15:07.848883 :A0594F4D: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-06-04 21:25:08.706210 :A05A1361:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 21:25:08.977167 :A05A13B7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 21:25:08.972 2025-06-04 21:25:08.977170 :A05A13B8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11976 pso_num=22 pso_serial#=228 2025-06-04 21:25:08.977308 :A05A13B9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11976 osp_idx=0 osp_ver=263715 osp_pg=0 (spawn #252405) 2025-06-04 21:25:08.977309 :A05A13BA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 21:25:08.977309 :A05A13BB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=27 time=2048067625 2025-06-04 21:25:08.977309 :A05A13BC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 21:25:08.977310 :A05A13BD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 21:25:08.977310 :A05A13BE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 21:25:08.977310 :A05A13BF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 21:25:08.977311 :A05A13C0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 21:25:08.977823 :A05A13C1: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-06-04 21:25:08.977866 :A05A13C9: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-06-04 21:35:09.748350 :A05AD48E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 21:35:10.035214 :A05AD4E9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 21:35:10.030 2025-06-04 21:35:10.035216 :A05AD4EA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14712 pso_num=22 pso_serial#=229 2025-06-04 21:35:10.035352 :A05AD4EB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14712 osp_idx=0 osp_ver=263716 osp_pg=0 (spawn #252428) 2025-06-04 21:35:10.035352 :A05AD4EC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 21:35:10.035353 :A05AD4ED:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=28 time=2048668688 2025-06-04 21:35:10.035353 :A05AD4EE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 21:35:10.035353 :A05AD4EF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 21:35:10.035354 :A05AD4F0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 21:35:10.035354 :A05AD4F1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 21:35:10.035354 :A05AD4F2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 21:35:10.035857 :A05AD4F3: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-06-04 21:35:10.035903 :A05AD4FB: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-06-04 21:45:10.652693 :A05B96FF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 21:45:11.032336 :A05B9764:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 21:45:11.027 2025-06-04 21:45:11.032338 :A05B9765:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15568 pso_num=22 pso_serial#=230 2025-06-04 21:45:11.032474 :A05B9766:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15568 osp_idx=0 osp_ver=263717 osp_pg=0 (spawn #252450) 2025-06-04 21:45:11.032474 :A05B9767:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 21:45:11.032474 :A05B9768:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=29 time=2049269672 2025-06-04 21:45:11.032475 :A05B9769:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 21:45:11.032475 :A05B976A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 21:45:11.032475 :A05B976B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 21:45:11.032476 :A05B976C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 21:45:11.032476 :A05B976D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 21:45:11.032972 :A05B976E: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-06-04 21:45:11.033020 :A05B9776: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-06-04 21:55:11.845959 :A05C5875:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-04 21:55:12.273236 :A05C58CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-04 21:55:12.268 2025-06-04 21:55:12.273238 :A05C58CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15396 pso_num=22 pso_serial#=231 2025-06-04 21:55:12.273380 :A05C58CC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15396 osp_idx=0 osp_ver=263718 osp_pg=0 (spawn #252473) 2025-06-04 21:55:12.273380 :A05C58CD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 21:55:12.273381 :A05C58CE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=30 time=2049870922 2025-06-04 21:55:12.273381 :A05C58CF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 21:55:12.273381 :A05C58D0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 21:55:12.273382 :A05C58D1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 21:55:12.273382 :A05C58D2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 21:55:12.273382 :A05C58D3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 21:55:12.273886 :A05C58D4: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-06-04 21:55:12.273932 :A05C58DC: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: 15396, W000)