Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250515220008\orcl_j005_3736_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:37400M/63366M, Ph+PgF:44661M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 3736, image: ORACLE.EXE (J005) *** 2025-05-15 22:00:08.950 *** 2025-05-15 22:00:08.950 Process diagnostic dump for ORACLE.EXE (J005), OS id=3736, pid: 31, proc_ser: 79, 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-15 22:00:08.950 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 3736, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-13 22:00:33.412073 :96B14D28:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=112 time=149443282 2025-05-13 22:00:33.412073 :96B14D29:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-13 22:00:33.412074 :96B14D2A:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-13 22:00:33.412074 :96B14D2B:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-13 22:00:33.412074 :96B14D2C:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-13 22:00:33.412074 :96B14D2D:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-13 22:00:33.412589 :96B14D2E: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-13 22:00:33.412635 :96B14D34: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-13 22:00:33.415221 :96B14D42: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-13 22:00:33.419705 :96B14D4A: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-13 22:00:33.419715 :96B14D4B:db_trace:ktur.c@3079:ktuabt(): [10444:31:249] ABORT TRANSACTION - xid: 0x0007.011.0000c1f8 2025-05-13 22:00:33.420233 :96B14D53:db_trace:ktu.c@4871:ktuaex1r(): [10442:31:249] Extension usn 7, ext 1, nex 2 2025-05-13 22:00:33.420239 :96B14D54:db_trace:ktusm.c@2117:ktusmasp(): [10445:31:249] des1:tsn:1 rdba:0x0081af74 where: kdiwh187: kdisprobe 2025-05-13 22:00:33.421421 :96B14D55: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-13 22:00:33.422542 :96B14D5D: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-13 22:00:33.423100 :96B14D65: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-13 22:00:33.423580 :96B14D6D: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-13 22:00:33.424015 :96B14D75: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-13 22:00:33.426440 :96B14D7D: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-13 22:00:33.427542 :96B14D85: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-13 22:00:33.476968 :96B14D9B: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-13 22:00:33.477842 :96B14DA3: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-13 22:00:33.478074 :96B14DAB: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-13 22:00:33.478295 :96B14DB3: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-13 22:00:33.480099 :96B14DBB:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-13 23:59:09.852514 :96BA65BA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-13 23:59:09.837 2025-05-13 23:59:09.852516 :96BA65BB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6600 pso_num=31 pso_serial#=69 2025-05-13 23:59:09.852653 :96BA65BC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=6600 (legacy spawn) 2025-05-13 23:59:09.866552 :96BA65BD: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-13 23:59:43.080340 :96BA7192:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-13 23:59:46.642424 :96BA72A8:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-13 23:59:46.641 2025-05-13 23:59:46.642426 :96BA72A9:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=2884 pso_num=31 pso_serial#=70 2025-05-13 23:59:46.642562 :96BA72AA:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=2884 (legacy spawn) 2025-05-13 23:59:46.656083 :96BA72AB: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-14 00:00:19.862846 :96BA7D92:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-14 22:00:00.221047 :97200DFF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-14 22:00:00.213 2025-05-14 22:00:00.221048 :97200E00:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11224 pso_num=31 pso_serial#=71 2025-05-14 22:00:00.221204 :97200E08:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11224 osp_idx=29 osp_ver=325 osp_pg=0 (spawn #182493) 2025-05-14 22:00:00.221204 :97200E09: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-14 22:00:00.221205 :97200E0A:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=113 time=235810094 2025-05-14 22:00:00.221206 :97200E0B:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 22:00:00.221206 :97200E0C:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 22:00:00.221207 :97200E0D:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 22:00:00.221208 :97200E0E:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 22:00:00.221208 :97200E0F:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 22:00:00.221826 :97200E10: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-14 22:00:30.292551 :97201FA0:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-14 23:59:06.629395 :97295BF4:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-14 23:59:06.616 2025-05-14 23:59:06.629397 :97295BF5:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12852 pso_num=31 pso_serial#=72 2025-05-14 23:59:06.629539 :97295BF6:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12852 (legacy spawn) 2025-05-14 23:59:06.636073 :97295BF7: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-14 23:59:06.636243 :97295BFF: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-14 23:59:06.637043 :97295C00:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-14 23:59:06.645685 :97295C03:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-14 23:59:06.632 2025-05-14 23:59:06.645687 :97295C04:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12004 pso_num=31 pso_serial#=73 2025-05-14 23:59:06.645822 :97295C05:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12004 (legacy spawn) 2025-05-14 23:59:06.652282 :97295C06: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-14 23:59:06.652442 :97295C0F: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-14 23:59:06.653219 :97295C10:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-14 23:59:10.022571 :97295D35:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-14 23:59:10.022 2025-05-14 23:59:10.022573 :97295D36:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12352 pso_num=31 pso_serial#=74 2025-05-14 23:59:10.022736 :97295D37:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12352 (legacy spawn) 2025-05-14 23:59:10.036932 :97295D38: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-14 23:59:13.217879 :97295E30: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-14 23:59:13.217909 :97295E33: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-14 23:59:13.218061 :97295E36: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-14 23:59:13.219302 :97295E47:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-14 23:59:13.478222 :97295E57:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-14 23:59:13.476 2025-05-14 23:59:13.478223 :97295E58:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12604 pso_num=31 pso_serial#=75 2025-05-14 23:59:13.478361 :97295E59:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12604 (legacy spawn) 2025-05-14 23:59:13.492462 :97295E5A: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-14 23:59:46.527382 :97296A37:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-14 23:59:47.152122 :97296AA6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-14 23:59:47.149 2025-05-14 23:59:47.152124 :97296AA7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10320 pso_num=31 pso_serial#=76 2025-05-14 23:59:47.152255 :97296AA8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10320 (legacy spawn) 2025-05-14 23:59:47.166845 :97296AA9: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-14 23:59:50.504582 :97296B99: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-14 23:59:50.504762 :97296B9E: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-14 23:59:50.506251 :97296BA3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-14 23:59:50.753146 :97296BB5:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-14 23:59:50.748 2025-05-14 23:59:50.753149 :97296BB6:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10908 pso_num=31 pso_serial#=77 2025-05-14 23:59:50.753285 :97296BB7:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10908 (legacy spawn) 2025-05-14 23:59:50.767397 :97296BB8: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-15 00:00:23.823741 :97297920:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-15 01:00:13.249664 :972E2B20:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-15 01:00:13.238 2025-05-15 01:00:13.249666 :972E2B21:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6832 pso_num=31 pso_serial#=78 2025-05-15 01:00:13.249794 :972E2B22:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=6832 osp_idx=29 osp_ver=332 osp_pg=0 (spawn #182913) 2025-05-15 01:00:13.249795 :972E2B23: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-15 01:00:13.249796 :972E2B24:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=114 time=246623110 2025-05-15 01:00:13.249796 :972E2B25:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 01:00:13.249796 :972E2B26:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 01:00:13.249797 :972E2B27:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 01:00:13.249797 :972E2B28:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 01:00:13.249797 :972E2B29:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 01:00:13.250301 :972E2B2A: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-15 01:00:13.250351 :972E2B30: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-15 01:00:13.257557 :972E2B3E: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-15 01:00:13.258618 :972E2B46: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-15 01:00:13.259113 :972E2B4E: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-15 01:00:13.259554 :972E2B56: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-15 01:00:13.259974 :972E2B5E: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-15 01:00:13.262712 :972E2B66: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-15 01:00:13.263801 :972E2B6E: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-15 01:00:13.313047 :972E2B76: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-15 01:00:13.313928 :972E2B7E: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-15 01:00:13.314160 :972E2B86: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-15 01:00:13.314775 :972E2B8E:db_trace:ktu.c@4871:ktuaex1r(): [10442:31:249] Extension usn 6, ext 3, nex 6 2025-05-15 01:00:13.314786 :972E2B8F:db_trace:ktusm.c@2117:ktusmasp(): [10445:31:249] des1:tsn:1 rdba:0x0081421b where: kduwh01: kdusru 2025-05-15 01:00:13.315041 :972E2B90: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-15 01:00:13.316797 :972E2B98:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-15 22:00:00.746802 :97922A49:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-15 22:00:00.743 2025-05-15 22:00:00.746803 :97922A4A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=3736 pso_num=31 pso_serial#=79 2025-05-15 22:00:00.746938 :97922A4B:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=3736 osp_idx=29 osp_ver=333 osp_pg=0 (spawn #185827) 2025-05-15 22:00:00.746939 :97922A4C: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-15 22:00:00.746940 :97922A4D:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=115 time=322210610 2025-05-15 22:00:00.746940 :97922A4E:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 22:00:00.746942 :97922A4F:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 22:00:00.746943 :97922A50:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 22:00:00.746943 :97922A51:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 22:00:00.746943 :97922A52:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 22:00:00.747766 :97922A5B: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-15 22:00:00.753331 :97922BF2: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-15 22:00:00.753379 :97922BF5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:jslv.c:9203 2025-05-15 22:00:00.770642 :97922CAB: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-15 22:00:00.770832 :97922CB3: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-15 22:00:00.771004 :97922CBB: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-15 22:00:00.771165 :97922CC3: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-15 22:00:00.771328 :97922CCB: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-15 22:00:00.771483 :97922CD3: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-15 22:00:00.771635 :97922CDB: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-15 22:00:00.771787 :97922CE3: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-15 22:00:00.771938 :97922CEB: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-15 22:00:00.772092 :97922CF3: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-15 22:00:00.772245 :97922CFB: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-15 22:00:00.772396 :97922D03: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-15 22:00:00.772567 :97922D0B: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-15 22:00:00.772737 :97922D13: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-15 22:00:00.772987 :97922D1B: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-15 22:00:00.773144 :97922D23: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-15 22:00:00.773333 :97922D2B: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-15 22:00:00.773662 :97922D33: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-15 22:00:00.773848 :97922D3B: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-15 22:00:00.774007 :97922D43: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-15 22:00:00.774261 :97922D4B: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-15 22:00:00.774506 :97922D53: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-15 22:00:00.774748 :97922D5B: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-15 22:00:00.775365 :97922D63: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-15 22:00:00.775636 :97922D6B: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-15 22:00:00.775885 :97922D73: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-15 22:00:00.776102 :97922D7B: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-15 22:00:00.776354 :97922D83: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-15 22:00:00.776513 :97922D8B: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-15 22:00:00.776666 :97922D93: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-15 22:00:00.776815 :97922D9B: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-15 22:00:00.792745 :97922DCA: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-15 22:00:00.795056 :97922DD9: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-15 22:00:00.802586 :97922DF6: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-15 22:00:00.803317 :97922DFD: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-15 22:00:02.489183 :9792322B: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-15 22:00:02.511375 :9792324D: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-15 22:00:02.513574 :97923255:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-05-15 22:00:02.513808 :97923256:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-05-15 22:00:02.514809 :97923257: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-15 22:00:02.514913 :9792325A: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-15 22:00:02.515820 :9792326C: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-15 22:00:02.517491 :97923274: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-15 22:00:02.517804 :9792327C: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-15 22:00:02.581183*: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-15 22:00:02.581183*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-15 22:00:02.581183*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-15 22:00:02.581183*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-15 22:00:02.581183*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-15 22:00:02.581183*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-15 22:00:02.581183*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-15 22:00:02.628059*:97923336:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003CE7D688 estart=1747317602 eid=16777615 ctx=0x000000079F4EAD08 cwh=0x0000000034170930 cpu=0 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-15 22:00:02.628059*:97923337:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003CE7D688 curCtx=0x000000003CEC84A0 pubTabIdxCnt=0 2025-05-15 22:00:02.628059*:97923338:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003CE7D688 2025-05-15 22:00:02.628059*:97923339:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003CE7D688 2025-05-15 22:00:02.643693*:97923343:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003CE7D688 curCtx=0x000000003CEC84A0 xsc->flg4=65792 flags=1 2025-05-15 22:00:02.643693*:97923344:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003CE7D688 2025-05-15 22:00:02.664575 :97923345: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-15 22:00:04.543876 :97923565: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-15 22:00:04.543889 :97923566:db_trace:ktur.c@3079:ktuabt(): [10444:31:249] ABORT TRANSACTION - xid: 0x0009.013.0001c718 2025-05-15 22:00:07.158774 :979237E2: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-15 22:00:07.160403 :979237EA: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-15 22:00:07.171753 :979237F2: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-15 22:00:07.195912 :979237FA: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-15 22:00:08.342696 :979238B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:jslv.c:9203 2025-05-15 22:00:08.352991 :979238B1: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-15 22:00:08.353402 :979238B9: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: 3736, J005)