Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250516220008\orcl_j003_7880_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:37385M/63366M, Ph+PgF:44617M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 7880, image: ORACLE.EXE (J003) *** 2025-05-16 22:00:08.686 *** 2025-05-16 22:00:08.686 Process diagnostic dump for ORACLE.EXE (J003), OS id=7880, pid: 29, proc_ser: 75, 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-16 22:00:08.686 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 7880, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-16 01:00:28.028103 :97A0B2DD:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-16 01:00:28.032600 :97A0B2F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:28.056472 :97A0B2F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:28.071132 :97A0B300:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:28.098257 :97A0B308:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:28.102069 :97A0B310:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:28.105808 :97A0B318:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:28.109957 :97A0B320:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:28.111030 :97A0B328:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:29.099176*:97A0B349:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029C66AB8 estart=1747328428 eid=16778508 ctx=0x000000077BA4B1C8 cwh=0x0000000029C50BE8 cpu=359375 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-16 01:00:29.099176*:97A0B34A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029C66AB8 curCtx=0x000000002B15F580 pubTabIdxCnt=0 2025-05-16 01:00:29.099176*:97A0B34B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029C66AB8 2025-05-16 01:00:29.099176*:97A0B34C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029C66AB8 2025-05-16 01:00:29.099176*:97A0B34D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B11D4D8 estart=1747328428 eid=16778501 ctx=0x0000000787AD5BF0 cwh=0x0000000029C507D0 cpu=359375 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-16 01:00:29.099176*:97A0B34E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B11D4D8 curCtx=0x0000000029C67A58 pubTabIdxCnt=0 2025-05-16 01:00:29.099176*:97A0B34F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B11D4D8 2025-05-16 01:00:29.099176*:97A0B350:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B11D4D8 2025-05-16 01:00:29.286675*:97A0B351:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029C66AB8 curCtx=0x000000002B15F580 xsc->flg4=65792 flags=1 2025-05-16 01:00:29.286675*:97A0B352:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029C66AB8 2025-05-16 01:00:29.286675*:97A0B353:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B11D4D8 curCtx=0x0000000029C67A58 xsc->flg4=65792 flags=2 2025-05-16 01:00:29.286675*:97A0B354:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B11D4D8 2025-05-16 01:00:29.358445 :97A0B36D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:29.385353 :97A0B380:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:29.385673 :97A0B388:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:29.385945 :97A0B390:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:29.385961 :97A0B393:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 01:00:29.386021 :97A0B3A0:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-16 01:00:29.388542 :97A0B3AD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-16 01:02:28.285007 :97A0DC6E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-16 01:02:28.273 2025-05-16 01:02:28.285008 :97A0DC6F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12468 pso_num=29 pso_serial#=71 2025-05-16 01:02:28.285146 :97A0DC70:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12468 osp_idx=27 osp_ver=11035 osp_pg=0 (spawn #186254) 2025-05-16 01:02:28.285146 :97A0DC71:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-16 01:02:28.285147 :97A0DC72:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=109 time=333158141 2025-05-16 01:02:28.285147 :97A0DC73:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-16 01:02:28.285148 :97A0DC74:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-16 01:02:28.285148 :97A0DC75:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-16 01:02:28.285149 :97A0DC76:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-16 01:02:28.285149 :97A0DC77:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-16 01:02:28.285790 :97A0DC78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-16 01:02:28.285835 :97A0DC7E:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-16 01:02:28.294349 :97A0DC93:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-16 01:12:29.711456 :97A1AD42:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-16 01:12:29.699 2025-05-16 01:12:29.711458 :97A1AD43:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11336 pso_num=29 pso_serial#=72 2025-05-16 01:12:29.711599 :97A1AD44:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=11336 osp_idx=27 osp_ver=11036 osp_pg=0 (spawn #186277) 2025-05-16 01:12:29.711600 :97A1AD45:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-16 01:12:29.711600 :97A1AD46:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=110 time=333759579 2025-05-16 01:12:29.711601 :97A1AD47:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-16 01:12:29.711601 :97A1AD48:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-16 01:12:29.711601 :97A1AD49:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-16 01:12:29.711602 :97A1AD4A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-16 01:12:29.711602 :97A1AD4B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-16 01:12:29.712116 :97A1AD4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-16 01:12:29.712162 :97A1AD52:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-16 01:12:29.716178 :97A1AD6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:12:29.716223 :97A1AD6C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:12:29.716482 :97A1AD6D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:12:29.716498 :97A1AD6E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:12:29.716715 :97A1AD6F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:12:29.716729 :97A1AD70:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:12:29.716937 :97A1AD71:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:12:29.716951 :97A1AD72:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:12:29.717163 :97A1AD73:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:12:29.717176 :97A1AD74:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:12:29.717379 :97A1AD75:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:12:29.717391 :97A1AD76:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:12:29.719800 :97A1AD77:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-16 01:42:33.662807 :97A4112D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-16 01:42:33.650 2025-05-16 01:42:33.662809 :97A4112E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13392 pso_num=29 pso_serial#=73 2025-05-16 01:42:33.662944 :97A4112F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13392 osp_idx=27 osp_ver=11037 osp_pg=0 (spawn #186345) 2025-05-16 01:42:33.662945 :97A41130:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-16 01:42:33.662946 :97A41131:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=111 time=335563532 2025-05-16 01:42:33.662946 :97A41132:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-16 01:42:33.662946 :97A41133:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-16 01:42:33.662947 :97A41134:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-16 01:42:33.662947 :97A41135:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-16 01:42:33.662947 :97A41136:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-16 01:42:33.663479 :97A41137:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-16 01:42:33.663530 :97A4113D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-16 01:42:33.667554 :97A41166:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:42:33.667596 :97A41167:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:42:33.667858 :97A41168:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:42:33.667874 :97A41169:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:42:33.668095 :97A4116A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:42:33.668108 :97A4116B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:42:33.668312 :97A4116C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:42:33.668324 :97A4116D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:42:33.668531 :97A4116E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:42:33.668543 :97A4116F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:42:33.668743 :97A41170:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-16 01:42:33.668756 :97A41171:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-16 01:42:33.671106 :97A41172:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-16 21:00:10.062471 :97FF2FEF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-16 21:00:10.060 2025-05-16 21:00:10.062473 :97FF2FF0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13000 pso_num=29 pso_serial#=74 2025-05-16 21:00:10.062608 :97FF2FF1:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13000 osp_idx=27 osp_ver=11038 osp_pg=0 (spawn #189016) 2025-05-16 21:00:10.062609 :97FF2FF2:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-16 21:00:10.062610 :97FF2FF3:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=112 time=405019922 2025-05-16 21:00:10.062611 :97FF2FF4:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-16 21:00:10.062612 :97FF2FF5:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-16 21:00:10.062612 :97FF2FF6:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-16 21:00:10.062612 :97FF2FF7:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-16 21:00:10.062613 :97FF2FF8:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-16 21:00:10.063162 :97FF2FF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-16 21:00:10.063204 :97FF2FFF:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-16 21:00:10.071630 :97FF3034:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-16 22:00:00.918105 :9803D797:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-16 22:00:00.911 2025-05-16 22:00:00.918106 :9803D798:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7880 pso_num=29 pso_serial#=75 2025-05-16 22:00:00.918249 :9803D79F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=7880 osp_idx=27 osp_ver=11039 osp_pg=0 (spawn #189155) 2025-05-16 22:00:00.918249 :9803D7A0:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-16 22:00:00.918250 :9803D7A1:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=113 time=408610782 2025-05-16 22:00:00.918250 :9803D7A2:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-16 22:00:00.918251 :9803D7A3:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-16 22:00:00.918252 :9803D7A4:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-16 22:00:00.918253 :9803D7A5:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-16 22:00:00.918253 :9803D7A6:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-16 22:00:00.918741 :9803D7A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.923726 :9803D7C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.923764 :9803D7C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-05-16 22:00:00.942069 :9803D9A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.942316 :9803D9AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.942474 :9803D9B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.942650 :9803D9BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.942804 :9803D9C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.942954 :9803D9CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.943101 :9803D9D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.943255 :9803D9DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.943403 :9803D9E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.943547 :9803D9EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.943740 :9803D9F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.944072 :9803D9FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.944221 :9803DA03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.944394 :9803DA0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.944547 :9803DA13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.944704 :9803DA1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.944857 :9803DA23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.945009 :9803DA2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.945163 :9803DA33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.945312 :9803DA3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.945464 :9803DA43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.945608 :9803DA4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.945747 :9803DA53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:00.966112 :9803DA5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:02.155573*:9803DD1B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000318F3AD0 estart=1747404002 eid=18432376 ctx=0x0000000777968158 cwh=0x00000000319209C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-16 22:00:02.155573*:9803DD1C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000318F3AD0 curCtx=0x000000003C637918 pubTabIdxCnt=0 2025-05-16 22:00:02.155573*:9803DD1D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000318F3AD0 2025-05-16 22:00:02.155573*:9803DD1E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000318F3AD0 2025-05-16 22:00:02.155573*:9803DD1F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003196B6F0 estart=1747404000 eid=16777325 ctx=0x000000077B54EAC8 cwh=0x0000000031920518 cpu=1000000 buffg=56614 interb=204800 r=25 rb=204800 w=0 wb=0 2025-05-16 22:00:02.155573*:9803DD20:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003196B6F0 curCtx=0x000000003C5187D0 pubTabIdxCnt=0 2025-05-16 22:00:02.155573*:9803DD21:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003196B6F0 2025-05-16 22:00:02.155573*:9803DD22:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003196B6F0 2025-05-16 22:00:02.155573*:9803DD24:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000318F3AD0 curCtx=0x000000003C637918 xsc->flg4=65792 flags=1 2025-05-16 22:00:02.155573*:9803DD26:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000318F3AD0 2025-05-16 22:00:02.639951*:9803DE13:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003196B6F0 curCtx=0x000000003C5187D0 xsc->flg4=65792 flags=2 2025-05-16 22:00:02.639951*:9803DE14:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003196B6F0 2025-05-16 22:00:02.648269 :9803DE15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:02.668951 :9803DE37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:02.670839 :9803DE3F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-16 22:00:02.671041 :9803DE40:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-16 22:00:02.672089 :9803DE41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:02.672184 :9803DE44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:02.673123 :9803DE56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:02.674623 :9803DE5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:02.674906 :9803DE66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:02.733697*: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-16 22:00:02.733697*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-16 22:00:02.733697*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-16 22:00:02.733697*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-16 22:00:02.733697*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-16 22:00:02.733697*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-16 22:00:02.733697*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-16 22:00:02.806916 :9803DEB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:04.705359 :9803E03B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:04.705371 :9803E03C:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.012.0001c74a 2025-05-16 22:00:07.123090 :9803E1C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:07.125325 :9803E1C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:07.136785 :9803E1D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:07.161070 :9803E1F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:08.326243 :9803E2F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-05-16 22:00:08.337850 :9803E2F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-16 22:00:08.338327 :9803E2F9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 7880, J003)