Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250517100704\orcl_m000_12296_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:37358M/63366M, Ph+PgF:44567M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 12296, image: ORACLE.EXE (M000) *** 2025-05-17 10:07:04.191 *** 2025-05-17 10:07:04.191 Process diagnostic dump for ORACLE.EXE (M000), OS id=12296, pid: 31, proc_ser: 98, sid: ------------------------------------------------------------------------------- Skipping session state dump because target process has no session. ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-17 10:07:04.191 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 12296, M000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-16 23:59:55.014763 :980DE782:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-16 23:59:55.007 2025-05-16 23:59:55.014765 :980DE783:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=7776 pso_num=31 pso_serial#=90 2025-05-16 23:59:55.014893 :980DE784:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=7776 (legacy spawn) 2025-05-16 23:59:55.028703 :980DE785:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 00:00:28.224500 :980DF4CA:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-17 00:00:34.651738 :980DF73A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-17 00:00:34.642 2025-05-17 00:00:34.651739 :980DF73B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13700 pso_num=31 pso_serial#=91 2025-05-17 00:00:34.651877 :980DF73C:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=13700 osp_idx=29 osp_ver=345 osp_pg=0 (spawn #189442) 2025-05-17 00:00:34.651878 :980DF73D:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 00:00:34.651878 :980DF73E:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=118 time=415844516 2025-05-17 00:00:34.651879 :980DF73F:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 00:00:34.651879 :980DF740:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 00:00:34.651880 :980DF741:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 00:00:34.651880 :980DF742:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 00:00:34.651880 :980DF743:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 00:00:34.652363 :980DF744:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 00:00:34.652403 :980DF74A:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 00:00:34.661092 :980DF75F:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-17 06:00:01.706648 :9829EB3B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-17 06:00:01.701 2025-05-17 06:00:01.706649 :9829EB3C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=2884 pso_num=31 pso_serial#=92 2025-05-17 06:00:01.706787 :9829EB3D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=2884 osp_idx=29 osp_ver=346 osp_pg=0 (spawn #190274) 2025-05-17 06:00:01.706788 :9829EB3E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 06:00:01.706789 :9829EB3F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=119 time=437411563 2025-05-17 06:00:01.706789 :9829EB40:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 06:00:01.706790 :9829EB41:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 06:00:01.706790 :9829EB42:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 06:00:01.706791 :9829EB43:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 06:00:01.706791 :9829EB44:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 06:00:01.707422 :9829EB4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.712535 :9829ECD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.712563 :9829ECDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:jslv.c:9203 2025-05-17 06:00:01.729662 :9829ED7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.729854 :9829ED84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.730026 :9829ED8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.730206 :9829ED94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.730440 :9829ED9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.730585 :9829EDA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.730609 :9829EDAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.730746 :9829EDBD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.730771 :9829EDBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.730910 :9829EDD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.730933 :9829EDD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.731088 :9829EDE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.731238 :9829EDF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.731263 :9829EDF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.731416 :9829EE07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.731577 :9829EE0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.731732 :9829EE18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.731886 :9829EE29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.732045 :9829EE31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.732216 :9829EE39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.732380 :9829EE41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.732535 :9829EE49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.732698 :9829EE51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.732858 :9829EE59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.733011 :9829EE61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.733199 :9829EE69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.733353 :9829EE71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.733507 :9829EE79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.733658 :9829EE81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.734292 :9829EE89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.736117 :9829EE9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.738159 :9829EEA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-17 06:00:01.761753 :9829EEB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:03.388828*:9829F1DD:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000341A3AD0 estart=1747432803 eid=18452789 ctx=0x0000000777968158 cwh=0x000000003CC50158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-17 06:00:03.388828*:9829F1DE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000341A3AD0 curCtx=0x000000003CC7EE70 pubTabIdxCnt=0 2025-05-17 06:00:03.388828*:9829F1DF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000341A3AD0 2025-05-17 06:00:03.388828*:9829F1E0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000341A3AD0 2025-05-17 06:00:03.388828*:9829F1E1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003421B6F0 estart=1747432801 eid=16777326 ctx=0x000000077B54EAC8 cwh=0x0000000034210068 cpu=1453125 buffg=77995 interb=204800 r=25 rb=204800 w=0 wb=0 2025-05-17 06:00:03.388828*:9829F1E2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003421B6F0 curCtx=0x00000000346E7FD8 pubTabIdxCnt=0 2025-05-17 06:00:03.388828*:9829F1E3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003421B6F0 2025-05-17 06:00:03.388828*:9829F1E4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003421B6F0 2025-05-17 06:00:03.388828*:9829F1E5:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000341A3AD0 curCtx=0x000000003CC7EE70 xsc->flg4=65792 flags=1 2025-05-17 06:00:03.388828*:9829F1E6:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000341A3AD0 2025-05-17 06:00:03.407918 :9829F207:db_trace:ktu.c@4871:ktuaex1r(): [10442:31:249] Extension usn 6, ext 0, nex 2 2025-05-17 06:00:03.407925 :9829F208:db_trace:ktusm.c@2117:ktusmasp(): [10445:31:249] des1:tsn:1 rdba:0x008102a7 where: kdiwh22: kdifind 2025-05-17 06:00:03.420081*:9829F254:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003421B6F0 curCtx=0x00000000346E7FD8 xsc->flg4=65792 flags=2 2025-05-17 06:00:03.420081*:9829F255:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003421B6F0 2025-05-17 06:00:03.423716 :9829F256:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:03.445588 :9829F269:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:03.447810 :9829F271:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-05-17 06:00:03.448046 :9829F272:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-05-17 06:00:03.449052 :9829F273:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:03.449151 :9829F276:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:03.450064 :9829F288:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:03.451824 :9829F290:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:03.452137 :9829F298:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:03.513825*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-17 06:00:03.513825*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-17 06:00:03.513825*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-17 06:00:03.560697*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-17 06:00:03.560697*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-17 06:00:03.560697*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-17 06:00:03.560697*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-17 06:00:03.625417 :9829F2E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:05.534137 :9829F3D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:05.534150 :9829F3D2:db_trace:ktur.c@3079:ktuabt(): [10444:31:249] ABORT TRANSACTION - xid: 0x0009.01a.0001c762 2025-05-17 06:00:08.209938 :9829F592:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:08.211539 :9829F59A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:08.222328 :9829F5A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:08.246169 :9829F5AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:09.402078 :9829F69E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:jslv.c:9203 2025-05-17 06:00:09.415118 :9829F69F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:09.416977 :9829F6A7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-17 06:00:13.517367 :9829F88B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-17 06:00:13.503 2025-05-17 06:00:13.517369 :9829F88C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12932 pso_num=31 pso_serial#=93 2025-05-17 06:00:13.517507 :9829F88D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12932 (legacy spawn) 2025-05-17 06:00:13.535970 :9829F88E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:13.536145 :9829F893:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-17 06:00:13.537017 :9829F898:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-17 06:00:13.545674 :9829F89B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-17 06:00:13.534 2025-05-17 06:00:13.545675 :9829F89C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12760 pso_num=31 pso_serial#=94 2025-05-17 06:00:13.545809 :9829F89D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12760 (legacy spawn) 2025-05-17 06:00:13.552282 :9829F89E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:13.552472 :9829F8A6:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-17 06:00:13.553277 :9829F8A8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-17 06:00:16.724852 :9829F99C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-17 06:00:16.723 2025-05-17 06:00:16.724854 :9829F99D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14028 pso_num=31 pso_serial#=95 2025-05-17 06:00:16.724993 :9829F99E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14028 (legacy spawn) 2025-05-17 06:00:16.731447 :9829F99F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:16.831996 :9829F9A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:16.902244 :9829F9A7:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-17 06:00:16.903197 :9829F9B6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-17 06:00:16.911842 :9829F9B9:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-17 06:00:16.910 2025-05-17 06:00:16.911844 :9829F9BA:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12576 pso_num=31 pso_serial#=96 2025-05-17 06:00:16.911978 :9829F9BB:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12576 (legacy spawn) 2025-05-17 06:00:16.918471 :9829F9BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:16.918643 :9829F9C4:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-17 06:00:16.919417 :9829F9C6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-17 06:00:20.639085 :9829FAEF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-17 06:00:20.631 2025-05-17 06:00:20.639087 :9829FAF0:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8216 pso_num=31 pso_serial#=97 2025-05-17 06:00:20.639227 :9829FAF1:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8216 (legacy spawn) 2025-05-17 06:00:20.645690 :9829FAF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:20.645863 :9829FAF7:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-17 06:00:20.646679 :9829FAFC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-17 06:00:23.376195 :9829FBF6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-17 06:00:23.371 2025-05-17 06:00:23.376197 :9829FBF7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12296 pso_num=31 pso_serial#=98 2025-05-17 06:00:23.376336 :9829FBF8:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=12296 osp_idx=29 osp_ver=352 osp_pg=0 (spawn #190276) 2025-05-17 06:00:23.376336 :9829FBF9:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 06:00:23.376337 :9829FBFA:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=120 time=437433250 2025-05-17 06:00:23.376337 :9829FBFB:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 06:00:23.376338 :9829FBFC:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 06:00:23.376338 :9829FBFD:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 06:00:23.376339 :9829FBFE:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 06:00:23.376339 :9829FBFF:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 06:00:23.376828 :9829FC00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.376878 :9829FC06:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 06:00:23.381385 :9829FC19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.403811 :9829FC21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.412050 :9829FC29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.412061 :9829FC2A:db_trace:ktur.c@3079:ktuabt(): [10444:31:249] ABORT TRANSACTION - xid: 0x0007.009.0000c254 2025-05-17 06:00:23.412604 :9829FC32:db_trace:ktu.c@4871:ktuaex1r(): [10442:31:249] Extension usn 7, ext 0, nex 2 2025-05-17 06:00:23.412610 :9829FC33:db_trace:ktusm.c@2117:ktusmasp(): [10445:31:249] des1:tsn:1 rdba:0x0080f4a3 where: kdiwh133: kdisdelete 2025-05-17 06:00:23.414965 :9829FC34:db_trace:ktu.c@4871:ktuaex1r(): [10442:31:249] Extension usn 7, ext 1, nex 3 2025-05-17 06:00:23.414974 :9829FC35:db_trace:ktusm.c@2117:ktusmasp(): [10445:31:249] des1:tsn:1 rdba:0x00811c70 where: kdiwh22: kdifind 2025-05-17 06:00:23.417453 :9829FC36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.417465 :9829FC37:db_trace:ktur.c@3079:ktuabt(): [10444:31:249] ABORT TRANSACTION - xid: 0x0007.015.0000c250 2025-05-17 06:00:23.417787 :9829FC3F:db_trace:ktu.c@4871:ktuaex1r(): [10442:31:249] Extension usn 7, ext 2, nex 4 2025-05-17 06:00:23.417793 :9829FC40:db_trace:ktusm.c@2117:ktusmasp(): [10445:31:249] des1:tsn:1 rdba:0x00811c7c where: kdiwh187: kdisprobe 2025-05-17 06:00:23.420475 :9829FC41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.446957 :9829FC49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.450842 :9829FC51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.454610 :9829FC59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.458766 :9829FC61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:23.459808 :9829FC69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:24.459154*:9829FCC1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B2AB4B0 estart=1747432823 eid=16778537 ctx=0x000000077BA4B1C8 cwh=0x000000002B2605F0 cpu=375000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-17 06:00:24.459154*:9829FCC2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B2AB4B0 curCtx=0x000000002B4CF580 pubTabIdxCnt=0 2025-05-17 06:00:24.459154*:9829FCC3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B2AB4B0 2025-05-17 06:00:24.459154*:9829FCC4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B2AB4B0 2025-05-17 06:00:24.459154*:9829FCC5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B2A78D0 estart=1747432823 eid=16778530 ctx=0x0000000787AD5BF0 cwh=0x000000002B2608C0 cpu=375000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-17 06:00:24.459154*:9829FCC6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B2A78D0 curCtx=0x000000002B272428 pubTabIdxCnt=0 2025-05-17 06:00:24.459154*:9829FCC7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B2A78D0 2025-05-17 06:00:24.459154*:9829FCC8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B2A78D0 2025-05-17 06:00:24.631036*:9829FD04:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B2AB4B0 curCtx=0x000000002B4CF580 xsc->flg4=65792 flags=1 2025-05-17 06:00:24.631036*:9829FD05:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B2AB4B0 2025-05-17 06:00:24.631036*:9829FD06:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B2A78D0 curCtx=0x000000002B272428 xsc->flg4=65792 flags=2 2025-05-17 06:00:24.631036*:9829FD07:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B2A78D0 2025-05-17 06:00:24.704009 :9829FD08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:24.745408 :9829FD10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:24.745691 :9829FD18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:24.745965 :9829FD20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:24.745981 :9829FD23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 06:00:24.746041 :9829FD2F:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-17 06:00:24.748713 :9829FD3D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 31 (osid: 12296, M000)