Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250531100331\orcl_m003_13452_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:37243M/63366M, Ph+PgF:44147M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 13452, image: ORACLE.EXE (M003) *** 2025-05-31 10:03:31.611 *** 2025-05-31 10:03:31.611 Process diagnostic dump for ORACLE.EXE (M003), OS id=13452, pid: 29, proc_ser: 143, 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-31 10:03:31.611 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 13452, M003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-31 01:00:12.527481 :9E39F2D6: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-31 01:00:12.531665 :9E39F2DE: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-31 01:00:12.532763 :9E39F2E6: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-31 01:00:13.486288*:9E39F36E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029AA1510 estart=1748624412 eid=16778870 ctx=0x000000077BA4B1C8 cwh=0x0000000029A50B90 cpu=312500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-31 01:00:13.486288*:9E39F36F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029AA1510 curCtx=0x000000002B4C7648 pubTabIdxCnt=0 2025-05-31 01:00:13.486288*:9E39F370:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029AA1510 2025-05-31 01:00:13.486288*:9E39F371:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029AA1510 2025-05-31 01:00:13.486288*:9E39F372:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029A61970 estart=1748624412 eid=16778861 ctx=0x0000000787AD5BF0 cwh=0x0000000029A50AA0 cpu=312500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-31 01:00:13.486288*:9E39F373:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029A61970 curCtx=0x0000000029A66278 pubTabIdxCnt=0 2025-05-31 01:00:13.486288*:9E39F374:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029A61970 2025-05-31 01:00:13.486288*:9E39F375:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029A61970 2025-05-31 01:00:13.736286*:9E39F382:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029AA1510 curCtx=0x000000002B4C7648 xsc->flg4=65792 flags=1 2025-05-31 01:00:13.736286*:9E39F383:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029AA1510 2025-05-31 01:00:13.736286*:9E39F384:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029A61970 curCtx=0x0000000029A66278 xsc->flg4=65792 flags=2 2025-05-31 01:00:13.736286*:9E39F385:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029A61970 2025-05-31 01:00:13.807806 :9E39F386: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-31 01:00:13.839243 :9E39F38E: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-31 01:00:13.839580 :9E39F396: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-31 01:00:13.839858 :9E39F39E: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-31 01:00:13.839875 :9E39F3A1: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-31 01:00:13.839947 :9E39F3AB: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-31 01:00:13.843314 :9E39F3BB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 01:00:18.771038 :9E39F588:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 01:00:18.758 2025-05-31 01:00:18.771041 :9E39F589:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15080 pso_num=29 pso_serial#=130 2025-05-31 01:00:18.771187 :9E39F58A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15080 (legacy spawn) 2025-05-31 01:00:18.777663 :9E39F58B: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-31 01:00:18.777847 :9E39F590: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-31 01:00:18.778665 :9E39F595:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 01:00:25.364436 :9E39F7EC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 01:00:25.350 2025-05-31 01:00:25.364438 :9E39F7ED:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9584 pso_num=29 pso_serial#=131 2025-05-31 01:00:25.364586 :9E39F7EE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9584 (legacy spawn) 2025-05-31 01:00:25.371091 :9E39F7EF: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-31 01:00:25.371271 :9E39F7F8: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-31 01:00:25.372092 :9E39F7F9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 01:00:25.380679 :9E39F7FC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 01:00:25.366 2025-05-31 01:00:25.380681 :9E39F7FD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12656 pso_num=29 pso_serial#=132 2025-05-31 01:00:25.380818 :9E39F7FE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12656 (legacy spawn) 2025-05-31 01:00:25.399011 :9E39F7FF: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-31 01:00:25.399203 :9E39F808: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-31 01:00:25.400066 :9E39F809:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 01:00:28.442912 :9E39F8EF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 01:00:28.430 2025-05-31 01:00:28.442914 :9E39F8F0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15160 pso_num=29 pso_serial#=133 2025-05-31 01:00:28.443053 :9E39F8F1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15160 (legacy spawn) 2025-05-31 01:00:28.449592 :9E39F8F2: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-31 01:00:28.449768 :9E39F8FB: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-31 01:00:28.450548 :9E39F8FC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 01:00:28.459075 :9E39F8FF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 01:00:28.445 2025-05-31 01:00:28.459077 :9E39F900:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14536 pso_num=29 pso_serial#=134 2025-05-31 01:00:28.459210 :9E39F901:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14536 (legacy spawn) 2025-05-31 01:00:28.465619 :9E39F902: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-31 01:00:28.465792 :9E39F90B: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-31 01:00:28.466572 :9E39F90C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 06:00:02.062188 :9E511E5E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 06:00:02.055 2025-05-31 06:00:02.062190 :9E511E5F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14732 pso_num=29 pso_serial#=135 2025-05-31 06:00:02.062316 :9E511E64:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14732 osp_idx=27 osp_ver=11864 osp_pg=0 (spawn #236922) 2025-05-31 06:00:02.062318 :9E511E65: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-31 06:00:02.062318 :9E511E66:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=138 time=1646960704 2025-05-31 06:00:02.062318 :9E511E67:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 06:00:02.062319 :9E511E68:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 06:00:02.062319 :9E511E69:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 06:00:02.062319 :9E511E6A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 06:00:02.062320 :9E511E6B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 06:00:02.062845 :9E511E71: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-31 06:00:02.067697 :9E511E90: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-31 06:00:02.067735 :9E511E93:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-05-31 06:00:02.085562 :9E511F37: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-31 06:00:02.085762 :9E511F3F: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-31 06:00:02.085930 :9E511F47: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-31 06:00:02.086083 :9E511F4F: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-31 06:00:02.086238 :9E511F57: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-31 06:00:02.086403 :9E511F5F: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-31 06:00:02.086563 :9E511F67: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-31 06:00:02.086729 :9E511F6F: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-31 06:00:02.086873 :9E511F77: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-31 06:00:02.087026 :9E511F7F: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-31 06:00:02.087174 :9E511F87: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-31 06:00:02.087329 :9E511F8F: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-31 06:00:02.087473 :9E511F97: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-31 06:00:02.087616 :9E511F9F: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-31 06:00:02.087793 :9E511FA7: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-31 06:00:02.087994 :9E511FAF: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-31 06:00:02.088175 :9E511FB7: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-31 06:00:02.108354 :9E511FBF: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-31 06:00:03.555331*:9E51238D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB03AD0 estart=1748642403 eid=18903119 ctx=0x0000000777968158 cwh=0x000000002DB309C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-31 06:00:03.555331*:9E51238E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB03AD0 curCtx=0x0000000034157918 pubTabIdxCnt=0 2025-05-31 06:00:03.555331*:9E51238F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB03AD0 2025-05-31 06:00:03.555331*:9E512390:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB03AD0 2025-05-31 06:00:03.555331*:9E512391:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB7B6F0 estart=1748642401 eid=16777356 ctx=0x000000077B54EAC8 cwh=0x000000002DB30518 cpu=1234375 buffg=68348 interb=196608 r=24 rb=196608 w=0 wb=0 2025-05-31 06:00:03.555331*:9E512392:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB7B6F0 curCtx=0x00000000320487D0 pubTabIdxCnt=0 2025-05-31 06:00:03.555331*:9E512393:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB7B6F0 2025-05-31 06:00:03.555331*:9E512394:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB7B6F0 2025-05-31 06:00:03.555331*:9E512395:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB03AD0 curCtx=0x0000000034157918 xsc->flg4=65792 flags=1 2025-05-31 06:00:03.555331*:9E512396:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB03AD0 2025-05-31 06:00:03.774065*:9E5124C7:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB7B6F0 curCtx=0x00000000320487D0 xsc->flg4=65792 flags=2 2025-05-31 06:00:03.774065*:9E5124C8:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB7B6F0 2025-05-31 06:00:03.785874 :9E5124C9: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-31 06:00:03.808473 :9E5124D1: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-31 06:00:03.810809 :9E5124D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-31 06:00:03.811026 :9E5124DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-31 06:00:03.812849 :9E5124DB: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-31 06:00:03.812990 :9E5124DE: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-31 06:00:03.814192 :9E5124F0: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-31 06:00:03.816026 :9E5124F8: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-31 06:00:03.816345 :9E512500: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-31 06:00:03.883445*: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-31 06:00:03.883445*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-31 06:00:03.883445*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-31 06:00:03.914692*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-31 06:00:03.914692*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-31 06:00:03.914692*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-31 06:00:03.914692*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-31 06:00:03.988310 :9E512508: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-31 06:00:05.926527 :9E512638: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-31 06:00:05.926538 :9E512639:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.008.0001ca2a 2025-05-31 06:00:08.483655 :9E5128D8: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-31 06:00:08.485015 :9E5128E0: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-31 06:00:08.495302 :9E5128E8: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-31 06:00:08.519371 :9E5128F0: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-31 06:00:09.667983 :9E5129B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-05-31 06:00:09.678552 :9E5129B1: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-31 06:00:09.680188 :9E5129B9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 06:00:10.369106 :9E5129EB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 06:00:10.356 2025-05-31 06:00:10.369108 :9E5129EC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13636 pso_num=29 pso_serial#=136 2025-05-31 06:00:10.369250 :9E5129ED:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13636 (legacy spawn) 2025-05-31 06:00:10.383454 :9E5129EE: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-31 06:00:10.383617 :9E5129F3: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-31 06:00:10.384437 :9E5129F8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 06:00:15.926629 :9E512C2D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 06:00:15.912 2025-05-31 06:00:15.926631 :9E512C2E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14392 pso_num=29 pso_serial#=137 2025-05-31 06:00:15.926766 :9E512C2F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14392 (legacy spawn) 2025-05-31 06:00:15.933211 :9E512C30: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-31 06:00:15.933388 :9E512C35: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-31 06:00:15.934171 :9E512C3A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 06:00:15.942778 :9E512C3D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 06:00:15.928 2025-05-31 06:00:15.942780 :9E512C3E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13408 pso_num=29 pso_serial#=138 2025-05-31 06:00:15.942919 :9E512C3F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13408 (legacy spawn) 2025-05-31 06:00:15.949380 :9E512C40: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-31 06:00:15.949556 :9E512C49: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-31 06:00:15.950338 :9E512C4A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 06:00:19.138796 :9E512D67:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 06:00:19.125 2025-05-31 06:00:19.138799 :9E512D68:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14592 pso_num=29 pso_serial#=139 2025-05-31 06:00:19.138932 :9E512D69:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14592 (legacy spawn) 2025-05-31 06:00:19.145408 :9E512D6A: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-31 06:00:19.145624 :9E512D6F: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-31 06:00:19.146463 :9E512D74:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 06:00:19.154927 :9E512D77:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 06:00:19.141 2025-05-31 06:00:19.154929 :9E512D78:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7880 pso_num=29 pso_serial#=140 2025-05-31 06:00:19.155066 :9E512D79:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=7880 (legacy spawn) 2025-05-31 06:00:19.161483 :9E512D7A: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-31 06:00:19.161646 :9E512D83: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-31 06:00:19.162413 :9E512D84:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 06:00:32.117351 :9E5131A6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 06:00:32.108 2025-05-31 06:00:32.117353 :9E5131A7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12908 pso_num=29 pso_serial#=141 2025-05-31 06:00:32.117491 :9E5131A8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12908 (legacy spawn) 2025-05-31 06:00:32.124018 :9E5131A9: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-31 06:00:32.124180 :9E5131B2: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-31 06:00:32.124994 :9E5131B3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 08:17:21.234220 :9E5C0261:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 08:17:21.221 2025-05-31 08:17:21.234223 :9E5C0262:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15120 pso_num=29 pso_serial#=142 2025-05-31 08:17:21.234339 :9E5C0263:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15120 osp_idx=27 osp_ver=11871 osp_pg=0 (spawn #237249) 2025-05-31 08:17:21.234340 :9E5C0264: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-31 08:17:21.234340 :9E5C0265:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=139 time=1655199875 2025-05-31 08:17:21.234340 :9E5C0266:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 08:17:21.234341 :9E5C0267:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 08:17:21.234341 :9E5C0268:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 08:17:21.234341 :9E5C0269:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 08:17:21.234342 :9E5C026A:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 08:17:21.234824 :9E5C026B: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-31 08:17:51.308141 :9E5C1B03:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-31 10:03:25.073376 :9E645B3A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-31 10:03:25.063 2025-05-31 10:03:25.073377 :9E645B3B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13452 pso_num=29 pso_serial#=143 2025-05-31 10:03:25.073518 :9E645B3C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13452 osp_idx=27 osp_ver=11872 osp_pg=0 (spawn #237496) 2025-05-31 10:03:25.073519 :9E645B3D: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-31 10:03:25.073520 :9E645B3E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=140 time=1661563719 2025-05-31 10:03:25.073521 :9E645B3F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 10:03:25.073521 :9E645B40:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 10:03:25.073521 :9E645B41:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 10:03:25.073522 :9E645B42:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 10:03:25.073522 :9E645B43:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 10:03:25.074103 :9E645B47: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-31 10:03:25.074144 :9E645B4D: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-31 10:03:25.083355 :9E645B5F: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: 13452, M003)