Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250502220005\orcl_j004_8168_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:37578M/63366M, Ph+PgF:45122M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 8168, image: ORACLE.EXE (J004) *** 2025-05-02 22:00:05.499 *** SESSION ID:(241.1273) 2025-05-02 22:00:05.499 *** 2025-05-02 22:00:05.499 Process diagnostic dump for ORACLE.EXE (J004), OS id=8168, pid: 30, proc_ser: 116, sid: 241, sess_ser: 1273 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 8168 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.328165 sec, exc=0.328165 sec, total=0.328165 sec wait times: max=0.500000 sec, heur=1.833451 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.505265 sec, exc=0.505265 sec, total=0.505265 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 241 serial 1273 --------------------------------------------------- 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:03 - 22:00:05] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-02 22:00:05.515 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 8168, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-01 23:59:04.196808 :9170C068:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-01 23:59:04.188 2025-05-01 23:59:04.196810 :9170C069:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=3284 pso_num=30 pso_serial#=104 2025-05-01 23:59:04.196947 :9170C06A:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=3284 (legacy spawn) 2025-05-01 23:59:04.203380 :9170C06B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-01 23:59:04.203563 :9170C070:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-01 23:59:04.204371 :9170C075:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-01 23:59:04.212997 :9170C078:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-01 23:59:04.203 2025-05-01 23:59:04.212999 :9170C079:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=7380 pso_num=30 pso_serial#=105 2025-05-01 23:59:04.213135 :9170C07A:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=7380 (legacy spawn) 2025-05-01 23:59:04.219691 :9170C07B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-01 23:59:04.219851 :9170C083:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-01 23:59:04.220673 :9170C085:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-01 23:59:05.674674 :9170C107:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-01 23:59:05.670 2025-05-01 23:59:05.674677 :9170C108:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9240 pso_num=30 pso_serial#=106 2025-05-01 23:59:05.674805 :9170C109:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=9240 (legacy spawn) 2025-05-01 23:59:05.688461 :9170C10A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-01 23:59:08.867148 :9170C20D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-01 23:59:08.867324 :9170C212:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-01 23:59:08.868520 :9170C217:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-01 23:59:09.127316 :9170C239:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-01 23:59:09.120 2025-05-01 23:59:09.127319 :9170C23A:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9808 pso_num=30 pso_serial#=107 2025-05-01 23:59:09.127456 :9170C23B:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=9808 (legacy spawn) 2025-05-01 23:59:09.141716 :9170C23C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-01 23:59:42.396837 :9170CE05:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-01 23:59:42.528500 :9170CE08:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-01 23:59:42.519 2025-05-01 23:59:42.528503 :9170CE09:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=10760 pso_num=30 pso_serial#=108 2025-05-01 23:59:42.528645 :9170CE0A:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=10760 (legacy spawn) 2025-05-01 23:59:42.542668 :9170CE0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-01 23:59:45.688111 :9170CF39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-01 23:59:45.688279 :9170CF3E:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-01 23:59:45.689535 :9170CF43:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-01 23:59:45.937762 :9170CF46:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-01 23:59:45.930 2025-05-01 23:59:45.937764 :9170CF47:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11972 pso_num=30 pso_serial#=109 2025-05-01 23:59:45.937897 :9170CF48:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=11972 (legacy spawn) 2025-05-01 23:59:45.951896 :9170CF49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 00:00:19.238832 :9170DC54:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-02 01:00:06.309693 :917573DB:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-02 01:00:06.301 2025-05-02 01:00:06.309695 :917573DC:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=4876 pso_num=30 pso_serial#=110 2025-05-02 01:00:06.309834 :917573DD:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=4876 (legacy spawn) 2025-05-02 01:00:06.316261 :917573DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:06.316433 :917573E6:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-02 01:00:06.317279 :917573E8:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-02 01:00:14.426217 :9175765A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-02 01:00:14.421 2025-05-02 01:00:14.426219 :9175765B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11948 pso_num=30 pso_serial#=111 2025-05-02 01:00:14.426356 :9175765C:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=11948 osp_idx=28 osp_ver=620 osp_pg=0 (spawn #138978) 2025-05-02 01:00:14.426357 :9175765D:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 01:00:14.426358 :9175765E:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=27 time=3418403906 2025-05-02 01:00:14.426358 :9175765F:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 01:00:14.426358 :91757660:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 01:00:14.426359 :91757661:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 01:00:14.426359 :91757662:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 01:00:14.426360 :91757663:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 01:00:14.426846 :91757664:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.426894 :9175766A:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-02 01:00:14.431299 :9175767D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.454172 :917576AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.468572 :917576B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.493915 :917576BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.497598 :917576C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.497923 :917576CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.501229 :917576D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.503929 :917576DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.505575 :917576E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.506641 :917576EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:14.510314 :917576F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:15.750969 :91757741:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:15.773148 :91757749:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:15.773437 :91757751:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:15.773709 :91757759:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:15.773724 :9175775C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:15.773794 :9175776B:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-02 01:00:15.776701 :91757776:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-02 01:00:16.094950 :91757806:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-02 01:00:16.081 2025-05-02 01:00:16.094953 :91757807:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11408 pso_num=30 pso_serial#=112 2025-05-02 01:00:16.095100 :91757808:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=11408 (legacy spawn) 2025-05-02 01:00:16.101518 :91757809:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:16.101683 :9175780E:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-02 01:00:16.102506 :91757813:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-02 01:00:16.111054 :91757816:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-02 01:00:16.096 2025-05-02 01:00:16.111056 :91757817:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11452 pso_num=30 pso_serial#=113 2025-05-02 01:00:16.111189 :91757818:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=11452 (legacy spawn) 2025-05-02 01:00:16.117626 :91757819:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:16.117792 :91757822:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-02 01:00:16.118580 :91757823:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-02 01:00:19.321245 :9175790C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-02 01:00:19.308 2025-05-02 01:00:19.321247 :9175790D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=8880 pso_num=30 pso_serial#=114 2025-05-02 01:00:19.321387 :9175790E:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=8880 (legacy spawn) 2025-05-02 01:00:19.327823 :9175790F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:19.328008 :91757914:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-02 01:00:19.328843 :91757919:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-02 01:00:19.337543 :9175791C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-02 01:00:19.324 2025-05-02 01:00:19.337546 :9175791D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=10600 pso_num=30 pso_serial#=115 2025-05-02 01:00:19.337683 :9175791E:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=10600 (legacy spawn) 2025-05-02 01:00:19.344185 :9175791F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 01:00:19.344352 :91757928:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-02 01:00:19.345157 :91757929:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-02 22:00:01.245863 :91D83851:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-02 22:00:01.245 2025-05-02 22:00:01.245864 :91D83852:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=8168 pso_num=30 pso_serial#=116 2025-05-02 22:00:01.245995 :91D83857:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=8168 osp_idx=28 osp_ver=625 osp_pg=0 (spawn #141929) 2025-05-02 22:00:01.245996 :91D83858:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 22:00:01.245996 :91D83859:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=28 time=3493990718 2025-05-02 22:00:01.245997 :91D8385A:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 22:00:01.245997 :91D8385B:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 22:00:01.245998 :91D8385C:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 22:00:01.245998 :91D8385D:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 22:00:01.245998 :91D8385E:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 22:00:01.246510 :91D83864:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.251465 :91D83884:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-05-02 22:00:01.255097 :91D838C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.627921 :91D83A35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.671658 :91D83A45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.687341 :91D83A4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.744643 :91D83A55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.877947 :91D83A70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.991602 :91D83A83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.000992 :91D83A8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.015401 :91D83A93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.029629 :91D83A9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.044394 :91D83AA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.053486 :91D83AAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.067682 :91D83AB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.077131 :91D83ABB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.092487 :91D83AC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.102183 :91D83ACB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.111657 :91D83AD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.126114 :91D83ADB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.135519 :91D83AE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.163080 :91D83AEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.177829 :91D83AF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.192316 :91D83AFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.206581 :91D83B03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.215919 :91D83B0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.231561 :91D83B13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.240818 :91D83B1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.250760 :91D83B23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.265708 :91D83B2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.274868 :91D83B33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.289630 :91D83B3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.296976 :91D83B43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.307190 :91D83B4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.322162 :91D83B53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.336669 :91D83B5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.346429 :91D83B71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.355529 :91D83B79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.364953 :91D83B81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.379614 :91D83B95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.395450 :91D83B9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.409977 :91D83BA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.419255 :91D83BAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.429219 :91D83BB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.438489 :91D83BBD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.447891 :91D83BC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.460353 :91D83BCD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.475082 :91D83BD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.484546 :91D83BDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.491607 :91D83C08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.498825 :91D83C10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.506068 :91D83C18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.513451 :91D83C20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.520974 :91D83C28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.528683 :91D83C30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.536397 :91D83C38:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.543635 :91D83C40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.988451*:91D83CF2:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031686918 estart=1746194401 eid=16777295 ctx=0x00000007831EA738 cwh=0x00000000316B0248 cpu=31250 buffg=45 interb=1040384 r=1 rb=1040384 w=0 wb=0 2025-05-02 22:00:02.988451*:91D83CF3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031686918 curCtx=0x000000003918EDB8 pubTabIdxCnt=0 2025-05-02 22:00:02.988451*:91D83CF4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031686918 2025-05-02 22:00:02.988451*:91D83CF5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031686918 2025-05-02 22:00:02.988451*:91D83CF6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB7C288 estart=1746194400 eid=16777255 ctx=0x000000079F654AA8 cwh=0x000000002DB70158 cpu=1625000 buffg=144982 interb=32104448 r=1440 rb=27516928 w=24 wb=4587520 2025-05-02 22:00:02.988451*:91D83CF7:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB7C288 curCtx=0x0000000031688510 pubTabIdxCnt=0 2025-05-02 22:00:02.988451*:91D83CF8:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB7C288 2025-05-02 22:00:02.988451*:91D83CF9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB7C288 2025-05-02 22:00:03.082669*:91D83D87:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031686918 curCtx=0x000000003918EDB8 xsc->flg4=65792 flags=1 2025-05-02 22:00:03.082669*:91D83D88:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031686918 2025-05-02 22:00:03.630735 :91D83E54:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.633014 :91D83E5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.633326 :91D83E64:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.640913 :91D83E6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.648408 :91D83E74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.656877 :91D83E7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.657213 :91D83E84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.657213*:91D83E87:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB7C288 curCtx=0x0000000031688510 xsc->flg4=65792 flags=2 2025-05-02 22:00:03.657213*:91D83E88:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB7C288 2025-05-02 22:00:03.657469 :91D83E8E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-05-02 22:00:03.659076 :91D83E8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.662217 :91D83E97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.662307 :91D83E9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.662397 :91D83EA7:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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 30 (osid: 8168, J004)