Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525060006\orcl_j001_15188_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:37298M/63366M, Ph+PgF:44326M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 15188, image: ORACLE.EXE (J001) *** 2025-05-25 06:00:06.569 *** SESSION ID:(209.62159) 2025-05-25 06:00:06.569 *** 2025-05-25 06:00:06.569 Process diagnostic dump for ORACLE.EXE (J001), OS id=15188, pid: 26, proc_ser: 136, sid: 209, sess_ser: 62159 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15188 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.183194 sec, exc=0.183194 sec, total=0.183194 sec wait times: max=0.500000 sec, heur=2.171350 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.000010 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.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.500104 sec, exc=0.500104 sec, total=0.500104 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 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.488013 sec, exc=0.488013 sec, total=0.488013 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 62159 --------------------------------------------------- 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, 06:00:04 - 06:00:06] idle wait at each sample [session created at: 06:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 06:00:06.569 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 15188, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 05:57:29.833179 :9BAE10DA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13204 pso_num=26 pso_serial#=121 2025-05-25 05:57:29.833316 :9BAE10DB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13204 (legacy spawn) 2025-05-25 05:57:29.839817 :9BAE10DC: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-05-25 05:57:29.839997 :9BAE10E5: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 2025-05-25 05:57:29.840814 :9BAE10E6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:57:29.849489 :9BAE10E9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:57:29.836 2025-05-25 05:57:29.849491 :9BAE10EA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12328 pso_num=26 pso_serial#=122 2025-05-25 05:57:29.849627 :9BAE10EB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12328 (legacy spawn) 2025-05-25 05:57:29.856119 :9BAE10EC: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-05-25 05:57:29.856280 :9BAE10F5: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 2025-05-25 05:57:29.857068 :9BAE10F6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:57:32.479478 :9BAE11CE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:57:32.471 2025-05-25 05:57:32.479480 :9BAE11CF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13408 pso_num=26 pso_serial#=123 2025-05-25 05:57:32.479628 :9BAE11D0:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13408 (legacy spawn) 2025-05-25 05:57:32.486186 :9BAE11D1: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-05-25 05:57:32.486353 :9BAE11DA: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 2025-05-25 05:57:32.487296 :9BAE11E7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:57:36.569118 :9BAE1310:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:57:36.560 2025-05-25 05:57:36.569120 :9BAE1311:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10448 pso_num=26 pso_serial#=124 2025-05-25 05:57:36.569259 :9BAE1312:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10448 (legacy spawn) 2025-05-25 05:57:36.575786 :9BAE1313: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-05-25 05:57:36.575957 :9BAE1318: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 2025-05-25 05:57:36.576794 :9BAE131D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:58:10.678109 :9BAE1E08:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:58:10.669 2025-05-25 05:58:10.678111 :9BAE1E09:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10896 pso_num=26 pso_serial#=125 2025-05-25 05:58:10.678243 :9BAE1E0A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10896 (legacy spawn) 2025-05-25 05:58:10.684635 :9BAE1E0B: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-05-25 05:58:10.684800 :9BAE1E10: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 2025-05-25 05:58:10.685660 :9BAE1E25:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:58:14.315157 :9BAE1F6C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:58:14.310 2025-05-25 05:58:14.315160 :9BAE1F6D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15072 pso_num=26 pso_serial#=126 2025-05-25 05:58:14.315296 :9BAE1F6E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15072 osp_idx=24 osp_ver=324741 osp_pg=0 (spawn #216933) 2025-05-25 05:58:14.315296 :9BAE1F6F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 05:58:14.315296 :9BAE1F70:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=23 time=1128466563 2025-05-25 05:58:14.315297 :9BAE1F71:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 05:58:14.315297 :9BAE1F72:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 05:58:14.315297 :9BAE1F73:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 05:58:14.315298 :9BAE1F74:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 05:58:14.315298 :9BAE1F75:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 05:58:14.315794 :9BAE1F76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 05:58:14.315839 :9BAE1F7C:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-25 05:58:14.335187 :9BAE1FA6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:58:34.649373 :9BAE25A4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:58:34.641 2025-05-25 05:58:34.649376 :9BAE25A5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14892 pso_num=26 pso_serial#=127 2025-05-25 05:58:34.649517 :9BAE25A6:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14892 (legacy spawn) 2025-05-25 05:58:34.655977 :9BAE25A7: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-05-25 05:58:34.656160 :9BAE25AC: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 2025-05-25 05:58:34.657166 :9BAE25BD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:58:39.128380 :9BAE270D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:58:39.114 2025-05-25 05:58:39.128383 :9BAE270E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13976 pso_num=26 pso_serial#=128 2025-05-25 05:58:39.128525 :9BAE270F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13976 (legacy spawn) 2025-05-25 05:58:39.135017 :9BAE2710: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-05-25 05:58:39.135185 :9BAE2719: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 2025-05-25 05:58:39.135986 :9BAE271A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:58:39.144741 :9BAE271D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:58:39.130 2025-05-25 05:58:39.144743 :9BAE271E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13400 pso_num=26 pso_serial#=129 2025-05-25 05:58:39.144877 :9BAE271F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13400 (legacy spawn) 2025-05-25 05:58:39.151273 :9BAE2720: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-05-25 05:58:39.151441 :9BAE2726: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 2025-05-25 05:58:39.152250 :9BAE272A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:59:15.460698 :9BAE33AC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:59:15.446 2025-05-25 05:59:15.460699 :9BAE33AD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13764 pso_num=26 pso_serial#=130 2025-05-25 05:59:15.460838 :9BAE33AE:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13764 (legacy spawn) 2025-05-25 05:59:15.467292 :9BAE33AF: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-05-25 05:59:15.510340 :9BAE33B4: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 2025-05-25 05:59:15.511245 :9BAE33B9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:59:15.531702 :9BAE33BC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:59:15.524 2025-05-25 05:59:15.531704 :9BAE33BD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12480 pso_num=26 pso_serial#=131 2025-05-25 05:59:15.531838 :9BAE33BE:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12480 (legacy spawn) 2025-05-25 05:59:15.538332 :9BAE33BF: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-05-25 05:59:15.538491 :9BAE33C8: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 2025-05-25 05:59:15.539309 :9BAE33C9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:59:18.834194 :9BAE34CC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:59:18.821 2025-05-25 05:59:18.834196 :9BAE34CD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=1112 pso_num=26 pso_serial#=132 2025-05-25 05:59:18.834332 :9BAE34CE:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=1112 (legacy spawn) 2025-05-25 05:59:18.840789 :9BAE34CF: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-05-25 05:59:18.840980 :9BAE34D4: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 2025-05-25 05:59:18.841793 :9BAE34D9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:59:18.850526 :9BAE34DC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:59:18.836 2025-05-25 05:59:18.850528 :9BAE34DD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9988 pso_num=26 pso_serial#=133 2025-05-25 05:59:18.850667 :9BAE34DE:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9988 (legacy spawn) 2025-05-25 05:59:18.857198 :9BAE34DF: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-05-25 05:59:18.857356 :9BAE34E8: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 2025-05-25 05:59:18.858192 :9BAE34E9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:59:18.860515 :9BAE34EC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:59:18.852 2025-05-25 05:59:18.860517 :9BAE34ED:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12592 pso_num=26 pso_serial#=134 2025-05-25 05:59:18.860645 :9BAE34EE:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12592 (legacy spawn) 2025-05-25 05:59:18.867077 :9BAE34EF: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-05-25 05:59:18.867231 :9BAE34F8: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 2025-05-25 05:59:18.868023 :9BAE34F9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 05:59:23.546588 :9BAE3690:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 05:59:23.538 2025-05-25 05:59:23.546590 :9BAE3691:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14576 pso_num=26 pso_serial#=135 2025-05-25 05:59:23.546724 :9BAE3692:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14576 (legacy spawn) 2025-05-25 05:59:23.553175 :9BAE3693: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-05-25 05:59:23.553361 :9BAE3698: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 2025-05-25 05:59:23.554167 :9BAE369D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 06:00:02.033248 :9BAE440D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 06:00:02.022 2025-05-25 06:00:02.033250 :9BAE440E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15188 pso_num=26 pso_serial#=136 2025-05-25 06:00:02.033384 :9BAE440F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15188 osp_idx=24 osp_ver=324751 osp_pg=0 (spawn #216939) 2025-05-25 06:00:02.033385 :9BAE4410:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 06:00:02.033385 :9BAE4411:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=24 time=1128574282 2025-05-25 06:00:02.033385 :9BAE4412:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 06:00:02.033386 :9BAE4413:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 06:00:02.033386 :9BAE4414:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 06:00:02.033387 :9BAE4415:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 06:00:02.033387 :9BAE4416:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 06:00:02.033888 :9BAE4417: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-05-25 06:00:02.038909 :9BAE443F: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-05-25 06:00:02.038949 :9BAE4442:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-05-25 06:00:02.042366 :9BAE445D: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-05-25 06:00:02.415382 :9BAE47A0: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-05-25 06:00:02.460371 :9BAE47B0: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-05-25 06:00:02.477081 :9BAE47B8: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-05-25 06:00:02.535806 :9BAE47C0: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-05-25 06:00:02.669054 :9BAE47EB: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-05-25 06:00:02.783129 :9BAE47F3: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-05-25 06:00:02.792883 :9BAE47FB: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-05-25 06:00:02.807593 :9BAE4803: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-05-25 06:00:02.822606 :9BAE480B: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-05-25 06:00:02.837898 :9BAE4813: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-05-25 06:00:02.847723 :9BAE481B: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-05-25 06:00:02.862523 :9BAE4823: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-05-25 06:00:02.871740 :9BAE482B: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-05-25 06:00:02.886794 :9BAE4833: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-05-25 06:00:02.896652 :9BAE483B: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-05-25 06:00:02.905893 :9BAE4843: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-05-25 06:00:02.920720 :9BAE484B: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-05-25 06:00:02.929831 :9BAE485B: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-05-25 06:00:02.945223 :9BAE4867: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-05-25 06:00:02.960941 :9BAE486F: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-05-25 06:00:02.976690 :9BAE4877: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-05-25 06:00:02.984058 :9BAE487F: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-05-25 06:00:02.999123 :9BAE4887: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-05-25 06:00:03.009182 :9BAE488F: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-05-25 06:00:03.023956 :9BAE4897: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-05-25 06:00:03.034198 :9BAE489F: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-05-25 06:00:03.043264 :9BAE48A7: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-05-25 06:00:03.058049 :9BAE48AF: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-05-25 06:00:03.072654 :9BAE48B7: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-05-25 06:00:03.081223 :9BAE48BF: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-05-25 06:00:03.090669 :9BAE48C7: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-05-25 06:00:03.105413 :9BAE48CF: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-05-25 06:00:03.119883 :9BAE48D7: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-05-25 06:00:03.129282 :9BAE48DF: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-05-25 06:00:03.138932 :9BAE48E7: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-05-25 06:00:03.148235 :9BAE48EF: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-05-25 06:00:03.163037 :9BAE48F7: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-05-25 06:00:03.177438 :9BAE48FF: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-05-25 06:00:03.186749 :9BAE4907: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-05-25 06:00:03.201255 :9BAE490F: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-05-25 06:00:03.209664 :9BAE4917: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-05-25 06:00:03.219587 :9BAE491F: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-05-25 06:00:03.229025 :9BAE4927: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-05-25 06:00:03.243819 :9BAE492F: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-05-25 06:00:03.250806 :9BAE4937: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-05-25 06:00:03.258052 :9BAE493F: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-05-25 06:00:03.264991 :9BAE4947: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-05-25 06:00:03.272015 :9BAE494F: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-05-25 06:00:03.279354 :9BAE4957: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-05-25 06:00:03.287204 :9BAE495F: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-05-25 06:00:03.294728 :9BAE4967: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-05-25 06:00:03.302012 :9BAE496F: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-05-25 06:00:03.381665*:9BAE4977:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000291D6918 estart=1748124002 eid=16777341 ctx=0x00000007831EA738 cwh=0x0000000029200248 cpu=78125 buffg=22289 interb=114688 r=7 rb=114688 w=0 wb=0 2025-05-25 06:00:03.381665*:9BAE4978:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000291D6918 curCtx=0x000000003FE71CD0 pubTabIdxCnt=0 2025-05-25 06:00:03.381665*:9BAE4979:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000291D6918 2025-05-25 06:00:03.381665*:9BAE497A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000291D6918 2025-05-25 06:00:03.381665*:9BAE497B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271EC288 estart=1748124001 eid=16777278 ctx=0x000000079F654AA8 cwh=0x00000000271E0158 cpu=1296875 buffg=109854 interb=27066368 r=1375 rb=24772608 w=12 wb=2293760 2025-05-25 06:00:03.381665*:9BAE497C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000271EC288 curCtx=0x000000003FE94F98 pubTabIdxCnt=0 2025-05-25 06:00:03.381665*:9BAE497D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000271EC288 2025-05-25 06:00:03.381665*:9BAE497E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000271EC288 2025-05-25 06:00:03.834800*:9BAE4B21:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000291D6918 curCtx=0x000000003FE71CD0 xsc->flg4=65792 flags=1 2025-05-25 06:00:03.834800*:9BAE4B22:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000291D6918 2025-05-25 06:00:04.369125 :9BAE4B37: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-05-25 06:00:04.371678 :9BAE4B3F: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-05-25 06:00:04.371922 :9BAE4B47: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-05-25 06:00:04.379622 :9BAE4B4F: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-05-25 06:00:04.387215 :9BAE4B57: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-05-25 06:00:04.395632 :9BAE4B5F: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-05-25 06:00:04.395945 :9BAE4B67: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-05-25 06:00:04.395945*:9BAE4B6A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000271EC288 curCtx=0x000000003FE94F98 xsc->flg4=65792 flags=2 2025-05-25 06:00:04.395945*:9BAE4B6B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000271EC288 2025-05-25 06:00:04.396196 :9BAE4B71:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-05-25 06:00:04.400683 :9BAE4B72: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-05-25 06:00:04.400763 :9BAE4B75: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-05-25 06:00:04.400865 :9BAE4B82: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: 15188, J001)