Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250626220005\orcl_j000_7180_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:36326M/63366M, Ph+PgF:42485M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 7180, image: ORACLE.EXE (J000) *** 2025-06-26 22:00:05.949 *** SESSION ID:(193.28359) 2025-06-26 22:00:05.949 *** 2025-06-26 22:00:05.949 Process diagnostic dump for ORACLE.EXE (J000), OS id=7180, pid: 24, proc_ser: 3, sid: 193, sess_ser: 28359 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 7180 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) 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.428526 sec, exc=0.428526 sec, total=0.428526 sec wait times: max=0.500000 sec, heur=1.914889 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=2 seq_num=3 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 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=1 seq_num=2 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 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.486342 sec, exc=0.486342 sec, total=0.486342 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 28359 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [2 samples, 22:00:04 - 22:00:05] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-26 22:00:05.949 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 7180, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-26 21:57:12.977380 :AA0D77A7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12564 pso_num=24 pso_serial#=243 2025-06-26 21:57:12.977511 :AA0D77A8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12564 (legacy spawn) 2025-06-26 21:57:12.984095 :AA0D77A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:57:12.984259 :AA0D77AF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:57:12.985083 :AA0D77B3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:57:13.438773 :AA0D77EB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:57:13.424 2025-06-26 21:57:13.438775 :AA0D77EC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13600 pso_num=24 pso_serial#=244 2025-06-26 21:57:13.438913 :AA0D77ED:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13600 (legacy spawn) 2025-06-26 21:57:13.445448 :AA0D77EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:57:13.445619 :AA0D77F7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:57:13.446468 :AA0D77F8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:57:13.455366 :AA0D77FB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:57:13.455 2025-06-26 21:57:13.455368 :AA0D77FC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12024 pso_num=24 pso_serial#=245 2025-06-26 21:57:13.455525 :AA0D77FD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12024 (legacy spawn) 2025-06-26 21:57:13.462219 :AA0D77FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:57:13.462406 :AA0D7805:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:57:13.463245 :AA0D7808:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:57:16.599421 :AA0D78F0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:57:16.586 2025-06-26 21:57:16.599423 :AA0D78F1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14000 pso_num=24 pso_serial#=246 2025-06-26 21:57:16.599566 :AA0D78F2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14000 (legacy spawn) 2025-06-26 21:57:16.606190 :AA0D78F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:57:16.711114 :AA0D78F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:57:16.792202 :AA0D78F9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:57:16.793130 :AA0D7918:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:57:16.813953 :AA0D791B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:57:16.805 2025-06-26 21:57:16.813955 :AA0D791C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7184 pso_num=24 pso_serial#=247 2025-06-26 21:57:16.814098 :AA0D791D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7184 (legacy spawn) 2025-06-26 21:57:16.820624 :AA0D791E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:57:16.820786 :AA0D7927:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:57:16.821594 :AA0D7928:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:57:37.042742 :AA0D7F7A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:57:37.037 2025-06-26 21:57:37.042744 :AA0D7F7B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15780 pso_num=24 pso_serial#=248 2025-06-26 21:57:37.042888 :AA0D7F7C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15780 osp_idx=22 osp_ver=468948 osp_pg=0 (spawn #325816) 2025-06-26 21:57:37.042889 :AA0D7F7D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-26 21:57:37.042889 :AA0D7F7E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=22 time=3950800235 2025-06-26 21:57:37.042889 :AA0D7F7F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 21:57:37.042890 :AA0D7F80:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 21:57:37.042890 :AA0D7F81:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 21:57:37.042890 :AA0D7F82:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 21:57:37.042891 :AA0D7F83:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 21:57:37.043386 :AA0D7F84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-26 21:57:37.044027 :AA0D7F99:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:57:37.051577 :AA0D7FB5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-26 21:57:37.051956 :AA0D7FB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:57:37.051990 :AA0D7FB9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-26 21:57:37.052866 :AA0D7FBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:57:37.052980 :AA0D7FC8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:58:07.148520 :AA0D89EF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:58:15.159681 :AA0D8C69:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:58:15.147 2025-06-26 21:58:15.159683 :AA0D8C6A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9468 pso_num=24 pso_serial#=249 2025-06-26 21:58:15.159819 :AA0D8C6B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9468 (legacy spawn) 2025-06-26 21:58:15.166244 :AA0D8C6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:58:15.166408 :AA0D8C71:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:58:15.167220 :AA0D8C76:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:58:18.946343 :AA0D8D8C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:58:18.937 2025-06-26 21:58:18.946345 :AA0D8D8D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7608 pso_num=24 pso_serial#=250 2025-06-26 21:58:18.946483 :AA0D8D8E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7608 (legacy spawn) 2025-06-26 21:58:18.953137 :AA0D8D8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:58:18.953309 :AA0D8D96:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:58:18.954288 :AA0D8D99:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:58:26.207715 :AA0D8FE2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:58:26.193 2025-06-26 21:58:26.207717 :AA0D8FE3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18408 pso_num=24 pso_serial#=251 2025-06-26 21:58:26.207851 :AA0D8FE4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18408 (legacy spawn) 2025-06-26 21:58:26.214273 :AA0D8FE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:58:26.214454 :AA0D8FEE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:58:26.215241 :AA0D8FEF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:58:26.223800 :AA0D8FF2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:58:26.208 2025-06-26 21:58:26.223802 :AA0D8FF3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3264 pso_num=24 pso_serial#=252 2025-06-26 21:58:26.223935 :AA0D8FF4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3264 (legacy spawn) 2025-06-26 21:58:26.230341 :AA0D8FF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:58:26.230505 :AA0D8FFD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:58:26.231317 :AA0D8FFF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:58:29.323900 :AA0D90F3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:58:29.310 2025-06-26 21:58:29.323902 :AA0D90F4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14720 pso_num=24 pso_serial#=253 2025-06-26 21:58:29.324040 :AA0D90F5:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14720 (legacy spawn) 2025-06-26 21:58:29.330474 :AA0D90F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:58:29.330647 :AA0D90FF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:58:29.331464 :AA0D9100:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:58:29.339991 :AA0D9103:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:58:29.326 2025-06-26 21:58:29.339994 :AA0D9104:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=5284 pso_num=24 pso_serial#=254 2025-06-26 21:58:29.340127 :AA0D9105:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=5284 (legacy spawn) 2025-06-26 21:58:29.346785 :AA0D9111:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:58:29.346958 :AA0D911A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:58:29.347794 :AA0D911B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:58:37.137983 :AA0D9385:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:58:37.132 2025-06-26 21:58:37.137986 :AA0D9386:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14752 pso_num=24 pso_serial#=255 2025-06-26 21:58:37.138125 :AA0D9387:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14752 osp_idx=22 osp_ver=468955 osp_pg=0 (spawn #325818) 2025-06-26 21:58:37.138126 :AA0D9388:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-26 21:58:37.138126 :AA0D9389:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=23 time=3950860329 2025-06-26 21:58:37.138127 :AA0D938A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 21:58:37.138127 :AA0D938B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 21:58:37.138127 :AA0D938C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 21:58:37.138128 :AA0D938D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 21:58:37.138128 :AA0D938E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 21:58:37.138629 :AA0D938F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-26 21:58:37.139311 :AA0D93A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:58:37.150569 :AA0D93E3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-26 21:58:37.151011 :AA0D93E4:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-26 21:58:37.153383 :AA0D93E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-26 21:58:37.153729 :AA0D93E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:58:37.153770 :AA0D93E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-26 21:58:37.154665 :AA0D93EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:58:37.154791 :AA0D93F8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:59:07.234098 :AA0D9DC8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:59:17.323706 :AA0DA0E1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:59:17.315 2025-06-26 21:59:17.323708 :AA0DA0E2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16668 pso_num=24 pso_serial#=1 2025-06-26 21:59:17.323846 :AA0DA0E3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16668 (legacy spawn) 2025-06-26 21:59:17.330337 :AA0DA0E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:59:17.330512 :AA0DA0ED:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:59:17.331332 :AA0DA0EE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:59:27.076245 :AA0DA3F9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:59:27.065 2025-06-26 21:59:27.076247 :AA0DA3FA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18044 pso_num=24 pso_serial#=2 2025-06-26 21:59:27.076385 :AA0DA3FB:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18044 (legacy spawn) 2025-06-26 21:59:27.083005 :AA0DA3FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:59:27.084082 :AA0DA405:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 21:59:27.084862 :AA0DA406:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-26 21:59:37.221059 :AA0DA749:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-26 21:59:37.214 2025-06-26 21:59:37.221062 :AA0DA74A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7180 pso_num=24 pso_serial#=3 2025-06-26 21:59:37.221214 :AA0DA74B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7180 osp_idx=22 osp_ver=468958 osp_pg=0 (spawn #325821) 2025-06-26 21:59:37.221215 :AA0DA74C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-26 21:59:37.221216 :AA0DA74D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=24 time=3950920407 2025-06-26 21:59:37.221216 :AA0DA74E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 21:59:37.221216 :AA0DA74F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 21:59:37.221217 :AA0DA750:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 21:59:37.221217 :AA0DA751:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 21:59:37.221218 :AA0DA752:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 21:59:37.221791 :AA0DA753:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-26 21:59:37.222552 :AA0DA768:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:59:37.230729 :AA0DA784:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-26 21:59:37.231138 :AA0DA785:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:59:37.231182 :AA0DA788:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-26 21:59:37.232177 :AA0DA78E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 21:59:37.232345 :AA0DA796:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 22:00:02.037012 :AA0DB193:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-26 22:00:02.037012*:AA0DB1BC:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1750946401 eid=16783071 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-26 22:00:02.037012*:AA0DB1BD:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-06-26 22:00:02.037012*:AA0DB1BE:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-06-26 22:00:02.037012*:AA0DB1BF:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-06-26 22:00:02.037012*:AA0DB1C1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-06-26 22:00:02.037012*:AA0DB1C2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-06-26 22:00:02.043772 :AA0DB1D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.043823 :AA0DB1DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-26 22:00:02.053703 :AA0DB210:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.055783 :AA0DB242:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.060864 :AA0DB395:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.061642 :AA0DB3AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.067169 :AA0DB3BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.067474 :AA0DB3CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.068282 :AA0DB3D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.068511 :AA0DB3DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-26 22:00:02.069474 :AA0DB3E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.069689 :AA0DB3E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.069753 :AA0DB3E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.070241 :AA0DB3E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.070357 :AA0DB3FD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 22:00:02.161964 :AA0DB49D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-26 22:00:02.164111 :AA0DB4A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.164154 :AA0DB4A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-26 22:00:02.166625 :AA0DB4B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-26 22:00:02.169903 :AA0DB4B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.169982 :AA0DB4BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-26 22:00:02.170068 :AA0DB4C8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-26 22:00:04.024156 :AA0DB8BB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-26 22:00:04.024156*:AA0DB8BC:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1750946403 eid=16783076 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-26 22:00:04.024156*:AA0DB8BD:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-06-26 22:00:04.024156*:AA0DB8BE:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-06-26 22:00:04.024156*:AA0DB8BF:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-06-26 22:00:04.024156*:AA0DB8C0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-06-26 22:00:04.024156*:AA0DB8C1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-06-26 22:00:04.026125 :AA0DB8C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:04.026160 :AA0DB8C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-26 22:00:04.031051 :AA0DB8CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:04.031694 :AA0DB8D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:04.032308 :AA0DB8DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:04.032471 :AA0DB8E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-26 22:00:04.033680 :AA0DB8E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 22:00:04.033711 :AA0DB8E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-26 22:00:04.033802 :AA0DB8EF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 7180, J000)