Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250803100815\orcl_j001_19556_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:35798M/63366M, Ph+PgF:41451M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 19556, image: ORACLE.EXE (J001) *** 2025-08-03 10:08:15.824 *** SESSION ID:(201.40353) 2025-08-03 10:08:15.824 *** 2025-08-03 10:08:15.824 Process diagnostic dump for ORACLE.EXE (J001), OS id=19556, pid: 25, proc_ser: 29, sid: 201, sess_ser: 40353 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19556 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.177841 sec, exc=0.177841 sec, total=0.177841 sec wait times: max=0.500000 sec, heur=5.674043 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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.000014 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500126 sec, exc=0.500126 sec, total=0.500126 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000015 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.502698 sec, exc=0.502698 sec, total=0.502698 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500123 sec, exc=0.500123 sec, total=0.500123 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time Sampled Session History of session 201 serial 40353 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 10:08:10 - 10:08:15] idle wait at each sample [session created at: 10:08:10] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-03 10:08:15.824 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 19556, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-03 09:58:55.017328 :BAB97987:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 09:58:55.017328 :BAB97988:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 09:58:55.017328 :BAB97989:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 09:58:55.017328 :BAB9798A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 09:58:55.017329 :BAB9798B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 09:58:55.017818 :BAB9798C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 09:59:25.091560 :BAB98399:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 09:59:55.110645 :BAB98DA7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 09:59:55.100 2025-08-03 09:59:55.110647 :BAB98DA8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12796 pso_num=25 pso_serial#=19 2025-08-03 09:59:55.110795 :BAB98DA9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12796 osp_idx=23 osp_ver=226061 osp_pg=0 (spawn #450820) 2025-08-03 09:59:55.110795 :BAB98DAA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 09:59:55.110796 :BAB98DAB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=147 time=2895864408 2025-08-03 09:59:55.110796 :BAB98DAC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 09:59:55.110796 :BAB98DAD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 09:59:55.110796 :BAB98DAE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 09:59:55.110796 :BAB98DAF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 09:59:55.110797 :BAB98DB0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 09:59:55.111276 :BAB98DB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:00:25.173301 :BAB997BF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:00:44.329458 :BAB9A6EF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:00:44.320 2025-08-03 10:00:44.329460 :BAB9A6F0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20296 pso_num=25 pso_serial#=20 2025-08-03 10:00:44.329595 :BAB9A6F1:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20296 (legacy spawn) 2025-08-03 10:00:44.336132 :BAB9A6F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:44.336455 :BAB9A6FB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-03 10:00:44.337343 :BAB9A6FC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:00:45.136378 :BAB9A771:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:00:45.133 2025-08-03 10:00:45.136379 :BAB9A772:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18768 pso_num=25 pso_serial#=21 2025-08-03 10:00:45.136512 :BAB9A773:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18768 osp_idx=23 osp_ver=226063 osp_pg=0 (spawn #450822) 2025-08-03 10:00:45.136513 :BAB9A774:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 10:00:45.136513 :BAB9A775:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=148 time=2895914423 2025-08-03 10:00:45.136514 :BAB9A776:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:00:45.136514 :BAB9A777:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:00:45.136515 :BAB9A778:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:00:45.136515 :BAB9A779:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:00:45.136515 :BAB9A77A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:00:45.137010 :BAB9A77B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.137053 :BAB9A781:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-03 10:00:45.139642 :BAB9A78F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.145269 :BAB9A797:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.146394 :BAB9A79F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.146967 :BAB9A7A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.147436 :BAB9A7AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.147876 :BAB9A7B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.150424 :BAB9A7BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.151515 :BAB9A7C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.202987 :BAB9A7DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.203855 :BAB9A7E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.204166 :BAB9A7EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.204383 :BAB9A7F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:00:45.206301 :BAB9A7FC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:00:55.194005 :BAB9AB57:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:00:55.182 2025-08-03 10:00:55.194007 :BAB9AB58:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1740 pso_num=25 pso_serial#=22 2025-08-03 10:00:55.194149 :BAB9AB59:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1740 osp_idx=23 osp_ver=226064 osp_pg=0 (spawn #450824) 2025-08-03 10:00:55.194150 :BAB9AB5A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 10:00:55.194150 :BAB9AB5B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=149 time=2895924486 2025-08-03 10:00:55.194150 :BAB9AB5C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:00:55.194150 :BAB9AB5D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:00:55.194151 :BAB9AB5E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:00:55.194151 :BAB9AB5F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:00:55.194151 :BAB9AB60:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:00:55.194626 :BAB9AB61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:01:25.268979 :BAB9B630:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:01:55.280636 :BAB9C034:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:01:55.269 2025-08-03 10:01:55.280638 :BAB9C035:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18064 pso_num=25 pso_serial#=23 2025-08-03 10:01:55.280759 :BAB9C036:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18064 osp_idx=23 osp_ver=226065 osp_pg=0 (spawn #450826) 2025-08-03 10:01:55.280760 :BAB9C037:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 10:01:55.280760 :BAB9C038:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=150 time=2895984579 2025-08-03 10:01:55.280760 :BAB9C039:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:01:55.280761 :BAB9C03A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:01:55.280761 :BAB9C03B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:01:55.280762 :BAB9C03C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:01:55.280762 :BAB9C03D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:01:55.281271 :BAB9C03E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:02:25.354455 :BAB9CA75:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:02:55.326983 :BAB9D479:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:02:55.316 2025-08-03 10:02:55.326985 :BAB9D47A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12980 pso_num=25 pso_serial#=24 2025-08-03 10:02:55.327122 :BAB9D47B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12980 osp_idx=23 osp_ver=226066 osp_pg=0 (spawn #450828) 2025-08-03 10:02:55.327122 :BAB9D47C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 10:02:55.327123 :BAB9D47D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=151 time=2896044626 2025-08-03 10:02:55.327123 :BAB9D47E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:02:55.327124 :BAB9D47F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:02:55.327124 :BAB9D480:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:02:55.327124 :BAB9D481:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:02:55.327125 :BAB9D482:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:02:55.327600 :BAB9D483:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:03:25.413504 :BAB9DE93:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:03:55.400091 :BAB9E8EB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:03:55.388 2025-08-03 10:03:55.400093 :BAB9E8EC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18360 pso_num=25 pso_serial#=25 2025-08-03 10:03:55.400228 :BAB9E8ED:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18360 osp_idx=23 osp_ver=226067 osp_pg=0 (spawn #450833) 2025-08-03 10:03:55.400229 :BAB9E8EE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 10:03:55.400229 :BAB9E8EF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=152 time=2896104704 2025-08-03 10:03:55.400229 :BAB9E8F0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:03:55.400230 :BAB9E8F1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:03:55.400230 :BAB9E8F2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:03:55.400231 :BAB9E8F3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:03:55.400231 :BAB9E8F4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:03:55.400710 :BAB9E8F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:04:25.497796 :BAB9F2F5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:04:55.516304 :BAB9FD08:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:04:55.505 2025-08-03 10:04:55.516307 :BAB9FD09:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20076 pso_num=25 pso_serial#=26 2025-08-03 10:04:55.516432 :BAB9FD0A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20076 osp_idx=23 osp_ver=226068 osp_pg=0 (spawn #450835) 2025-08-03 10:04:55.516432 :BAB9FD0B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 10:04:55.516432 :BAB9FD0C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=153 time=2896164814 2025-08-03 10:04:55.516433 :BAB9FD0D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:04:55.516434 :BAB9FD0E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:04:55.516435 :BAB9FD0F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:04:55.516435 :BAB9FD10:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:04:55.516435 :BAB9FD11:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:04:55.516920 :BAB9FD12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:05:10.542590 :BABA022C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-03 10:05:40.632226 :BABA0C44:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:05:55.623034 :BABA173D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:05:55.612 2025-08-03 10:05:55.623035 :BABA173E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12648 pso_num=25 pso_serial#=27 2025-08-03 10:05:55.623154 :BABA173F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12648 osp_idx=23 osp_ver=226069 osp_pg=0 (spawn #450837) 2025-08-03 10:05:55.623154 :BABA1740:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 10:05:55.623155 :BABA1741:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=154 time=2896224923 2025-08-03 10:05:55.623155 :BABA1742:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:05:55.623155 :BABA1743:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:05:55.623156 :BABA1744:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:05:55.623156 :BABA1745:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:05:55.623156 :BABA1746:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:05:55.623646 :BABA1747:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:06:25.719948 :BABA2229:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:06:55.699558 :BABA2C37:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:06:55.687 2025-08-03 10:06:55.699560 :BABA2C38:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20196 pso_num=25 pso_serial#=28 2025-08-03 10:06:55.699698 :BABA2C39:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20196 osp_idx=23 osp_ver=226070 osp_pg=0 (spawn #450839) 2025-08-03 10:06:55.699698 :BABA2C3A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 10:06:55.699698 :BABA2C3B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=155 time=2896285001 2025-08-03 10:06:55.699699 :BABA2C3C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:06:55.699699 :BABA2C3D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:06:55.699699 :BABA2C3E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:06:55.699699 :BABA2C3F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:06:55.699700 :BABA2C40:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:06:55.700146 :BABA2C41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:07:25.778344 :BABA3708:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-03 10:07:55.798432 :BABA4117:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-03 10:07:55.787 2025-08-03 10:07:55.798434 :BABA4118:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19556 pso_num=25 pso_serial#=29 2025-08-03 10:07:55.798550 :BABA4119:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19556 osp_idx=23 osp_ver=226071 osp_pg=0 (spawn #450841) 2025-08-03 10:07:55.798551 :BABA411A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 10:07:55.798551 :BABA411B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=156 time=2896345095 2025-08-03 10:07:55.798551 :BABA411C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:07:55.798551 :BABA411D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:07:55.798552 :BABA411E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:07:55.798552 :BABA411F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:07:55.798552 :BABA4120:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:07:55.799046 :BABA4121:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 10:08:08.450274 :BABA4660:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-03 10:08:08.555846 :BABA4993:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-03 10:08:08.555846*:BABA49A2:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1754186888 eid=16785388 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-03 10:08:08.555846*:BABA49A3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-08-03 10:08:08.555846*:BABA49A4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-08-03 10:08:08.555846*:BABA49A5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-08-03 10:08:08.555846*:BABA49A6:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-08-03 10:08:08.555846*:BABA49A7:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-08-03 10:08:08.561097 :BABA49B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:08.561136 :BABA49B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-03 10:08:08.565942 :BABA49C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:08.691636 :BABA49F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:08.827090 :BABA4A0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:08.957892 :BABA4A16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.079241 :BABA4A59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.080347 :BABA4A61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.099262 :BABA4A69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.099949 :BABA4A71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.100500 :BABA4A79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.101096 :BABA4A81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.103969 :BABA4A95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.104873 :BABA4A9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-03 10:08:10.105766 :BABA4A9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-03 10:08:10.106574 :BABA4A9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-03 10:08:10.106986 :BABA4AA0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-03 10:08:10.106993 :BABA4AA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-03 10:08:10.107610 :BABA4AA2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-03 10:08:10.108102 :BABA4AA3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-03 10:08:10.108398 :BABA4AA4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-03 10:08:10.109496 :BABA4AA5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-03 10:08:10.146808 :BABA4AA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.146853 :BABA4AA9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-03 10:08:10.147696 :BABA4AAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.148182 :BABA4AB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.148500 :BABA4ABF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.148676 :BABA4AC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.148819 :BABA4ACF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.148951 :BABA4AD7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-03 10:08:10.152997 :BABA4AD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.153098 :BABA4ADB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-03 10:08:10.153188 :BABA4AE8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 19556, J001)