Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250726222249\orcl_j000_17416_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:36035M/63366M, Ph+PgF:41818M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 17416, image: ORACLE.EXE (J000) *** 2025-07-26 22:22:49.349 *** SESSION ID:(177.34961) 2025-07-26 22:22:49.349 *** 2025-07-26 22:22:49.349 Process diagnostic dump for ORACLE.EXE (J000), OS id=17416, pid: 22, proc_ser: 23, sid: 177, sess_ser: 34961 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17416 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.180705 sec, exc=0.180705 sec, total=0.180705 sec wait times: max=0.500000 sec, heur=6.188561 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=11 seq_num=12 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.502407 sec, exc=0.502407 sec, total=0.502407 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500092 sec, exc=0.500092 sec, total=0.500092 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500134 sec, exc=0.500134 sec, total=0.500134 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.503347 sec, exc=0.503347 sec, total=0.503347 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time Sampled Session History of session 177 serial 34961 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:22:43 - 22:22:49] idle wait at each sample [session created at: 22:22:43] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-26 22:22:49.349 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 17416, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-26 22:19:22.979641 :B7650AF3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18732 (legacy spawn) 2025-07-26 22:19:22.986093 :B7650AF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:19:22.986278 :B7650AF9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:19:22.987073 :B7650AFE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:19:22.995829 :B7650B01:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:19:22.980 2025-07-26 22:19:22.995831 :B7650B02:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18536 pso_num=22 pso_serial#=5 2025-07-26 22:19:22.995990 :B7650B03:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18536 (legacy spawn) 2025-07-26 22:19:23.002435 :B7650B04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:19:23.002600 :B7650B0D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:19:23.003373 :B7650B0E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:19:26.087593 :B7650BF6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:19:26.074 2025-07-26 22:19:26.087595 :B7650BF7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6376 pso_num=22 pso_serial#=6 2025-07-26 22:19:26.087731 :B7650BF8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=6376 (legacy spawn) 2025-07-26 22:19:26.094155 :B7650BF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:19:26.094326 :B7650BFE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:19:26.095141 :B7650C03:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:19:26.114043 :B7650C06:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:19:26.105 2025-07-26 22:19:26.114045 :B7650C07:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16212 pso_num=22 pso_serial#=7 2025-07-26 22:19:26.114197 :B7650C08:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16212 (legacy spawn) 2025-07-26 22:19:26.120656 :B7650C09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:19:26.120841 :B7650C10:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:19:26.121692 :B7650C13:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:19:39.727639 :B7651020:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:19:39.719 2025-07-26 22:19:39.727641 :B7651021:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19292 pso_num=22 pso_serial#=8 2025-07-26 22:19:39.727782 :B7651022:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19292 (legacy spawn) 2025-07-26 22:19:39.734321 :B7651023:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:19:39.734493 :B7651028:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:19:39.735307 :B765102D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:19:42.938257 :B7651146:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:19:42.932 2025-07-26 22:19:42.938259 :B7651147:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11144 pso_num=22 pso_serial#=9 2025-07-26 22:19:42.938393 :B7651148:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11144 osp_idx=0 osp_ver=455766 osp_pg=0 (spawn #425868) 2025-07-26 22:19:42.938394 :B7651149:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:19:42.938394 :B765114A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=133 time=2249158829 2025-07-26 22:19:42.938395 :B765114B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:19:42.938395 :B765114C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:19:42.938395 :B765114D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:19:42.938396 :B765114E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:19:42.938396 :B765114F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:19:42.938893 :B7651150:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 22:19:42.939550 :B7651165:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:19:42.947126 :B7651181:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-26 22:19:42.947493 :B7651182:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:19:42.947528 :B7651185:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-26 22:19:42.948477 :B765118B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:19:42.948600 :B7651190:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:20:07.967559 :B76519E6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-26 22:20:07.967582*:B76519E9:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCCBB58 estart=1753539607 eid=16976261 ctx=0x000000077BD98A08 cwh=0x000000000FC802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-26 22:20:07.967582*:B76519EA:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 pubTabIdxCnt=0 2025-07-26 22:20:07.967582*:B76519EB:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCCBB58 2025-07-26 22:20:07.967582*:B76519EC:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCCBB58 2025-07-26 22:20:07.967582*:B76519ED:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 xsc->flg4=65792 flags=2 2025-07-26 22:20:07.967582*:B76519EE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCCBB58 2025-07-26 22:20:07.968130 :B76519EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:20:07.973442 :B76519F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:20:07.974234 :B76519FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:20:07.974367 :B7651A08:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:20:38.062940 :B7652393:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:20:39.185089 :B76523AE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:20:39.172 2025-07-26 22:20:39.185091 :B76523AF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18004 pso_num=22 pso_serial#=10 2025-07-26 22:20:39.185229 :B76523B0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18004 (legacy spawn) 2025-07-26 22:20:39.191745 :B76523B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:20:39.191921 :B76523B6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:20:39.192760 :B76523BB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:20:39.201404 :B76523BE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:20:39.188 2025-07-26 22:20:39.201406 :B76523BF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=316 pso_num=22 pso_serial#=11 2025-07-26 22:20:39.201555 :B76523C0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=316 (legacy spawn) 2025-07-26 22:20:39.208071 :B76523C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:20:39.208238 :B76523C8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:20:39.209051 :B76523CB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:20:41.963117 :B76524AB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:20:41.954 2025-07-26 22:20:41.963119 :B76524AC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16768 pso_num=22 pso_serial#=12 2025-07-26 22:20:41.963258 :B76524AD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16768 (legacy spawn) 2025-07-26 22:20:41.969764 :B76524AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:20:41.969946 :B76524B3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:20:41.970742 :B76524B8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:20:43.006854 :B7652557:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:20:43.001 2025-07-26 22:20:43.006856 :B7652558:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17352 pso_num=22 pso_serial#=13 2025-07-26 22:20:43.007009 :B7652559:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17352 osp_idx=0 osp_ver=455770 osp_pg=0 (spawn #425870) 2025-07-26 22:20:43.007010 :B765255A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:20:43.007011 :B765255B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=134 time=2249218908 2025-07-26 22:20:43.007011 :B765255C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:20:43.007011 :B765255D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:20:43.007012 :B765255E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:20:43.007012 :B765255F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:20:43.007013 :B7652560:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:20:43.007541 :B7652561:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 22:20:43.008208 :B7652576:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:20:43.019230 :B7652592:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-26 22:20:43.019742 :B7652593:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-26 22:20:43.022077 :B7652594:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-26 22:20:43.022415 :B7652595:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:20:43.022453 :B7652598:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-26 22:20:43.023315 :B765259E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:20:43.023446 :B76525A7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:21:13.080496 :B7652EF1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:21:15.363531 :B7652F63:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:21:15.361 2025-07-26 22:21:15.363534 :B7652F64:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17944 pso_num=22 pso_serial#=14 2025-07-26 22:21:15.363678 :B7652F65:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17944 (legacy spawn) 2025-07-26 22:21:15.379686 :B7652F66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:21:15.379892 :B7652F6B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:21:15.380764 :B7652F70:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:21:15.389413 :B7652F73:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:21:15.377 2025-07-26 22:21:15.389415 :B7652F74:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17132 pso_num=22 pso_serial#=15 2025-07-26 22:21:15.389551 :B7652F75:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17132 (legacy spawn) 2025-07-26 22:21:15.396029 :B7652F76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:21:15.396198 :B7652F7F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:21:15.397019 :B7652F80:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:21:35.627480 :B76535D3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:21:35.627 2025-07-26 22:21:35.627483 :B76535D4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10908 pso_num=22 pso_serial#=16 2025-07-26 22:21:35.627630 :B76535D5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10908 (legacy spawn) 2025-07-26 22:21:35.634204 :B76535D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:21:35.634383 :B76535DB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:21:35.635230 :B76535EC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:21:43.082288 :B7653860:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:21:43.077 2025-07-26 22:21:43.082290 :B7653861:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4284 pso_num=22 pso_serial#=17 2025-07-26 22:21:43.082424 :B7653862:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4284 osp_idx=0 osp_ver=455774 osp_pg=0 (spawn #425872) 2025-07-26 22:21:43.082424 :B7653863:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:21:43.082425 :B7653864:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=135 time=2249278970 2025-07-26 22:21:43.082426 :B7653865:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:21:43.082426 :B7653866:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:21:43.082426 :B7653867:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:21:43.082427 :B7653868:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:21:43.082427 :B7653869:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:21:43.082958 :B765386A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 22:21:43.083599 :B765387F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:21:43.091150 :B765389B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-26 22:21:43.091564 :B765389C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:21:43.091605 :B765389F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-26 22:21:43.092484 :B76538A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:21:43.092603 :B76538AE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:22:13.186615 :B76542FA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:22:15.152021 :B7654361:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:22:15.139 2025-07-26 22:22:15.152023 :B7654362:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19172 pso_num=22 pso_serial#=18 2025-07-26 22:22:15.152158 :B7654363:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19172 (legacy spawn) 2025-07-26 22:22:15.158593 :B7654364:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:22:15.158762 :B7654369:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:22:15.159552 :B765436E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:22:25.197233 :B765469F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:22:25.189 2025-07-26 22:22:25.197235 :B76546A0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19176 pso_num=22 pso_serial#=19 2025-07-26 22:22:25.197369 :B76546A1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19176 (legacy spawn) 2025-07-26 22:22:25.203805 :B76546A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:22:25.203989 :B76546A7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:22:25.204842 :B76546BA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:22:25.213689 :B76546BD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:22:25.205 2025-07-26 22:22:25.213691 :B76546BE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17844 pso_num=22 pso_serial#=20 2025-07-26 22:22:25.213835 :B76546BF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17844 (legacy spawn) 2025-07-26 22:22:25.220349 :B76546C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:22:25.220518 :B76546C9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:22:25.221487 :B76546CA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:22:28.482892 :B76547C0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:22:28.470 2025-07-26 22:22:28.482894 :B76547C1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18360 pso_num=22 pso_serial#=21 2025-07-26 22:22:28.483040 :B76547C2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18360 (legacy spawn) 2025-07-26 22:22:28.489618 :B76547C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:22:28.489799 :B76547C8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:22:28.490590 :B76547CD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:22:28.499295 :B76547D0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:22:28.485 2025-07-26 22:22:28.499297 :B76547D1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18760 pso_num=22 pso_serial#=22 2025-07-26 22:22:28.499446 :B76547D2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18760 (legacy spawn) 2025-07-26 22:22:28.505859 :B76547D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:22:28.506032 :B76547DC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-26 22:22:28.506851 :B76547DD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-26 22:22:43.150920 :B7654C4F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-26 22:22:43.145 2025-07-26 22:22:43.150922 :B7654C50:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17416 pso_num=22 pso_serial#=23 2025-07-26 22:22:43.151065 :B7654C51:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17416 osp_idx=0 osp_ver=455780 osp_pg=0 (spawn #425874) 2025-07-26 22:22:43.151066 :B7654C52:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:22:43.151066 :B7654C53:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=136 time=2249339048 2025-07-26 22:22:43.151067 :B7654C54:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:22:43.151067 :B7654C55:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:22:43.151067 :B7654C56:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:22:43.151068 :B7654C57:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:22:43.151068 :B7654C58:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:22:43.151559 :B7654C59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 22:22:43.152196 :B7654C6E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:22:43.163255 :B7654C8A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-26 22:22:43.163725 :B7654C8B:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-26 22:22:43.166122 :B7654C8C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-26 22:22:43.166463 :B7654C8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:22:43.166498 :B7654C90:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-26 22:22:43.167364 :B7654C96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 22:22:43.167481 :B7654C9F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 17416, J000)