Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250611222406\orcl_j000_16896_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:36758M/63366M, Ph+PgF:43291M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 16896, image: ORACLE.EXE (J000) *** 2025-06-11 22:24:06.709 *** SESSION ID:(209.9607) 2025-06-11 22:24:06.709 *** 2025-06-11 22:24:06.709 Process diagnostic dump for ORACLE.EXE (J000), OS id=16896, pid: 26, proc_ser: 207, sid: 209, sess_ser: 9607 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16896 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.169535 sec, exc=0.169535 sec, total=0.169535 sec wait times: max=0.500000 sec, heur=4.161857 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500134 sec, exc=0.500134 sec, total=0.500134 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.492138 sec, exc=0.492138 sec, total=0.492138 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 209 serial 9607 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:24:02 - 22:24:06] idle wait at each sample [session created at: 22:24:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-11 22:24:06.709 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 16896, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-11 22:10:01.434417 :A36E0FFA:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:10:01.434417 :A36E0FFB:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:10:01.434909 :A36E0FFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:10:31.486503 :A36E19B1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:11:01.513613 :A36E236A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:11:01.501 2025-06-11 22:11:01.513615 :A36E236B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16664 pso_num=26 pso_serial#=194 2025-06-11 22:11:01.513744 :A36E236C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16664 osp_idx=24 osp_ver=429359 osp_pg=0 (spawn #275843) 2025-06-11 22:11:01.513744 :A36E236D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:11:01.513745 :A36E236E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=92 time=2655604704 2025-06-11 22:11:01.513745 :A36E236F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:11:01.513746 :A36E2370:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:11:01.513746 :A36E2371:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:11:01.513746 :A36E2372:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:11:01.513747 :A36E2373:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:11:01.514238 :A36E2374:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:11:31.581998 :A36E2D03:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:12:01.598542 :A36E3AB1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:12:01.588 2025-06-11 22:12:01.598544 :A36E3AB2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17004 pso_num=26 pso_serial#=195 2025-06-11 22:12:01.598677 :A36E3AB3:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17004 osp_idx=24 osp_ver=429360 osp_pg=0 (spawn #275845) 2025-06-11 22:12:01.598677 :A36E3AB4:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:12:01.598678 :A36E3AB5:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=93 time=2655664782 2025-06-11 22:12:01.598678 :A36E3AB6:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:12:01.598679 :A36E3AB7:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:12:01.598679 :A36E3AB8:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:12:01.598679 :A36E3AB9:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:12:01.598680 :A36E3ABA:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:12:01.599143 :A36E3ABB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:12:26.628591 :A36E43A2:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-11 22:12:56.713357 :A36E4D71:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:13:01.698602 :A36E4F4A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:13:01.686 2025-06-11 22:13:01.698604 :A36E4F4B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16004 pso_num=26 pso_serial#=196 2025-06-11 22:13:01.698731 :A36E4F4C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16004 osp_idx=24 osp_ver=429361 osp_pg=0 (spawn #275847) 2025-06-11 22:13:01.698732 :A36E4F4D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:13:01.698732 :A36E4F4E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=94 time=2655724875 2025-06-11 22:13:01.698732 :A36E4F4F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:13:01.698733 :A36E4F50:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:13:01.698733 :A36E4F51:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:13:01.698733 :A36E4F52:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:13:01.698734 :A36E4F53:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:13:01.699238 :A36E4F54:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:13:31.791671 :A36E5912:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:14:01.754393 :A36E62F3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:14:01.743 2025-06-11 22:14:01.754395 :A36E62F4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17168 pso_num=26 pso_serial#=197 2025-06-11 22:14:01.754523 :A36E62F5:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17168 osp_idx=24 osp_ver=429362 osp_pg=0 (spawn #275849) 2025-06-11 22:14:01.754524 :A36E62F6:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:14:01.754524 :A36E62F7:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=95 time=2655784938 2025-06-11 22:14:01.754525 :A36E62F8:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:14:01.754525 :A36E62F9:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:14:01.754526 :A36E62FA:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:14:01.754526 :A36E62FB:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:14:01.754526 :A36E62FC:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:14:01.754993 :A36E62FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:14:31.823255 :A36E6C65:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:15:01.837970 :A36E7618:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:15:01.826 2025-06-11 22:15:01.837973 :A36E7619:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15892 pso_num=26 pso_serial#=198 2025-06-11 22:15:01.838111 :A36E761A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15892 osp_idx=24 osp_ver=429363 osp_pg=0 (spawn #275851) 2025-06-11 22:15:01.838112 :A36E761B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:15:01.838113 :A36E761C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=96 time=2655845016 2025-06-11 22:15:01.838113 :A36E761D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:15:01.838114 :A36E761E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:15:01.838114 :A36E761F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:15:01.838114 :A36E7620:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:15:01.838115 :A36E7621:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:15:01.838598 :A36E7622:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:15:31.915608 :A36E805A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:16:01.937190 :A36E8A48:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:16:01.926 2025-06-11 22:16:01.937192 :A36E8A49:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14444 pso_num=26 pso_serial#=199 2025-06-11 22:16:01.937337 :A36E8A4A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14444 osp_idx=24 osp_ver=429364 osp_pg=0 (spawn #275854) 2025-06-11 22:16:01.937337 :A36E8A4B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:16:01.937337 :A36E8A4C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=97 time=2655905125 2025-06-11 22:16:01.937338 :A36E8A4D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:16:01.937338 :A36E8A4E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:16:01.937338 :A36E8A4F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:16:01.937339 :A36E8A50:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:16:01.937339 :A36E8A51:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:16:01.937836 :A36E8A52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:16:32.041861 :A36E9415:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:17:02.009151 :A36E9E27:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:17:01.998 2025-06-11 22:17:02.009153 :A36E9E28:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15624 pso_num=26 pso_serial#=200 2025-06-11 22:17:02.009291 :A36E9E29:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15624 osp_idx=24 osp_ver=429365 osp_pg=0 (spawn #275856) 2025-06-11 22:17:02.009292 :A36E9E2A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:17:02.009292 :A36E9E2B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=98 time=2655965188 2025-06-11 22:17:02.009292 :A36E9E2C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:17:02.009293 :A36E9E2D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:17:02.009293 :A36E9E2E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:17:02.009293 :A36E9E2F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:17:02.009294 :A36E9E30:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:17:02.009783 :A36E9E31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:17:32.082016 :A36EA82D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:18:02.078442 :A36EB22C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:18:02.067 2025-06-11 22:18:02.078445 :A36EB22D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13096 pso_num=26 pso_serial#=201 2025-06-11 22:18:02.078575 :A36EB22E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13096 osp_idx=24 osp_ver=429366 osp_pg=0 (spawn #275859) 2025-06-11 22:18:02.078576 :A36EB22F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:18:02.078576 :A36EB230:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=99 time=2656025266 2025-06-11 22:18:02.078577 :A36EB231:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:18:02.078577 :A36EB232:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:18:02.078577 :A36EB233:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:18:02.078578 :A36EB234:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:18:02.078578 :A36EB235:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:18:02.079047 :A36EB236:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:18:32.127129 :A36EBBE2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:19:02.150499 :A36EC5AB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:19:02.139 2025-06-11 22:19:02.150501 :A36EC5AC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16520 pso_num=26 pso_serial#=202 2025-06-11 22:19:02.150638 :A36EC5AD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16520 osp_idx=24 osp_ver=429367 osp_pg=0 (spawn #275861) 2025-06-11 22:19:02.150638 :A36EC5AE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:19:02.150639 :A36EC5AF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=100 time=2656085329 2025-06-11 22:19:02.150639 :A36EC5B0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:19:02.150640 :A36EC5B1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:19:02.150640 :A36EC5B2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:19:02.150640 :A36EC5B3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:19:02.150641 :A36EC5B4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:19:02.151183 :A36EC5B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:19:32.249144 :A36ECF89:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:20:02.230808 :A36ED959:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:20:02.220 2025-06-11 22:20:02.230810 :A36ED95A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14424 pso_num=26 pso_serial#=203 2025-06-11 22:20:02.230939 :A36ED95B:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14424 osp_idx=24 osp_ver=429368 osp_pg=0 (spawn #275863) 2025-06-11 22:20:02.230939 :A36ED95C:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:20:02.230940 :A36ED95D:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=101 time=2656145422 2025-06-11 22:20:02.230940 :A36ED95E:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:20:02.230941 :A36ED95F:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:20:02.230941 :A36ED960:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:20:02.230942 :A36ED961:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:20:02.230942 :A36ED962:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:20:02.231421 :A36ED963:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:20:32.284038 :A36EE2FA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:21:02.318657 :A36EECC5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:21:02.307 2025-06-11 22:21:02.318660 :A36EECC6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12136 pso_num=26 pso_serial#=204 2025-06-11 22:21:02.318793 :A36EECC7:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12136 osp_idx=24 osp_ver=429369 osp_pg=0 (spawn #275865) 2025-06-11 22:21:02.318793 :A36EECC8:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:21:02.318793 :A36EECC9:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=102 time=2656205500 2025-06-11 22:21:02.318794 :A36EECCA:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:21:02.318794 :A36EECCB:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:21:02.318795 :A36EECCC:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:21:02.318795 :A36EECCD:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:21:02.318795 :A36EECCE:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:21:02.319275 :A36EECCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:21:32.393029 :A36EF690:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:22:02.391713 :A36F00C4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:22:02.380 2025-06-11 22:22:02.391715 :A36F00C5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16988 pso_num=26 pso_serial#=205 2025-06-11 22:22:02.391851 :A36F00C6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16988 osp_idx=24 osp_ver=429370 osp_pg=0 (spawn #275867) 2025-06-11 22:22:02.391852 :A36F00C7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:22:02.391852 :A36F00C8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=103 time=2656265579 2025-06-11 22:22:02.391853 :A36F00C9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:22:02.391853 :A36F00CA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:22:02.391854 :A36F00CB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:22:02.391854 :A36F00CC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:22:02.391854 :A36F00CD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:22:02.392333 :A36F00CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:22:27.409209 :A36F09DD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-11 22:22:27.409239*:A36F09DE:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271DBB58 estart=1749651747 eid=16904866 ctx=0x000000077BD98A08 cwh=0x00000000271901B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-11 22:22:27.409239*:A36F09DF:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000271DBB58 curCtx=0x000000002719F708 pubTabIdxCnt=0 2025-06-11 22:22:27.409239*:A36F09E0:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000271DBB58 2025-06-11 22:22:27.409239*:A36F09E1:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000271DBB58 2025-06-11 22:22:27.409239*:A36F09E2:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000271DBB58 curCtx=0x000000002719F708 xsc->flg4=65792 flags=2 2025-06-11 22:22:27.409239*:A36F09E3:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000271DBB58 2025-06-11 22:22:27.409803 :A36F09E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 22:22:27.415220 :A36F09EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 22:22:27.416187 :A36F09F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 22:22:27.416316 :A36F09FD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 22:22:57.522402 :A36F13A9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:23:02.472475 :A36F159F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:23:02.461 2025-06-11 22:23:02.472477 :A36F15A0:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17400 pso_num=26 pso_serial#=206 2025-06-11 22:23:02.472622 :A36F15A1:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17400 osp_idx=24 osp_ver=429371 osp_pg=0 (spawn #275870) 2025-06-11 22:23:02.472623 :A36F15A2:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:23:02.472623 :A36F15A3:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=104 time=2656325657 2025-06-11 22:23:02.472624 :A36F15A4:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:23:02.472624 :A36F15A5:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:23:02.472624 :A36F15A6:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:23:02.472625 :A36F15A7:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:23:02.472626 :A36F15A8:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:23:02.473119 :A36F15A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:23:32.517894 :A36F1F4C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-11 22:24:02.542428 :A36F5E6E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-11 22:24:02.537 2025-06-11 22:24:02.542430 :A36F5E6F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16896 pso_num=26 pso_serial#=207 2025-06-11 22:24:02.542563 :A36F5E70:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16896 osp_idx=24 osp_ver=429372 osp_pg=0 (spawn #275872) 2025-06-11 22:24:02.542564 :A36F5E71:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:24:02.542564 :A36F5E72:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=105 time=2656385735 2025-06-11 22:24:02.542564 :A36F5E73:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:24:02.542565 :A36F5E74:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:24:02.542565 :A36F5E75:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:24:02.542565 :A36F5E76:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:24:02.542565 :A36F5E77:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:24:02.543051 :A36F5E78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 22:24:02.543655 :A36F5E8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 22:24:02.554697 :A36F5EA9:db_trace:kwqid.c@2146:kwqidrdq(): [10960:26:209] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-11 22:24:02.555155 :A36F5EAA:db_trace:kwqid.c@2146:kwqidrdq(): [10960:26:209] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-11 22:24:02.557534 :A36F5EAB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-11 22:24:02.557867 :A36F5EAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 22:24:02.557902 :A36F5EAF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-11 22:24:02.558806 :A36F5EB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 22:24:02.558923 :A36F5EBE:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 16896, J000)