Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250630220006\orcl_j000_15732_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:36708M/63366M, Ph+PgF:42768M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 15732, image: ORACLE.EXE (J000) *** 2025-06-30 22:00:06.097 *** SESSION ID:(201.65193) 2025-06-30 22:00:06.097 *** 2025-06-30 22:00:06.097 Process diagnostic dump for ORACLE.EXE (J000), OS id=15732, pid: 25, proc_ser: 47, sid: 201, sess_ser: 65193 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15732 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) 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.065941 sec, exc=0.065941 sec, total=0.065941 sec wait times: max=0.500000 sec, heur=2.062402 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.000005 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.510105 sec, exc=0.510105 sec, total=0.510105 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 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.500025 sec, exc=0.500025 sec, total=0.500025 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 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.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 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.486309 sec, exc=0.486309 sec, total=0.486309 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 65193 --------------------------------------------------- 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:00:04 - 22:00:06] 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-06-30 22:00:06.097 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 15732, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-30 21:51:05.848466 :ABD2A209: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-30 21:51:35.930771 :ABD2ABA4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 21:52:05.914071 :ABD2B550:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 21:52:05.903 2025-06-30 21:52:05.914072 :ABD2B551:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15584 pso_num=25 pso_serial#=38 2025-06-30 21:52:05.914206 :ABD2B552:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15584 osp_idx=23 osp_ver=177375 osp_pg=0 (spawn #339154) 2025-06-30 21:52:05.914206 :ABD2B553: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-30 21:52:05.914207 :ABD2B554:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=90 time=1101798 2025-06-30 21:52:05.914207 :ABD2B555:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 21:52:05.914208 :ABD2B556:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 21:52:05.914208 :ABD2B557:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 21:52:05.914209 :ABD2B558:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 21:52:05.914209 :ABD2B559:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 21:52:05.914674 :ABD2B55A: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-30 21:52:30.946780 :ABD2BDBC: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-30 21:53:01.035301 :ABD2C7D0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 21:53:05.986845 :ABD2C9C2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 21:53:05.976 2025-06-30 21:53:05.986848 :ABD2C9C3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7776 pso_num=25 pso_serial#=39 2025-06-30 21:53:05.986990 :ABD2C9C4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7776 osp_idx=23 osp_ver=177376 osp_pg=0 (spawn #339156) 2025-06-30 21:53:05.986990 :ABD2C9C5: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-30 21:53:05.986991 :ABD2C9C6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=91 time=1161876 2025-06-30 21:53:05.986991 :ABD2C9C7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 21:53:05.986992 :ABD2C9C8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 21:53:05.986992 :ABD2C9C9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 21:53:05.986992 :ABD2C9CA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 21:53:05.986993 :ABD2C9CB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 21:53:05.987489 :ABD2C9CC: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-30 21:53:36.110415 :ABD2D372:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 21:54:06.081765 :ABD2DD0F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 21:54:06.070 2025-06-30 21:54:06.081768 :ABD2DD10:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7628 pso_num=25 pso_serial#=40 2025-06-30 21:54:06.081905 :ABD2DD11:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7628 osp_idx=23 osp_ver=177377 osp_pg=0 (spawn #339159) 2025-06-30 21:54:06.081905 :ABD2DD12: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-30 21:54:06.081906 :ABD2DD13:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=92 time=1221970 2025-06-30 21:54:06.081906 :ABD2DD14:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 21:54:06.081906 :ABD2DD15:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 21:54:06.081907 :ABD2DD16:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 21:54:06.081907 :ABD2DD17:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 21:54:06.081907 :ABD2DD18:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 21:54:06.082398 :ABD2DD19: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-30 21:54:36.159105 :ABD2E6AA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 21:55:06.164040 :ABD2F08C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 21:55:06.152 2025-06-30 21:55:06.164042 :ABD2F08D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9808 pso_num=25 pso_serial#=41 2025-06-30 21:55:06.164185 :ABD2F08E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9808 osp_idx=23 osp_ver=177378 osp_pg=0 (spawn #339161) 2025-06-30 21:55:06.164185 :ABD2F08F: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-30 21:55:06.164186 :ABD2F090:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=93 time=1282048 2025-06-30 21:55:06.164186 :ABD2F091:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 21:55:06.164187 :ABD2F092:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 21:55:06.164187 :ABD2F093:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 21:55:06.164187 :ABD2F094:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 21:55:06.164188 :ABD2F095:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 21:55:06.164680 :ABD2F096: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-30 21:55:36.228659 :ABD2FA67:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 21:56:06.223343 :ABD303F8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 21:56:06.212 2025-06-30 21:56:06.223345 :ABD303F9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16768 pso_num=25 pso_serial#=42 2025-06-30 21:56:06.223474 :ABD303FA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16768 osp_idx=23 osp_ver=177379 osp_pg=0 (spawn #339163) 2025-06-30 21:56:06.223475 :ABD303FB: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-30 21:56:06.223475 :ABD303FC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=94 time=1342111 2025-06-30 21:56:06.223476 :ABD303FD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 21:56:06.223477 :ABD303FE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 21:56:06.223477 :ABD303FF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 21:56:06.223477 :ABD30400:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 21:56:06.223478 :ABD30401:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 21:56:06.223954 :ABD30402: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-30 21:56:36.299312 :ABD30D94:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 21:57:06.305333 :ABD3174A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 21:57:06.294 2025-06-30 21:57:06.305335 :ABD3174B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17560 pso_num=25 pso_serial#=43 2025-06-30 21:57:06.305466 :ABD3174C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17560 osp_idx=23 osp_ver=177380 osp_pg=0 (spawn #339165) 2025-06-30 21:57:06.305466 :ABD3174D: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-30 21:57:06.305467 :ABD3174E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=95 time=1402189 2025-06-30 21:57:06.305467 :ABD3174F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 21:57:06.305468 :ABD31750:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 21:57:06.305468 :ABD31751:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 21:57:06.305468 :ABD31752:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 21:57:06.305468 :ABD31753:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 21:57:06.305954 :ABD31754: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-30 21:57:36.396535 :ABD32168:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 21:58:06.398820 :ABD32B4D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 21:58:06.387 2025-06-30 21:58:06.398822 :ABD32B4E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15660 pso_num=25 pso_serial#=44 2025-06-30 21:58:06.398951 :ABD32B4F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15660 osp_idx=23 osp_ver=177381 osp_pg=0 (spawn #339167) 2025-06-30 21:58:06.398952 :ABD32B50: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-30 21:58:06.398952 :ABD32B51:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=96 time=1462283 2025-06-30 21:58:06.398953 :ABD32B52:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 21:58:06.398953 :ABD32B53:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 21:58:06.398953 :ABD32B54:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 21:58:06.398954 :ABD32B55:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 21:58:06.398954 :ABD32B56:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 21:58:06.399436 :ABD32B57: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-30 21:58:36.494787 :ABD3347E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 21:59:06.495701 :ABD33E35:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 21:59:06.484 2025-06-30 21:59:06.495703 :ABD33E36:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4288 pso_num=25 pso_serial#=45 2025-06-30 21:59:06.495848 :ABD33E37:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4288 osp_idx=23 osp_ver=177382 osp_pg=0 (spawn #339169) 2025-06-30 21:59:06.495849 :ABD33E38: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-30 21:59:06.495849 :ABD33E39:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=97 time=1522376 2025-06-30 21:59:06.495850 :ABD33E3A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 21:59:06.495850 :ABD33E3B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 21:59:06.495850 :ABD33E3C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 21:59:06.495851 :ABD33E3D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 21:59:06.495851 :ABD33E3E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 21:59:06.496337 :ABD33E3F: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-30 21:59:36.566295 :ABD3480D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 22:00:00.044448 :ABD34FCC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 22:00:00.035 2025-06-30 22:00:00.044450 :ABD34FCD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18232 pso_num=25 pso_serial#=46 2025-06-30 22:00:00.044588 :ABD34FCE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18232 osp_idx=23 osp_ver=177383 osp_pg=0 (spawn #339171) 2025-06-30 22:00:00.044589 :ABD34FCF: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-30 22:00:00.044589 :ABD34FD0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=98 time=1575939 2025-06-30 22:00:00.044590 :ABD34FD1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 22:00:00.044590 :ABD34FD2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 22:00:00.044591 :ABD34FD3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 22:00:00.044591 :ABD34FD4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 22:00:00.044591 :ABD34FD5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 22:00:00.045083 :ABD34FD6: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-30 22:00:00.045137 :ABD34FDD: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-30 22:00:00.045234 :ABD34FF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-30 22:00:00.049120 :ABD34FFF: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-30 22:00:00.050302 :ABD35007: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-30 22:00:00.050329 :ABD3500A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-30 22:00:00.050367 :ABD35015:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-30 22:00:00.052096 :ABD35020: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-30 22:00:00.052123 :ABD35023:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-30 22:00:00.052167 :ABD3502E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-30 22:00:00.052640 :ABD35039: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-30 22:00:00.055205 :ABD35041: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-30 22:00:00.060769 :ABD35049: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-30 22:00:00.066171 :ABD35051: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-30 22:00:00.067573 :ABD35059: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-30 22:00:00.070558 :ABD35061: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-30 22:00:00.071838 :ABD35069: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-30 22:00:00.072750 :ABD35071: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-30 22:00:00.075370 :ABD35079: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-30 22:00:00.078797 :ABD35081:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-30 22:00:00.079156 :ABD35090: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-30 22:00:00.079205 :ABD35093:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-30 22:00:00.079219 :ABD35094:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-30 22:00:00.081157 :ABD3509A: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-30 22:00:00.081202 :ABD3509D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-30 22:00:00.081214 :ABD3509E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-30 22:00:00.083297 :ABD350A4: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-30 22:00:00.083341 :ABD350A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-30 22:00:00.083353 :ABD350A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-30 22:00:00.083536 :ABD350AE: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-30 22:00:00.083554 :ABD350B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-30 22:00:00.083594 :ABD350BC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-30 22:00:00.085068 :ABD350C7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-30 22:00:01.572682 :ABD35122:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-30 22:00:01.567 2025-06-30 22:00:01.572684 :ABD35123:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15732 pso_num=25 pso_serial#=47 2025-06-30 22:00:01.572818 :ABD35124:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15732 osp_idx=23 osp_ver=177384 osp_pg=0 (spawn #339172) 2025-06-30 22:00:01.572819 :ABD35125: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-30 22:00:01.572820 :ABD35126:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=99 time=1577470 2025-06-30 22:00:01.572820 :ABD35127:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-30 22:00:01.572820 :ABD35128:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-30 22:00:01.572821 :ABD35129:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-30 22:00:01.572821 :ABD3512A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-30 22:00:01.572821 :ABD3512B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-30 22:00:01.573300 :ABD3512C: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-30 22:00:01.578764 :ABD35159: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-30 22:00:01.578814 :ABD3515C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-30 22:00:01.589842 :ABD351AB: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-30 22:00:01.595785 :ABD351E9: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-30 22:00:01.601370 :ABD3520D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-30 22:00:01.601518 :ABD35218:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-30 22:00:01.602251 :ABD3522E: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-30 22:00:01.602433 :ABD35236:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-30 22:00:01.603850 :ABD35237: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-30 22:00:01.603890 :ABD3523A: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-30 22:00:01.603977 :ABD35247:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-30 22:00:01.707939 :ABD354A9: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-30 22:00:01.709996 :ABD354AD: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-30 22:00:01.710037 :ABD354B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-30 22:00:01.712497 :ABD354B6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-30 22:00:01.715787 :ABD354B7: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-30 22:00:01.715866 :ABD354BA: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-30 22:00:01.715947 :ABD354C7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-30 22:00:04.025627 :ABD35927: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-30 22:00:04.025627*:ABD3592C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1751292003 eid=16783329 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-30 22:00:04.025627*:ABD3592D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-06-30 22:00:04.025627*:ABD3592E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-06-30 22:00:04.025627*:ABD3592F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-06-30 22:00:04.025627*:ABD35930:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-06-30 22:00:04.025627*:ABD35931:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-06-30 22:00:04.027781 :ABD35932: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-30 22:00:04.027818 :ABD35935:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-30 22:00:04.034468 :ABD3593B: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-30 22:00:04.035188 :ABD35943: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-30 22:00:04.035939 :ABD3594B: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-30 22:00:04.036125 :ABD35953:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-30 22:00:04.037586 :ABD35954: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-30 22:00:04.037627 :ABD35957: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-30 22:00:04.037707 :ABD35964: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: 15732, J000)