Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250911220008\orcl_j003_23240_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:34853M/63366M, Ph+PgF:39561M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 23240, image: ORACLE.EXE (J003) *** 2025-09-11 22:00:08.506 *** 2025-09-11 22:00:08.506 Process diagnostic dump for ORACLE.EXE (J003), OS id=23240, pid: 29, proc_ser: 52, 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-09-11 22:00:08.506 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 23240, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-11 00:05:59.639957 :CBBDFE55:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22724 pso_num=29 pso_serial#=38 2025-09-11 00:05:59.640091 :CBBDFE56:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22724 (legacy spawn) 2025-09-11 00:05:59.646650 :CBBDFE57: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-09-11 00:05:59.646824 :CBBDFE5C: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-09-11 00:05:59.647645 :CBBDFE61:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:05.287066 :CBBE003E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:05.272 2025-09-11 00:06:05.287068 :CBBE003F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22252 pso_num=29 pso_serial#=39 2025-09-11 00:06:05.287208 :CBBE0040:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22252 (legacy spawn) 2025-09-11 00:06:05.293634 :CBBE0041: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-09-11 00:06:05.293804 :CBBE0049: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-09-11 00:06:05.294596 :CBBE004B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:05.303355 :CBBE004E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:05.288 2025-09-11 00:06:05.303357 :CBBE004F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22932 pso_num=29 pso_serial#=40 2025-09-11 00:06:05.303492 :CBBE0050:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22932 (legacy spawn) 2025-09-11 00:06:05.323674 :CBBE0051: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-09-11 00:06:05.323844 :CBBE005A: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-09-11 00:06:05.324664 :CBBE005B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:07.515971 :CBBE0139:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:07.507 2025-09-11 00:06:07.515974 :CBBE013A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22436 pso_num=29 pso_serial#=41 2025-09-11 00:06:07.516125 :CBBE013B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22436 (legacy spawn) 2025-09-11 00:06:07.523034 :CBBE013C: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-09-11 00:06:07.523230 :CBBE0145: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-09-11 00:06:07.524193 :CBBE0146:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:08.598356 :CBBE0160:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:08.585 2025-09-11 00:06:08.598358 :CBBE0161:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21244 pso_num=29 pso_serial#=42 2025-09-11 00:06:08.598491 :CBBE0162:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21244 (legacy spawn) 2025-09-11 00:06:08.605032 :CBBE0163: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-09-11 00:06:08.605215 :CBBE0168: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-09-11 00:06:08.606015 :CBBE016D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:08.614679 :CBBE0170:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:08.601 2025-09-11 00:06:08.614681 :CBBE0171:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23640 pso_num=29 pso_serial#=43 2025-09-11 00:06:08.614822 :CBBE0172:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=23640 (legacy spawn) 2025-09-11 00:06:08.621436 :CBBE0173: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-09-11 00:06:08.621610 :CBBE017C: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-09-11 00:06:08.622436 :CBBE017D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:01.587774 :CBC22D4A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:01.573 2025-09-11 01:00:01.587776 :CBC22D4B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24280 pso_num=29 pso_serial#=44 2025-09-11 01:00:01.587932 :CBC22D4C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=24280 (legacy spawn) 2025-09-11 01:00:01.594796 :CBC22D4D: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-09-11 01:00:01.594968 :CBC22D52: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-09-11 01:00:01.595840 :CBC22D57:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:01.605370 :CBC22D5A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:01.604 2025-09-11 01:00:01.605372 :CBC22D5B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16340 pso_num=29 pso_serial#=45 2025-09-11 01:00:01.605521 :CBC22D5C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16340 (legacy spawn) 2025-09-11 01:00:01.612389 :CBC22D5D: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-09-11 01:00:01.612559 :CBC22D66: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-09-11 01:00:01.613480 :CBC22D67:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:01.815685 :CBC22D87:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:01.807 2025-09-11 01:00:01.815688 :CBC22D88:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21996 pso_num=29 pso_serial#=46 2025-09-11 01:00:01.815828 :CBC22D89:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21996 (legacy spawn) 2025-09-11 01:00:01.822327 :CBC22D8A: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-09-11 01:00:01.822531 :CBC22D93: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-09-11 01:00:01.823427 :CBC22D94:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:04.811181 :CBC22E77:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:04.797 2025-09-11 01:00:04.811184 :CBC22E78:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24700 pso_num=29 pso_serial#=47 2025-09-11 01:00:04.811322 :CBC22E79:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=24700 (legacy spawn) 2025-09-11 01:00:04.817825 :CBC22E7A: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-09-11 01:00:04.817994 :CBC22E7F: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-09-11 01:00:04.818792 :CBC22E84:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:04.827411 :CBC22E87:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:04.813 2025-09-11 01:00:04.827413 :CBC22E88:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24676 pso_num=29 pso_serial#=48 2025-09-11 01:00:04.827555 :CBC22E89:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=24676 (legacy spawn) 2025-09-11 01:00:04.834016 :CBC22E8A: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-09-11 01:00:04.834179 :CBC22E93: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-09-11 01:00:04.834983 :CBC22E94:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 08:22:54.908733 :CBE4D4C9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 08:22:54.908 2025-09-11 08:22:54.908735 :CBE4D4CA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24184 pso_num=29 pso_serial#=49 2025-09-11 08:22:54.911471 :CBE4D4CB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=24184 osp_idx=27 osp_ver=17388 osp_pg=0 (spawn #580612) 2025-09-11 08:22:54.911472 :CBE4D4CC:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-11 08:22:54.911473 :CBE4D4CD:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=228 time=1964626190 2025-09-11 08:22:54.911473 :CBE4D4CE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-11 08:22:54.911474 :CBE4D4CF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-11 08:22:54.911474 :CBE4D4D0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-11 08:22:54.911474 :CBE4D4D1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-11 08:22:54.911475 :CBE4D4D2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-11 08:22:54.916698 :CBE4D4D3: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-09-11 08:22:54.916756 :CBE4D4D9: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-09-11 08:22:54.917203 :CBE4D4DA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 08:22:57.107361 :CBE4D5F7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 08:22:57.097 2025-09-11 08:22:57.107363 :CBE4D5F8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=25032 pso_num=29 pso_serial#=50 2025-09-11 08:22:57.107497 :CBE4D5F9:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=25032 osp_idx=27 osp_ver=17389 osp_pg=0 (spawn #580614) 2025-09-11 08:22:57.107498 :CBE4D5FA:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-11 08:22:57.107499 :CBE4D5FB:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=229 time=1964628408 2025-09-11 08:22:57.107499 :CBE4D5FC:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-11 08:22:57.107500 :CBE4D5FD:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-11 08:22:57.107500 :CBE4D5FE:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-11 08:22:57.107500 :CBE4D5FF:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-11 08:22:57.107501 :CBE4D600:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-11 08:22:57.108023 :CBE4D601: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-09-11 08:22:57.108068 :CBE4D607: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-09-11 08:22:57.108467 :CBE4D608:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 16:00:19.588228 :CC086CAE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 16:00:19.582 2025-09-11 16:00:19.588230 :CC086CAF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=25260 pso_num=29 pso_serial#=51 2025-09-11 16:00:19.588356 :CC086CB0:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=25260 osp_idx=27 osp_ver=17390 osp_pg=0 (spawn #581670) 2025-09-11 16:00:19.588357 :CC086CB1:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-11 16:00:19.588357 :CC086CB2:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=230 time=1992070877 2025-09-11 16:00:19.588358 :CC086CB3:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-11 16:00:19.588358 :CC086CB4:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-11 16:00:19.588358 :CC086CB5:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-11 16:00:19.588359 :CC086CB6:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-11 16:00:19.588359 :CC086CB7:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-11 16:00:19.588854 :CC086CB8: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-09-11 16:00:19.588902 :CC086CBE: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-09-11 16:00:19.596508 :CC086CCC: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-09-11 16:00:19.597569 :CC086CD4: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-09-11 16:00:19.598050 :CC086CDC: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-09-11 16:00:19.598486 :CC086CE4: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-09-11 16:00:19.598971 :CC086CEC: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-09-11 16:00:19.601387 :CC086CF4: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-09-11 16:00:19.602462 :CC086CFC: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-09-11 16:00:19.653395 :CC086D04: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-09-11 16:00:19.654293 :CC086D0C: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-09-11 16:00:19.654533 :CC086D14: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-09-11 16:00:19.654751 :CC086D1C: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-09-11 16:00:19.656597 :CC086D24:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 22:00:00.424910 :CC24457A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 22:00:00.413 2025-09-11 22:00:00.424911 :CC24457B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23240 pso_num=29 pso_serial#=52 2025-09-11 22:00:00.425051 :CC244582:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23240 osp_idx=27 osp_ver=17391 osp_pg=0 (spawn #582502) 2025-09-11 22:00:00.425051 :CC244583:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-11 22:00:00.425052 :CC244584:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=231 time=2013651721 2025-09-11 22:00:00.425052 :CC244585:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-11 22:00:00.425053 :CC244586:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-11 22:00:00.425053 :CC244587:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-11 22:00:00.425054 :CC244588:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-11 22:00:00.425054 :CC244589:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-11 22:00:00.425544 :CC24458A: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-09-11 22:00:00.430619 :CC2445A1: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-09-11 22:00:00.430655 :CC2445A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-09-11 22:00:00.448284 :CC24478A: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-09-11 22:00:00.448593 :CC244792: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-09-11 22:00:00.448768 :CC24479A: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-09-11 22:00:00.448933 :CC2447A2: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-09-11 22:00:00.449093 :CC2447AA: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-09-11 22:00:00.449245 :CC2447B2: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-09-11 22:00:00.449520 :CC2447BA: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-09-11 22:00:00.449739 :CC2447C2: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-09-11 22:00:00.449929 :CC2447CA: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-09-11 22:00:00.450089 :CC2447D2: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-09-11 22:00:00.450257 :CC2447DA: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-09-11 22:00:00.450654 :CC2447E2: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-09-11 22:00:00.450840 :CC2447EA: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-09-11 22:00:00.451002 :CC2447F2: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-09-11 22:00:00.451267 :CC2447FA: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-09-11 22:00:00.451514 :CC244802: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-09-11 22:00:00.451762 :CC24480A: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-09-11 22:00:00.452403 :CC244812: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-09-11 22:00:00.478850 :CC24481A: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-09-11 22:00:01.522177*:CC244A05:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000325D3AD0 estart=1757599201 eid=22269343 ctx=0x0000000777968158 cwh=0x000000002B460AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-09-11 22:00:01.522177*:CC244A06:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000325D3AD0 curCtx=0x000000005F25C4B8 pubTabIdxCnt=0 2025-09-11 22:00:01.522177*:CC244A07:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000325D3AD0 2025-09-11 22:00:01.522177*:CC244A08:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000325D3AD0 2025-09-11 22:00:01.522177*:CC244A09:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003261AAF8 estart=1757599200 eid=16777579 ctx=0x000000077B54EAC8 cwh=0x000000002B460608 cpu=859375 buffg=49359 interb=196608 r=24 rb=196608 w=0 wb=0 2025-09-11 22:00:01.522177*:CC244A0A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003261AAF8 curCtx=0x000000005F071240 pubTabIdxCnt=0 2025-09-11 22:00:01.522177*:CC244A0B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003261AAF8 2025-09-11 22:00:01.522177*:CC244A0C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003261AAF8 2025-09-11 22:00:01.522177*:CC244A0D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000325D3AD0 curCtx=0x000000005F25C4B8 xsc->flg4=65792 flags=1 2025-09-11 22:00:01.522177*:CC244A0E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000325D3AD0 2025-09-11 22:00:02.115936*:CC244B36:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003261AAF8 curCtx=0x000000005F071240 xsc->flg4=65792 flags=2 2025-09-11 22:00:02.115936*:CC244B37:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003261AAF8 2025-09-11 22:00:02.128856 :CC244B38: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-09-11 22:00:02.151724 :CC244B60: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-09-11 22:00:02.154007 :CC244B68:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-09-11 22:00:02.154254 :CC244B69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-09-11 22:00:02.155288 :CC244B6A: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-09-11 22:00:02.155425 :CC244B6D: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-09-11 22:00:02.156497 :CC244B7F: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-09-11 22:00:02.158287 :CC244B9F: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-09-11 22:00:02.158627 :CC244BAA: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-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-11 22:00:02.310245 :CC244C4F: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-09-11 22:00:04.263370 :CC244DA4: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-09-11 22:00:04.263385 :CC244DA5:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0001.012.0000ca13 2025-09-11 22:00:06.764902 :CC244F06: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-09-11 22:00:06.766585 :CC244F0E: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-09-11 22:00:06.778311 :CC244F16: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-09-11 22:00:06.808888 :CC244F1E: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-09-11 22:00:08.075164 :CC245052:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-09-11 22:00:08.087756 :CC245053: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-09-11 22:00:08.089120 :CC24505B: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: 23240, J003)