Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250901221833\orcl_j000_18472_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:35051M/63366M, Ph+PgF:40009M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 18472, image: ORACLE.EXE (J000) *** 2025-09-01 22:18:33.558 *** SESSION ID:(193.12235) 2025-09-01 22:18:33.558 *** 2025-09-01 22:18:33.558 Process diagnostic dump for ORACLE.EXE (J000), OS id=18472, pid: 24, proc_ser: 50, sid: 193, sess_ser: 12235 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18472 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.061750 sec, exc=0.061750 sec, total=0.061750 sec wait times: max=0.500000 sec, heur=0.061750 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.000000 sec since current wait Sampled Session History of session 193 serial 12235 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:18:33] idle wait at each sample [session created at: 22:18:33] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-01 22:18:33.558 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 18472, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-01 22:15:11.744111 :C7B6756C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:15:11.744880 :C7B6756D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:15:33.187149 :C7B67C1B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:15:33.181 2025-09-01 22:15:33.187151 :C7B67C1C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16476 pso_num=24 pso_serial#=32 2025-09-01 22:15:33.187290 :C7B67C1D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16476 osp_idx=22 osp_ver=826586 osp_pg=0 (spawn #549201) 2025-09-01 22:15:33.187290 :C7B67C1E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:15:33.187291 :C7B67C1F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=187 time=1150584487 2025-09-01 22:15:33.187291 :C7B67C20:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:15:33.187291 :C7B67C21:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:15:33.187292 :C7B67C22:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:15:33.187292 :C7B67C23:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:15:33.187292 :C7B67C24:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:15:33.187784 :C7B67C25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-01 22:15:33.188491 :C7B67C3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:15:33.195996 :C7B67C56:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-09-01 22:15:33.196435 :C7B67C57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:15:33.196478 :C7B67C5A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-09-01 22:15:33.197349 :C7B67C60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:15:33.197475 :C7B67C69:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:16:03.296783 :C7B68628:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:16:07.451429 :C7B68770:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:16:07.443 2025-09-01 22:16:07.451431 :C7B68771:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7196 pso_num=24 pso_serial#=33 2025-09-01 22:16:07.451567 :C7B68772:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7196 (legacy spawn) 2025-09-01 22:16:07.458029 :C7B68773:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:07.458214 :C7B68778:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:16:07.459066 :C7B6877D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:16:15.444269 :C7B68A01:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:16:15.435 2025-09-01 22:16:15.444271 :C7B68A02:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20996 pso_num=24 pso_serial#=34 2025-09-01 22:16:15.444412 :C7B68A03:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20996 (legacy spawn) 2025-09-01 22:16:15.450919 :C7B68A04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:15.451087 :C7B68A09:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:16:15.451894 :C7B68A0E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:16:21.116153 :C7B68BD4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:16:21.113 2025-09-01 22:16:21.116155 :C7B68BD5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21800 pso_num=24 pso_serial#=35 2025-09-01 22:16:21.116300 :C7B68BD6:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21800 (legacy spawn) 2025-09-01 22:16:21.122815 :C7B68BD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:21.122982 :C7B68BE0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:16:21.123827 :C7B68BE1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:16:21.132660 :C7B68BE4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:16:21.128 2025-09-01 22:16:21.132661 :C7B68BE5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22084 pso_num=24 pso_serial#=36 2025-09-01 22:16:21.132801 :C7B68BE6:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22084 (legacy spawn) 2025-09-01 22:16:21.139490 :C7B68BE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:21.139657 :C7B68BF0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:16:21.140498 :C7B68BF1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:16:24.314516 :C7B68CF5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:16:24.301 2025-09-01 22:16:24.314519 :C7B68CF6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2268 pso_num=24 pso_serial#=37 2025-09-01 22:16:24.314662 :C7B68CF7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=2268 (legacy spawn) 2025-09-01 22:16:24.321159 :C7B68CF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:24.321333 :C7B68CFD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:16:24.322145 :C7B68D02:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:16:24.330889 :C7B68D05:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:16:24.317 2025-09-01 22:16:24.330891 :C7B68D06:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23304 pso_num=24 pso_serial#=38 2025-09-01 22:16:24.331027 :C7B68D07:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23304 (legacy spawn) 2025-09-01 22:16:24.337520 :C7B68D08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:24.337551 :C7B68D0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:24.337691 :C7B68D12:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:16:24.338484 :C7B68D1F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:16:27.321758 :C7B68E13:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:16:27.309 2025-09-01 22:16:27.321760 :C7B68E14:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23260 pso_num=24 pso_serial#=39 2025-09-01 22:16:27.321907 :C7B68E15:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23260 (legacy spawn) 2025-09-01 22:16:27.328423 :C7B68E16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:27.328587 :C7B68E1B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:16:27.329368 :C7B68E20:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:16:27.338070 :C7B68E23:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:16:27.324 2025-09-01 22:16:27.338072 :C7B68E24:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22040 pso_num=24 pso_serial#=40 2025-09-01 22:16:27.338212 :C7B68E25:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22040 (legacy spawn) 2025-09-01 22:16:27.344705 :C7B68E26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:27.344867 :C7B68E2F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:16:27.345683 :C7B68E30:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:16:33.282308 :C7B69026:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:16:33.277 2025-09-01 22:16:33.282309 :C7B69027:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23092 pso_num=24 pso_serial#=41 2025-09-01 22:16:33.282444 :C7B69028:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23092 osp_idx=22 osp_ver=826595 osp_pg=0 (spawn #549203) 2025-09-01 22:16:33.282444 :C7B69029:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:16:33.282445 :C7B6902A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=188 time=1150644580 2025-09-01 22:16:33.282445 :C7B6902B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:16:33.282445 :C7B6902C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:16:33.282446 :C7B6902D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:16:33.282446 :C7B6902E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:16:33.282446 :C7B6902F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:16:33.282932 :C7B69030:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-01 22:16:33.283568 :C7B69045:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:33.294734 :C7B69061:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-01 22:16:33.295193 :C7B69062:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-01 22:16:33.297559 :C7B69063:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-09-01 22:16:33.297902 :C7B69064:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:33.297938 :C7B69067:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-09-01 22:16:33.298795 :C7B6906D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:16:33.298918 :C7B69076:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:17:03.364139 :C7B69A51:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:17:09.607743 :C7B69C32:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:17:09.599 2025-09-01 22:17:09.607745 :C7B69C33:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22148 pso_num=24 pso_serial#=42 2025-09-01 22:17:09.607882 :C7B69C34:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22148 (legacy spawn) 2025-09-01 22:17:09.614435 :C7B69C35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:17:09.614609 :C7B69C3A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:17:09.615430 :C7B69C3F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:17:23.838650 :C7B6A0DC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:17:23.825 2025-09-01 22:17:23.838652 :C7B6A0DD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20536 pso_num=24 pso_serial#=43 2025-09-01 22:17:23.838795 :C7B6A0DE:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20536 (legacy spawn) 2025-09-01 22:17:23.845333 :C7B6A0DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:17:23.845499 :C7B6A0E6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:17:23.846331 :C7B6A0E9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:17:33.367162 :C7B6A3DF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:17:33.361 2025-09-01 22:17:33.367164 :C7B6A3E0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23500 pso_num=24 pso_serial#=44 2025-09-01 22:17:33.367302 :C7B6A3E1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23500 osp_idx=22 osp_ver=826598 osp_pg=0 (spawn #549205) 2025-09-01 22:17:33.367302 :C7B6A3E2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:17:33.367303 :C7B6A3E3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=189 time=1150704674 2025-09-01 22:17:33.367303 :C7B6A3E4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:17:33.367304 :C7B6A3E5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:17:33.367304 :C7B6A3E6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:17:33.367305 :C7B6A3E7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:17:33.367305 :C7B6A3E8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:17:33.367812 :C7B6A3E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-01 22:17:33.368510 :C7B6A3FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:17:33.376252 :C7B6A41A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-09-01 22:17:33.376664 :C7B6A41B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:17:33.376701 :C7B6A41E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-09-01 22:17:33.377570 :C7B6A424:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:17:33.377689 :C7B6A42D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:18:03.475420 :C7B6AE58:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:18:10.401755 :C7B6B08E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:18:10.387 2025-09-01 22:18:10.401758 :C7B6B08F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21356 pso_num=24 pso_serial#=45 2025-09-01 22:18:10.401903 :C7B6B090:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21356 (legacy spawn) 2025-09-01 22:18:10.408376 :C7B6B091:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:10.408557 :C7B6B096:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:18:10.409348 :C7B6B09B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:18:10.418031 :C7B6B09E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:18:10.402 2025-09-01 22:18:10.418033 :C7B6B09F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23352 pso_num=24 pso_serial#=46 2025-09-01 22:18:10.418206 :C7B6B0A0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23352 (legacy spawn) 2025-09-01 22:18:10.424684 :C7B6B0A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:10.424841 :C7B6B0AA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:18:10.425646 :C7B6B0AB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:18:11.796122 :C7B6B107:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:18:11.788 2025-09-01 22:18:11.796125 :C7B6B108:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21816 pso_num=24 pso_serial#=47 2025-09-01 22:18:11.796291 :C7B6B109:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21816 (legacy spawn) 2025-09-01 22:18:11.802823 :C7B6B10A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:11.802995 :C7B6B111:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:18:11.803884 :C7B6B114:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:18:13.504170 :C7B6B1B7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:18:13.491 2025-09-01 22:18:13.504173 :C7B6B1B8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22988 pso_num=24 pso_serial#=48 2025-09-01 22:18:13.504307 :C7B6B1B9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22988 (legacy spawn) 2025-09-01 22:18:13.510798 :C7B6B1BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:13.510968 :C7B6B1BF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:18:13.511754 :C7B6B1C4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:18:13.520511 :C7B6B1C7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:18:13.506 2025-09-01 22:18:13.520513 :C7B6B1C8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15732 pso_num=24 pso_serial#=49 2025-09-01 22:18:13.520655 :C7B6B1C9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15732 (legacy spawn) 2025-09-01 22:18:13.541344 :C7B6B1CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:13.541509 :C7B6B1D3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:18:13.542311 :C7B6B1D4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-01 22:18:18.448935 :C7B6B366:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-01 22:18:18.443 2025-09-01 22:18:18.448937 :C7B6B367:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18472 pso_num=24 pso_serial#=50 2025-09-01 22:18:18.449083 :C7B6B368:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18472 osp_idx=22 osp_ver=826604 osp_pg=0 (spawn #549207) 2025-09-01 22:18:18.449083 :C7B6B369:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:18:18.449084 :C7B6B36A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=190 time=1150749752 2025-09-01 22:18:18.449084 :C7B6B36B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:18:18.449084 :C7B6B36C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:18:18.449085 :C7B6B36D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:18:18.449085 :C7B6B36E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:18:18.449085 :C7B6B36F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:18:18.449591 :C7B6B370:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-01 22:18:18.450241 :C7B6B385:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:18.455655 :C7B6B3A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:18.456619 :C7B6B3A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:18.456759 :C7B6B3B2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-01 22:18:33.480081 :C7B728BA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-01 22:18:33.480103*:C7B728BD:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D4BB58 estart=1756736312 eid=17034965 ctx=0x000000077BD98A08 cwh=0x00000000232802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-01 22:18:33.480103*:C7B728BE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D4BB58 curCtx=0x000000002328F300 pubTabIdxCnt=0 2025-09-01 22:18:33.480103*:C7B728BF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D4BB58 2025-09-01 22:18:33.480103*:C7B728C0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D4BB58 2025-09-01 22:18:33.480103*:C7B728C1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D4BB58 curCtx=0x000000002328F300 xsc->flg4=65792 flags=2 2025-09-01 22:18:33.480103*:C7B728C2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D4BB58 2025-09-01 22:18:33.480633 :C7B728C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:33.491527 :C7B728D0:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-01 22:18:33.491976 :C7B728D2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-01 22:18:33.494296 :C7B728D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-09-01 22:18:33.494762 :C7B728D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:33.494796 :C7B728D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-09-01 22:18:33.495730 :C7B728DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:18:33.495834 :C7B728E6: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: 18472, J000)