Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250915220005\orcl_j000_25008_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:34717M/63366M, Ph+PgF:39306M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 25008, image: ORACLE.EXE (J000) *** 2025-09-15 22:00:05.224 *** SESSION ID:(193.44589) 2025-09-15 22:00:05.224 *** 2025-09-15 22:00:05.224 Process diagnostic dump for ORACLE.EXE (J000), OS id=25008, pid: 24, proc_ser: 148, sid: 193, sess_ser: 44589 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 25008 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.187925 sec, exc=0.187925 sec, total=0.187925 sec wait times: max=0.500000 sec, heur=1.184135 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.000014 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.496179 sec, exc=0.496179 sec, total=0.496179 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 193 serial 44589 --------------------------------------------------- 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 --------------------------------------------------- [2 samples, 22:00:04 - 22:00:05] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-15 22:00:05.224 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 25008, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-15 21:52:04.731064 :CDEA838E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 21:52:04.719 2025-09-15 21:52:04.731067 :CDEA838F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25080 pso_num=24 pso_serial#=139 2025-09-15 21:52:04.731179 :CDEA8390:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=25080 osp_idx=22 osp_ver=896563 osp_pg=0 (spawn #595826) 2025-09-15 21:52:04.731180 :CDEA8391:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 21:52:04.731181 :CDEA8392:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=156 time=2358776033 2025-09-15 21:52:04.731181 :CDEA8393:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:52:04.731181 :CDEA8394:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:52:04.731182 :CDEA8395:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:52:04.731182 :CDEA8396:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:52:04.731182 :CDEA8397:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:52:04.731685 :CDEA8398:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 21:52:34.800653 :CDEA8E61:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-15 21:53:04.816043 :CDEA984F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 21:53:04.805 2025-09-15 21:53:04.816046 :CDEA9850:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25176 pso_num=24 pso_serial#=140 2025-09-15 21:53:04.816168 :CDEA9851:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=25176 osp_idx=22 osp_ver=896564 osp_pg=0 (spawn #595828) 2025-09-15 21:53:04.816168 :CDEA9852:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 21:53:04.816169 :CDEA9853:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=157 time=2358836112 2025-09-15 21:53:04.816169 :CDEA9854:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:53:04.816170 :CDEA9855:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:53:04.816170 :CDEA9856:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:53:04.816170 :CDEA9857:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:53:04.816171 :CDEA9858:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:53:04.816644 :CDEA9859:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 21:53:34.913427 :CDEAA260:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-15 21:54:04.916498 :CDEAAC78:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 21:54:04.905 2025-09-15 21:54:04.916500 :CDEAAC79:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25180 pso_num=24 pso_serial#=141 2025-09-15 21:54:04.916621 :CDEAAC7A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=25180 osp_idx=22 osp_ver=896565 osp_pg=0 (spawn #595831) 2025-09-15 21:54:04.916621 :CDEAAC7B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 21:54:04.916622 :CDEAAC7C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=158 time=2358896221 2025-09-15 21:54:04.916622 :CDEAAC7D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:54:04.916623 :CDEAAC7E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:54:04.916623 :CDEAAC7F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:54:04.916623 :CDEAAC80:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:54:04.916623 :CDEAAC81:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:54:04.917114 :CDEAAC82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 21:54:35.017324 :CDEAB63E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-15 21:55:05.020957 :CDEAC063:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 21:55:05.010 2025-09-15 21:55:05.020959 :CDEAC064:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25400 pso_num=24 pso_serial#=142 2025-09-15 21:55:05.021079 :CDEAC065:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=25400 osp_idx=22 osp_ver=896566 osp_pg=0 (spawn #595833) 2025-09-15 21:55:05.021080 :CDEAC066:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 21:55:05.021080 :CDEAC067:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=159 time=2358956315 2025-09-15 21:55:05.021081 :CDEAC068:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:55:05.021081 :CDEAC069:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:55:05.021081 :CDEAC06A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:55:05.021082 :CDEAC06B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:55:05.021082 :CDEAC06C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:55:05.021599 :CDEAC06D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 21:55:35.127746 :CDEACAAE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-15 21:56:05.117251 :CDEAD4CC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 21:56:05.106 2025-09-15 21:56:05.117253 :CDEAD4CD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24984 pso_num=24 pso_serial#=143 2025-09-15 21:56:05.117380 :CDEAD4CE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24984 osp_idx=22 osp_ver=896567 osp_pg=0 (spawn #595835) 2025-09-15 21:56:05.117381 :CDEAD4CF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 21:56:05.117381 :CDEAD4D0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=160 time=2359016408 2025-09-15 21:56:05.117381 :CDEAD4D1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:56:05.117382 :CDEAD4D2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:56:05.117382 :CDEAD4D3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:56:05.117382 :CDEAD4D4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:56:05.117382 :CDEAD4D5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:56:05.117854 :CDEAD4D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 21:56:35.171780 :CDEADEA3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-15 21:56:50.185736 :CDEAE3A6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 21:56:50.174 2025-09-15 21:56:50.185738 :CDEAE3A7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23364 pso_num=24 pso_serial#=144 2025-09-15 21:56:50.185869 :CDEAE3A8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23364 osp_idx=22 osp_ver=896568 osp_pg=0 (spawn #595837) 2025-09-15 21:56:50.185870 :CDEAE3A9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 21:56:50.185871 :CDEAE3AA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=161 time=2359061487 2025-09-15 21:56:50.185871 :CDEAE3AB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:56:50.185871 :CDEAE3AC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:56:50.185872 :CDEAE3AD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:56:50.185872 :CDEAE3AE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:56:50.185872 :CDEAE3AF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:56:50.186353 :CDEAE3B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 21:57:05.194173 :CDEAE8D5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 21:57:35.294872 :CDEAF30B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-15 21:58:05.305478 :CDEAFD25:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 21:58:05.294 2025-09-15 21:58:05.305481 :CDEAFD26:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24728 pso_num=24 pso_serial#=145 2025-09-15 21:58:05.305601 :CDEAFD27:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24728 osp_idx=22 osp_ver=896569 osp_pg=0 (spawn #595839) 2025-09-15 21:58:05.305601 :CDEAFD28:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 21:58:05.305602 :CDEAFD29:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=162 time=2359136596 2025-09-15 21:58:05.305602 :CDEAFD2A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:58:05.305602 :CDEAFD2B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:58:05.305603 :CDEAFD2C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:58:05.305603 :CDEAFD2D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:58:05.305604 :CDEAFD2E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:58:05.306093 :CDEAFD2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 21:58:35.367890 :CDEB06FA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-15 21:59:05.411953 :CDEB112B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 21:59:05.401 2025-09-15 21:59:05.411955 :CDEB112C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23104 pso_num=24 pso_serial#=146 2025-09-15 21:59:05.412086 :CDEB112D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23104 osp_idx=22 osp_ver=896570 osp_pg=0 (spawn #595841) 2025-09-15 21:59:05.412087 :CDEB112E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 21:59:05.412088 :CDEB112F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=163 time=2359196705 2025-09-15 21:59:05.412088 :CDEB1130:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:59:05.412089 :CDEB1131:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:59:05.412089 :CDEB1132:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:59:05.412089 :CDEB1133:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:59:05.412090 :CDEB1134:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:59:05.412568 :CDEB1135:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 21:59:35.506076 :CDEB1B05:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-15 22:00:00.041388 :CDEB232E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 22:00:00.032 2025-09-15 22:00:00.041390 :CDEB232F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22740 pso_num=24 pso_serial#=147 2025-09-15 22:00:00.041536 :CDEB2330:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22740 osp_idx=22 osp_ver=896571 osp_pg=0 (spawn #595843) 2025-09-15 22:00:00.041537 :CDEB2331:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 22:00:00.041537 :CDEB2332:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=164 time=2359251346 2025-09-15 22:00:00.041538 :CDEB2333:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 22:00:00.041539 :CDEB2334:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 22:00:00.041539 :CDEB2335:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 22:00:00.041539 :CDEB2336:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 22:00:00.041539 :CDEB2337:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 22:00:00.042039 :CDEB2338:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.042081 :CDEB233F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09-15 22:00:00.042159 :CDEB2353:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.046035 :CDEB2362:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.047194 :CDEB236A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.047218 :CDEB236D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.047258 :CDEB2378:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-15 22:00:00.049147 :CDEB2383:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.049175 :CDEB2386:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.049214 :CDEB2391:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-15 22:00:00.049675 :CDEB239C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.052043 :CDEB23A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.057343 :CDEB23AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.062900 :CDEB23B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.064433 :CDEB23BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.067367 :CDEB23C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.068712 :CDEB23CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.071703 :CDEB23D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.075090 :CDEB23DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.075481 :CDEB23EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.075526 :CDEB23EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-09-15 22:00:00.075539 :CDEB23EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-09-15 22:00:00.077604 :CDEB23F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.077652 :CDEB23F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-09-15 22:00:00.077664 :CDEB23F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-09-15 22:00:00.079864 :CDEB23FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.079908 :CDEB2402:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-09-15 22:00:00.079919 :CDEB2403:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-09-15 22:00:00.080080 :CDEB2409:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.080094 :CDEB240C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.080134 :CDEB2416:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-15 22:00:00.081909 :CDEB2422:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-15 22:00:00.491117 :CDEB2499:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 22:00:00.485 2025-09-15 22:00:00.491119 :CDEB249A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25008 pso_num=24 pso_serial#=148 2025-09-15 22:00:00.491255 :CDEB249B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=25008 osp_idx=22 osp_ver=896572 osp_pg=0 (spawn #595844) 2025-09-15 22:00:00.491256 :CDEB249C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 22:00:00.491257 :CDEB249D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=165 time=2359251799 2025-09-15 22:00:00.491257 :CDEB249E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 22:00:00.491258 :CDEB249F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 22:00:00.491258 :CDEB24A0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 22:00:00.491258 :CDEB24A1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 22:00:00.491259 :CDEB24A2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 22:00:00.491748 :CDEB24A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.497167 :CDEB24C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.497228 :CDEB24C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-09-15 22:00:00.506851 :CDEB2510:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.508869 :CDEB2540:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.513947 :CDEB255B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.515026 :CDEB2578:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.520522 :CDEB26B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.520681 :CDEB26D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.521453 :CDEB26F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.521652 :CDEB26FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-09-15 22:00:00.523080 :CDEB26FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.523117 :CDEB2700:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.523180 :CDEB270D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09-15 22:00:00.626485 :CDEB281E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 22:00:00.628593 :CDEB2823:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.628636 :CDEB2826:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-09-15 22:00:00.631144 :CDEB282C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-09-15 22:00:00.634445 :CDEB282D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.634530 :CDEB2830:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-15 22:00:00.634608 :CDEB283D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09-15 22:00:04.026823 :CDEB2CDC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 22:00:04.026823*:CDEB2CE2:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1757944803 eid=16788065 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-15 22:00:04.026823*:CDEB2CE3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-09-15 22:00:04.026823*:CDEB2CE4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-09-15 22:00:04.026823*:CDEB2CE5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-09-15 22:00:04.026823*:CDEB2CE6:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-09-15 22:00:04.026823*:CDEB2CE7:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-09-15 22:00:04.029084 :CDEB2CE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:04.029126 :CDEB2CEB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-09-15 22:00:04.035739 :CDEB2CF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:04.036551 :CDEB2CF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:04.037349 :CDEB2D01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:04.037554 :CDEB2D09:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-09-15 22:00:04.038985 :CDEB2D0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 22:00:04.039026 :CDEB2D0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-15 22:00:04.039116 :CDEB2D1A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 25008, J000)