Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250913220454\orcl_j001_23588_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:34792M/63366M, Ph+PgF:39466M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 23588, image: ORACLE.EXE (J001) *** 2025-09-13 22:04:54.442 *** SESSION ID:(209.51215) 2025-09-13 22:04:54.442 *** 2025-09-13 22:04:54.442 Process diagnostic dump for ORACLE.EXE (J001), OS id=23588, pid: 26, proc_ser: 222, sid: 209, sess_ser: 51215 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23588 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.058814 sec, exc=0.058814 sec, total=0.058814 sec wait times: max=0.500000 sec, heur=2.051888 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.502090 sec, exc=0.502090 sec, total=0.502090 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: 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.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.490950 sec, exc=0.490950 sec, total=0.490950 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 51215 --------------------------------------------------- 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, 22:04:52 - 22:04:54] idle wait at each sample [session created at: 22:04:52] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-13 22:04:54.442 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 23588, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-13 21:55:40.230217 :CD070D4F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 21:55:40.230218 :CD070D50:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 21:55:40.230218 :CD070D51:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 21:55:40.230218 :CD070D52:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 21:55:40.230219 :CD070D53:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 21:55:40.230738 :CD070D54: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-09-13 21:56:10.324828 :CD071759:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-13 21:56:40.328546 :CD072132:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-13 21:56:40.317 2025-09-13 21:56:40.328548 :CD072133:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19576 pso_num=26 pso_serial#=214 2025-09-13 21:56:40.328669 :CD072134:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19576 osp_idx=24 osp_ver=842744 osp_pg=0 (spawn #589164) 2025-09-13 21:56:40.328670 :CD072135:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 21:56:40.328670 :CD072136:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=12 time=2186251627 2025-09-13 21:56:40.328670 :CD072137:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 21:56:40.328671 :CD072138:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 21:56:40.328671 :CD072139:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 21:56:40.328671 :CD07213A:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 21:56:40.328672 :CD07213B:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 21:56:40.329235 :CD07213C: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-09-13 21:57:10.408046 :CD072AF2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-13 21:57:40.425753 :CD0734D4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-13 21:57:40.414 2025-09-13 21:57:40.425755 :CD0734D5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19760 pso_num=26 pso_serial#=215 2025-09-13 21:57:40.425877 :CD0734D6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19760 osp_idx=24 osp_ver=842745 osp_pg=0 (spawn #589166) 2025-09-13 21:57:40.425878 :CD0734D7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 21:57:40.425878 :CD0734D8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=13 time=2186311721 2025-09-13 21:57:40.425879 :CD0734D9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 21:57:40.425879 :CD0734DA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 21:57:40.425880 :CD0734DB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 21:57:40.425880 :CD0734DC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 21:57:40.425880 :CD0734DD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 21:57:40.426389 :CD0734DE: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-09-13 21:58:10.497420 :CD073F35:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-13 21:58:40.490615 :CD074916:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-13 21:58:40.479 2025-09-13 21:58:40.490617 :CD074917:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24084 pso_num=26 pso_serial#=216 2025-09-13 21:58:40.490739 :CD074918:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24084 osp_idx=24 osp_ver=842746 osp_pg=0 (spawn #589168) 2025-09-13 21:58:40.490739 :CD074919:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 21:58:40.490739 :CD07491A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=14 time=2186371783 2025-09-13 21:58:40.490740 :CD07491B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 21:58:40.490740 :CD07491C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 21:58:40.490741 :CD07491D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 21:58:40.490741 :CD07491E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 21:58:40.490741 :CD07491F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 21:58:40.491229 :CD074920: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-09-13 21:59:10.590602 :CD0752F9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-13 21:59:40.568181 :CD075CC8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-13 21:59:40.556 2025-09-13 21:59:40.568184 :CD075CC9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21120 pso_num=26 pso_serial#=217 2025-09-13 21:59:40.568314 :CD075CCA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=21120 osp_idx=24 osp_ver=842747 osp_pg=0 (spawn #589170) 2025-09-13 21:59:40.568314 :CD075CCB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 21:59:40.568315 :CD075CCC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=15 time=2186431862 2025-09-13 21:59:40.568315 :CD075CCD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 21:59:40.568316 :CD075CCE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 21:59:40.568316 :CD075CCF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 21:59:40.568316 :CD075CD0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 21:59:40.568317 :CD075CD1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 21:59:40.568818 :CD075CD2: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-09-13 22:00:04.017447 :CD076469: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-09-13 22:00:34.109343 :CD076E5C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-13 22:00:40.663071 :CD07709B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-13 22:00:40.652 2025-09-13 22:00:40.663073 :CD07709C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=25064 pso_num=26 pso_serial#=218 2025-09-13 22:00:40.663215 :CD07709D:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=25064 osp_idx=24 osp_ver=842748 osp_pg=0 (spawn #589172) 2025-09-13 22:00:40.663216 :CD07709E:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 22:00:40.663217 :CD07709F:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=16 time=2186491955 2025-09-13 22:00:40.663217 :CD0770A0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 22:00:40.663218 :CD0770A1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 22:00:40.663218 :CD0770A2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 22:00:40.663218 :CD0770A3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 22:00:40.663219 :CD0770A4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 22:00:40.663721 :CD0770AD: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-09-13 22:01:10.745897 :CD077C99:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-13 22:01:40.763641 :CD078673:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-13 22:01:40.752 2025-09-13 22:01:40.763643 :CD078674:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6364 pso_num=26 pso_serial#=219 2025-09-13 22:01:40.763763 :CD078675:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=6364 osp_idx=24 osp_ver=842749 osp_pg=0 (spawn #589176) 2025-09-13 22:01:40.763764 :CD078676:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 22:01:40.763765 :CD078677:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=17 time=2186552065 2025-09-13 22:01:40.763765 :CD078678:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 22:01:40.763766 :CD078679:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 22:01:40.763766 :CD07867A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 22:01:40.763766 :CD07867B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 22:01:40.763767 :CD07867C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 22:01:40.764231 :CD07867D: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-09-13 22:02:10.850522 :CD079081:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-13 22:02:25.814612 :CD07957E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-13 22:02:25.804 2025-09-13 22:02:25.814614 :CD07957F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24264 pso_num=26 pso_serial#=220 2025-09-13 22:02:25.814749 :CD079580:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24264 osp_idx=24 osp_ver=842750 osp_pg=0 (spawn #589178) 2025-09-13 22:02:25.814750 :CD079581:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 22:02:25.814750 :CD079582:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=18 time=2186597112 2025-09-13 22:02:25.814751 :CD079583:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 22:02:25.814751 :CD079584:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 22:02:25.814751 :CD079585:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 22:02:25.814752 :CD079586:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 22:02:25.814752 :CD079587:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 22:02:25.815225 :CD079588: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-09-13 22:02:40.834204 :CD079A79: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-09-13 22:03:10.923006 :CD07A52C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-13 22:03:40.937722 :CD07AEC9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-13 22:03:40.926 2025-09-13 22:03:40.937724 :CD07AECA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24676 pso_num=26 pso_serial#=221 2025-09-13 22:03:40.937854 :CD07AECB:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24676 osp_idx=24 osp_ver=842751 osp_pg=0 (spawn #589180) 2025-09-13 22:03:40.937855 :CD07AECC:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 22:03:40.937855 :CD07AECD:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=19 time=2186672237 2025-09-13 22:03:40.937855 :CD07AECE:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 22:03:40.937856 :CD07AECF:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 22:03:40.937856 :CD07AED0:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 22:03:40.937857 :CD07AED1:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 22:03:40.937857 :CD07AED2:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 22:03:40.938413 :CD07AED3: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-09-13 22:04:11.041875 :CD07B8A3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-13 22:04:41.032731 :CD07C256:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-13 22:04:41.021 2025-09-13 22:04:41.032733 :CD07C257:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23588 pso_num=26 pso_serial#=222 2025-09-13 22:04:41.032847 :CD07C258:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=23588 osp_idx=24 osp_ver=842752 osp_pg=0 (spawn #589182) 2025-09-13 22:04:41.032848 :CD07C259:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 22:04:41.032849 :CD07C25A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=20 time=2186732330 2025-09-13 22:04:41.032849 :CD07C25B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 22:04:41.032849 :CD07C25C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 22:04:41.032849 :CD07C25D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 22:04:41.032850 :CD07C25E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 22:04:41.032850 :CD07C25F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 22:04:41.033377 :CD07C260: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-09-13 22:04:50.603130 :CD07C6B5: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-09-13 22:04:50.705844 :CD07C9FD: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-09-13 22:04:50.705844*:CD07CA0C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BBC718 estart=1757772290 eid=16787938 ctx=0x000000077FD6A000 cwh=0x0000000028BB0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-13 22:04:50.705844*:CD07CA0D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BBC718 curCtx=0x0000000028BB8938 pubTabIdxCnt=0 2025-09-13 22:04:50.705844*:CD07CA0E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BBC718 2025-09-13 22:04:50.705844*:CD07CA0F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BBC718 2025-09-13 22:04:50.705844*:CD07CA10:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BBC718 curCtx=0x0000000028BB8938 xsc->flg4=65792 flags=2 2025-09-13 22:04:50.705844*:CD07CA11:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BBC718 2025-09-13 22:04:50.711330 :CD07CA1C: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-09-13 22:04:50.711376 :CD07CA1F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-09-13 22:04:50.715509 :CD07CA2F: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-09-13 22:04:50.822031 :CD07CA5F: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-09-13 22:04:50.951680 :CD07CA67: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-09-13 22:04:51.082491 :CD07CA88: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-09-13 22:04:52.196271 :CD07CABC: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-09-13 22:04:52.197238 :CD07CAC4: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-09-13 22:04:52.198955 :CD07CACC: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-09-13 22:04:52.200444 :CD07CAD4: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-09-13 22:04:52.201865 :CD07CADC: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-09-13 22:04:52.204424 :CD07CAE4: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-09-13 22:04:52.206921 :CD07CAEC: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-09-13 22:04:52.221462 :CD07CAF4: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-09-13 22:04:52.222134 :CD07CAFC: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-09-13 22:04:52.222753 :CD07CB04: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-09-13 22:04:52.223266 :CD07CB0C: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-09-13 22:04:52.225817 :CD07CB14: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-09-13 22:04:52.226801 :CD07CB1C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.227552 :CD07CB1D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.228320 :CD07CB1E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.228728 :CD07CB1F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.228734 :CD07CB20:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.229304 :CD07CB21:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.229796 :CD07CB22:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.230094 :CD07CB23:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.231245 :CD07CB24:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.265630 :CD07CB25: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-09-13 22:04:52.265677 :CD07CB28:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.266530 :CD07CB2E: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-09-13 22:04:52.267403 :CD07CB36:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.267901 :CD07CB37: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-09-13 22:04:52.286206 :CD07CB3F: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-09-13 22:04:52.286253 :CD07CB42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.287005 :CD07CB48: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-09-13 22:04:52.287881 :CD07CB50:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.288361 :CD07CB51: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-09-13 22:04:52.308858 :CD07CB59: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-09-13 22:04:52.308909 :CD07CB5C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.309741 :CD07CB62: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-09-13 22:04:52.310621 :CD07CB6A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.311040 :CD07CB6B: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-09-13 22:04:52.332723 :CD07CB7D: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-09-13 22:04:52.332775 :CD07CB80:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.333606 :CD07CB86: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-09-13 22:04:52.345583 :CD07CB98:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.366710 :CD07CBC6: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-09-13 22:04:52.366761 :CD07CBC9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.367590 :CD07CBCF: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-09-13 22:04:52.368430 :CD07CBD7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-13 22:04:52.368913 :CD07CBD8: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-09-13 22:04:52.389441 :CD07CBE0: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-09-13 22:04:52.389491 :CD07CBE3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-13 22:04:52.390385 :CD07CBE9: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-09-13 22:04:52.390845 :CD07CBF1: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-09-13 22:04:52.391195 :CD07CBF9: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-09-13 22:04:52.391376 :CD07CC01: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-09-13 22:04:52.391505 :CD07CC09: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-09-13 22:04:52.391656 :CD07CC11:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-09-13 22:04:52.400122 :CD07CC12: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-09-13 22:04:52.400220 :CD07CC15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-13 22:04:52.400317 :CD07CC22: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: 23588, J001)