Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629100941\orcl_j001_12252_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:36555M/63366M, Ph+PgF:42680M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12252, image: ORACLE.EXE (J001) *** 2025-06-29 10:09:41.130 *** SESSION ID:(201.61767) 2025-06-29 10:09:41.130 *** 2025-06-29 10:09:41.130 Process diagnostic dump for ORACLE.EXE (J001), OS id=12252, pid: 25, proc_ser: 162, sid: 201, sess_ser: 61767 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12252 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.381755 sec, exc=0.381755 sec, total=0.381755 sec wait times: max=0.500000 sec, heur=2.885880 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=4 seq_num=5 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.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 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=1 seq_num=2 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.504095 sec, exc=0.504095 sec, total=0.504095 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 201 serial 61767 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 10:09:38 - 10:09:41] idle wait at each sample [session created at: 10:09:38] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-29 10:09:41.130 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12252, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 09:59:20.148933 :AB2A518D: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-06-29 09:59:50.274799 :AB2A5BBB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 09:59:55.237733 :AB2A5DD7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 09:59:55.227 2025-06-29 09:59:55.237735 :AB2A5DD8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17536 pso_num=25 pso_serial#=152 2025-06-29 09:59:55.237866 :AB2A5DD9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17536 osp_idx=23 osp_ver=175704 osp_pg=0 (spawn #334183) 2025-06-29 09:59:55.237867 :AB2A5DDA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 09:59:55.237867 :AB2A5DDB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=243 time=4166938422 2025-06-29 09:59:55.237867 :AB2A5DDC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 09:59:55.237868 :AB2A5DDD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 09:59:55.237868 :AB2A5DDE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 09:59:55.237868 :AB2A5DDF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 09:59:55.237869 :AB2A5DE0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 09:59:55.238326 :AB2A5DE1: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-06-29 10:00:25.307057 :AB2A6834:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:00:36.713996 :AB2A6CD6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:00:36.709 2025-06-29 10:00:36.713998 :AB2A6CD7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12088 pso_num=25 pso_serial#=153 2025-06-29 10:00:36.714130 :AB2A6CD8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12088 osp_idx=23 osp_ver=175705 osp_pg=0 (spawn #334185) 2025-06-29 10:00:36.714130 :AB2A6CD9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:00:36.714131 :AB2A6CDA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=244 time=4166979891 2025-06-29 10:00:36.714131 :AB2A6CDB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:00:36.714132 :AB2A6CDC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:00:36.714132 :AB2A6CDD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:00:36.714132 :AB2A6CDE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:00:36.714132 :AB2A6CDF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:00:36.714629 :AB2A6CE0: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-06-29 10:00:36.714677 :AB2A6CE6: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-06-29 10:00:36.722333 :AB2A6CF4: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-06-29 10:00:36.723472 :AB2A6CFC: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-06-29 10:00:36.724074 :AB2A6D04: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-06-29 10:00:36.724567 :AB2A6D0C: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-06-29 10:00:36.725005 :AB2A6D14: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-06-29 10:00:36.727477 :AB2A6D1C: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-06-29 10:00:36.728612 :AB2A6D24: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-06-29 10:00:36.779362 :AB2A6D2C: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-06-29 10:00:36.780229 :AB2A6D34: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-06-29 10:00:36.780468 :AB2A6D3C: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-06-29 10:00:36.780683 :AB2A6D44: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-06-29 10:00:36.782558 :AB2A6D4C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:00:55.311419 :AB2A734C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:00:55.300 2025-06-29 10:00:55.311421 :AB2A734D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13624 pso_num=25 pso_serial#=154 2025-06-29 10:00:55.311569 :AB2A734E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13624 osp_idx=23 osp_ver=175706 osp_pg=0 (spawn #334187) 2025-06-29 10:00:55.311570 :AB2A734F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:00:55.311570 :AB2A7350:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=245 time=4166998500 2025-06-29 10:00:55.311571 :AB2A7351:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:00:55.311571 :AB2A7352:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:00:55.311572 :AB2A7353:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:00:55.311572 :AB2A7354:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:00:55.311572 :AB2A7355:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:00:55.312073 :AB2A7356: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-06-29 10:01:25.367520 :AB2A7DEC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:01:55.386764 :AB2A87E0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:01:55.375 2025-06-29 10:01:55.386766 :AB2A87E1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17236 pso_num=25 pso_serial#=155 2025-06-29 10:01:55.386898 :AB2A87E2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17236 osp_idx=23 osp_ver=175707 osp_pg=0 (spawn #334189) 2025-06-29 10:01:55.386899 :AB2A87E3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:01:55.386899 :AB2A87E4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=4167058563 2025-06-29 10:01:55.386899 :AB2A87E5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:01:55.386900 :AB2A87E6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:01:55.386900 :AB2A87E7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:01:55.386900 :AB2A87E8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:01:55.386901 :AB2A87E9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:01:55.387417 :AB2A87EA: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-06-29 10:02:25.461890 :AB2A91CD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:02:55.484626 :AB2A9B8C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:02:55.473 2025-06-29 10:02:55.484629 :AB2A9B8D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17620 pso_num=25 pso_serial#=156 2025-06-29 10:02:55.484767 :AB2A9B8E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17620 osp_idx=23 osp_ver=175708 osp_pg=0 (spawn #334191) 2025-06-29 10:02:55.484768 :AB2A9B8F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:02:55.484768 :AB2A9B90:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=4167118672 2025-06-29 10:02:55.484769 :AB2A9B91:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:02:55.484769 :AB2A9B92:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:02:55.484769 :AB2A9B93:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:02:55.484770 :AB2A9B94:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:02:55.484770 :AB2A9B95:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:02:55.485259 :AB2A9B96: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-06-29 10:03:25.590265 :AB2AA5A1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:03:55.577212 :AB2AAF8D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:03:55.566 2025-06-29 10:03:55.577214 :AB2AAF8E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16648 pso_num=25 pso_serial#=157 2025-06-29 10:03:55.577350 :AB2AAF8F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16648 osp_idx=23 osp_ver=175709 osp_pg=0 (spawn #334193) 2025-06-29 10:03:55.577350 :AB2AAF90:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:03:55.577351 :AB2AAF91:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=4167178766 2025-06-29 10:03:55.577351 :AB2AAF92:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:03:55.577351 :AB2AAF93:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:03:55.577352 :AB2AAF94:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:03:55.577352 :AB2AAF95:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:03:55.577352 :AB2AAF96:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:03:55.577817 :AB2AAF97: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-06-29 10:04:25.637086 :AB2AB967:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:04:55.640245 :AB2AC372:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:04:55.629 2025-06-29 10:04:55.640247 :AB2AC373:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15500 pso_num=25 pso_serial#=158 2025-06-29 10:04:55.640391 :AB2AC374:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15500 osp_idx=23 osp_ver=175710 osp_pg=0 (spawn #334196) 2025-06-29 10:04:55.640391 :AB2AC375:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:04:55.640392 :AB2AC376:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=4167238829 2025-06-29 10:04:55.640392 :AB2AC377:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:04:55.640393 :AB2AC378:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:04:55.640394 :AB2AC379:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:04:55.640394 :AB2AC37A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:04:55.640394 :AB2AC37B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:04:55.640869 :AB2AC37C: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-06-29 10:05:25.674212 :AB2ACE29:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:05:55.722013 :AB2ADD55:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:05:55.711 2025-06-29 10:05:55.722015 :AB2ADD56:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7476 pso_num=25 pso_serial#=159 2025-06-29 10:05:55.722151 :AB2ADD57:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7476 osp_idx=23 osp_ver=175711 osp_pg=0 (spawn #334198) 2025-06-29 10:05:55.722152 :AB2ADD58:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:05:55.722152 :AB2ADD59:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=4167298907 2025-06-29 10:05:55.722152 :AB2ADD5A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:05:55.722153 :AB2ADD5B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:05:55.722153 :AB2ADD5C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:05:55.722153 :AB2ADD5D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:05:55.722154 :AB2ADD5E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:05:55.722628 :AB2ADD5F: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-06-29 10:06:25.783517 :AB2AE89A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:06:55.785560 :AB2AF2B7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:06:55.774 2025-06-29 10:06:55.785562 :AB2AF2B8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12524 pso_num=25 pso_serial#=160 2025-06-29 10:06:55.785698 :AB2AF2B9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12524 osp_idx=23 osp_ver=175712 osp_pg=0 (spawn #334201) 2025-06-29 10:06:55.785699 :AB2AF2BA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:06:55.785699 :AB2AF2BB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=251 time=4167358969 2025-06-29 10:06:55.785699 :AB2AF2BC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:06:55.785700 :AB2AF2BD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:06:55.785700 :AB2AF2BE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:06:55.785700 :AB2AF2BF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:06:55.785701 :AB2AF2C0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:06:55.786199 :AB2AF2C1: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-06-29 10:07:25.840791 :AB2AFC7F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:07:55.906419 :AB2B0665:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:07:55.904 2025-06-29 10:07:55.906421 :AB2B0666:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9536 pso_num=25 pso_serial#=161 2025-06-29 10:07:55.906554 :AB2B0667:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9536 osp_idx=23 osp_ver=175713 osp_pg=0 (spawn #334203) 2025-06-29 10:07:55.906555 :AB2B0668:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:07:55.906555 :AB2B0669:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=252 time=4167419079 2025-06-29 10:07:55.906555 :AB2B066A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:07:55.906556 :AB2B066B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:07:55.906556 :AB2B066C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:07:55.906556 :AB2B066D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:07:55.906557 :AB2B066E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:07:55.907023 :AB2B066F: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-06-29 10:08:26.021309 :AB2B104D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 10:08:56.003578 :AB2B1A38:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 10:08:55.992 2025-06-29 10:08:56.003580 :AB2B1A39:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12252 pso_num=25 pso_serial#=162 2025-06-29 10:08:56.003723 :AB2B1A3A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12252 osp_idx=23 osp_ver=175714 osp_pg=0 (spawn #334205) 2025-06-29 10:08:56.003724 :AB2B1A3B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:08:56.003724 :AB2B1A3C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=4167479188 2025-06-29 10:08:56.003724 :AB2B1A3D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:08:56.003725 :AB2B1A3E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:08:56.003725 :AB2B1A3F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:08:56.003726 :AB2B1A40:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:08:56.003726 :AB2B1A41:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:08:56.004244 :AB2B1A42: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-06-29 10:09:21.054401 :AB2B227D: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-06-29 10:09:36.536463 :AB2B28DC: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-06-29 10:09:36.640876 :AB2B2C2B: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-06-29 10:09:36.640876*:AB2B2C39:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1751162976 eid=16783234 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-29 10:09:36.640876*:AB2B2C3A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-06-29 10:09:36.640876*:AB2B2C3B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-06-29 10:09:36.640876*:AB2B2C3C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-06-29 10:09:36.640876*:AB2B2C3D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-06-29 10:09:36.640876*:AB2B2C3E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-06-29 10:09:36.646247 :AB2B2C49: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-06-29 10:09:36.646284 :AB2B2C4C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-29 10:09:36.651633 :AB2B2C5C: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-06-29 10:09:36.778030 :AB2B2C7B: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-06-29 10:09:36.912400 :AB2B2C83: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-06-29 10:09:37.042357 :AB2B2C8B: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-06-29 10:09:38.174506 :AB2B2D08: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-06-29 10:09:38.175487 :AB2B2D10: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-06-29 10:09:38.194139 :AB2B2D18: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-06-29 10:09:38.194792 :AB2B2D20: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-06-29 10:09:38.195315 :AB2B2D28: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-06-29 10:09:38.195822 :AB2B2D30: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-06-29 10:09:38.198542 :AB2B2D38: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-06-29 10:09:38.199410 :AB2B2D40:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-29 10:09:38.200177 :AB2B2D41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-29 10:09:38.201037 :AB2B2D42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-29 10:09:38.201467 :AB2B2D43:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-29 10:09:38.201474 :AB2B2D44:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-29 10:09:38.202090 :AB2B2D45:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-29 10:09:38.202606 :AB2B2D46:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-29 10:09:38.202917 :AB2B2D47:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-29 10:09:38.203934 :AB2B2D48:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-29 10:09:38.241015 :AB2B2D49: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-06-29 10:09:38.241072 :AB2B2D4C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-29 10:09:38.242026 :AB2B2D52: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-06-29 10:09:38.242508 :AB2B2D5A: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-06-29 10:09:38.242883 :AB2B2D62: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-06-29 10:09:38.243058 :AB2B2D6A: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-06-29 10:09:38.243199 :AB2B2D72: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-06-29 10:09:38.243345 :AB2B2D7A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-29 10:09:38.244748 :AB2B2D7B: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-06-29 10:09:38.247926 :AB2B2D83: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-06-29 10:09:38.248012 :AB2B2D86: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-06-29 10:09:38.248120 :AB2B2D93: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: 12252, J001)