Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250708220008\orcl_j003_16688_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:36555M/63366M, Ph+PgF:42682M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 16688, image: ORACLE.EXE (J003) *** 2025-07-08 22:00:09.106 *** 2025-07-08 22:00:09.106 Process diagnostic dump for ORACLE.EXE (J003), OS id=16688, pid: 29, proc_ser: 91, 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-07-08 22:00:09.106 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 16688, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-08 01:00:11.495495*:AEFDBCE4:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B5673A0 2025-07-08 01:00:11.565343 :AEFDBCE5: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-07-08 01:00:11.603361 :AEFDBCED: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-07-08 01:00:11.603694 :AEFDBCF5: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-07-08 01:00:11.603991 :AEFDBCFD: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-07-08 01:00:11.604007 :AEFDBD00: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-07-08 01:00:11.604073 :AEFDBD0E: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-07-08 01:00:11.606872 :AEFDBD1A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 01:00:13.254322 :AEFDBE4E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 01:00:13.245 2025-07-08 01:00:13.254324 :AEFDBE4F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13944 pso_num=29 pso_serial#=81 2025-07-08 01:00:13.254472 :AEFDBE50:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13944 (legacy spawn) 2025-07-08 01:00:13.260978 :AEFDBE51: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-07-08 01:00:13.261152 :AEFDBE56: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-07-08 01:00:13.261995 :AEFDBE5B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 01:00:21.684850 :AEFDC0CA:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 01:00:21.670 2025-07-08 01:00:21.684852 :AEFDC0CB:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18132 pso_num=29 pso_serial#=82 2025-07-08 01:00:21.684983 :AEFDC0CC:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18132 (legacy spawn) 2025-07-08 01:00:21.691441 :AEFDC0E7: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-07-08 01:00:21.691616 :AEFDC0EC: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-07-08 01:00:21.692409 :AEFDC0F1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 01:00:21.710413 :AEFDC0F4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 01:00:21.701 2025-07-08 01:00:21.710415 :AEFDC0F5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8228 pso_num=29 pso_serial#=83 2025-07-08 01:00:21.710556 :AEFDC0F6:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=8228 (legacy spawn) 2025-07-08 01:00:21.716998 :AEFDC0F7: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-07-08 01:00:21.717177 :AEFDC100: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-07-08 01:00:21.717968 :AEFDC101:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 01:00:24.818678 :AEFDC1F6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 01:00:24.805 2025-07-08 01:00:24.818680 :AEFDC1F7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17300 pso_num=29 pso_serial#=84 2025-07-08 01:00:24.818820 :AEFDC1F8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17300 (legacy spawn) 2025-07-08 01:00:24.825873 :AEFDC1F9: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-07-08 01:00:24.826076 :AEFDC1FE: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-07-08 01:00:24.826805 :AEFDC203:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 01:00:24.835968 :AEFDC206:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 01:00:24.821 2025-07-08 01:00:24.835970 :AEFDC207:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16700 pso_num=29 pso_serial#=85 2025-07-08 01:00:24.836095 :AEFDC208:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16700 (legacy spawn) 2025-07-08 01:00:24.843096 :AEFDC209: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-07-08 01:00:24.843267 :AEFDC212: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-07-08 01:00:24.844061 :AEFDC213:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 01:05:47.338085 :AEFE36BE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 01:05:47.329 2025-07-08 01:05:47.338087 :AEFE36BF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12648 pso_num=29 pso_serial#=86 2025-07-08 01:05:47.338228 :AEFE36C0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12648 (legacy spawn) 2025-07-08 01:05:47.348710 :AEFE36CD: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-07-08 01:05:47.348854 :AEFE36D6: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-07-08 01:05:47.349681 :AEFE36D7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 08:08:50.052393 :AF203735:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 08:08:50.039 2025-07-08 08:08:50.052395 :AF203736:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16512 pso_num=29 pso_serial#=87 2025-07-08 08:08:50.054318 :AF203737:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16512 osp_idx=27 osp_ver=14111 osp_pg=0 (spawn #363922) 2025-07-08 08:08:50.054319 :AF203738:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 08:08:50.054319 :AF203739:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=114 time=642905923 2025-07-08 08:08:50.054319 :AF20373A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 08:08:50.054319 :AF20373B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 08:08:50.054320 :AF20373C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 08:08:50.054320 :AF20373D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 08:08:50.054320 :AF20373E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 08:08:50.054905 :AF20373F: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-07-08 08:08:50.054947 :AF203745: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-07-08 08:08:50.055304 :AF203746:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 08:08:52.440069 :AF2038BB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 08:08:52.438 2025-07-08 08:08:52.440071 :AF2038BC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14420 pso_num=29 pso_serial#=88 2025-07-08 08:08:52.440207 :AF2038BD:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14420 osp_idx=27 osp_ver=14112 osp_pg=0 (spawn #363924) 2025-07-08 08:08:52.440208 :AF2038BE:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 08:08:52.440209 :AF2038BF:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=115 time=642908314 2025-07-08 08:08:52.440209 :AF2038C0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 08:08:52.440210 :AF2038C1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 08:08:52.440210 :AF2038C2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 08:08:52.440210 :AF2038C3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 08:08:52.440211 :AF2038C4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 08:08:52.440868 :AF2038C5: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-07-08 08:08:52.440910 :AF2038CB: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-07-08 08:08:52.441253 :AF2038CC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 08:08:54.556644 :AF203ECE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 08:08:54.547 2025-07-08 08:08:54.556646 :AF203ECF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14852 pso_num=29 pso_serial#=89 2025-07-08 08:08:54.556791 :AF203ED0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14852 (legacy spawn) 2025-07-08 08:08:54.583221 :AF203ED1: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-07-08 08:08:54.687472 :AF203EE3: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-07-08 08:08:54.769051 :AF203FDC: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-07-08 08:08:54.770601 :AF203FE1: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-07-08 08:08:54.770760 :AF203FE3: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-07-08 08:08:54.771838 :AF204032: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-07-08 08:08:54.772848 :AF20403E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 16:00:11.514698 :AF45D99A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 16:00:11.515 2025-07-08 16:00:11.514700 :AF45D99B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15304 pso_num=29 pso_serial#=90 2025-07-08 16:00:11.514830 :AF45D99C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15304 osp_idx=27 osp_ver=14114 osp_pg=0 (spawn #365013) 2025-07-08 16:00:11.514831 :AF45D99D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 16:00:11.514831 :AF45D99E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=116 time=671187392 2025-07-08 16:00:11.514832 :AF45D99F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 16:00:11.514832 :AF45D9A0:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 16:00:11.514833 :AF45D9A1:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 16:00:11.514833 :AF45D9A2:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 16:00:11.514833 :AF45D9A3:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 16:00:11.515356 :AF45D9B8: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-07-08 16:00:11.515393 :AF45D9BE: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-07-08 16:00:11.522597 :AF45D9CC: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-07-08 16:00:11.523648 :AF45D9D4: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-07-08 16:00:11.524150 :AF45D9DC: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-07-08 16:00:11.524592 :AF45D9E4: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-07-08 16:00:11.525053 :AF45D9EC: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-07-08 16:00:11.527604 :AF45D9F4: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-07-08 16:00:11.528679 :AF45D9FC: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-07-08 16:00:11.578244 :AF45DA04: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-07-08 16:00:11.579187 :AF45DA0C: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-07-08 16:00:11.579435 :AF45DA14: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-07-08 16:00:11.579657 :AF45DA1C: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-07-08 16:00:11.581406 :AF45DA24:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 22:00:00.202437 :AF625E62:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 22:00:00.190 2025-07-08 22:00:00.202438 :AF625E63:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16688 pso_num=29 pso_serial#=91 2025-07-08 22:00:00.202555 :AF625E69:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16688 osp_idx=27 osp_ver=14115 osp_pg=0 (spawn #365839) 2025-07-08 22:00:00.202555 :AF625E6A:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 22:00:00.202556 :AF625E6B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=117 time=692776095 2025-07-08 22:00:00.202557 :AF625E6C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 22:00:00.202557 :AF625E6D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 22:00:00.202557 :AF625E6E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 22:00:00.202558 :AF625E6F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 22:00:00.202558 :AF625E70:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 22:00:00.203076 :AF625E71: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-07-08 22:00:00.207966 :AF625E88: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-07-08 22:00:00.208004 :AF625E8B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-08 22:00:00.224666 :AF625F0B: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-07-08 22:00:00.224874 :AF625F13: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-07-08 22:00:00.225050 :AF625F1B: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-07-08 22:00:00.225219 :AF625F2A: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-07-08 22:00:00.225384 :AF625F32: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-07-08 22:00:00.225547 :AF625F3A: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-07-08 22:00:00.225715 :AF625F42: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-07-08 22:00:00.225879 :AF625F4A: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-07-08 22:00:00.226033 :AF625F52: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-07-08 22:00:00.226183 :AF625F5A: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-07-08 22:00:00.226339 :AF625F62: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-07-08 22:00:00.226501 :AF625F6A: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-07-08 22:00:00.226649 :AF625F72: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-07-08 22:00:00.226801 :AF625F7A: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-07-08 22:00:00.226982 :AF625F82: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-07-08 22:00:00.227146 :AF625F8A: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-07-08 22:00:00.227294 :AF625F92: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-07-08 22:00:00.227452 :AF625F9A: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-07-08 22:00:00.227820 :AF625FA2: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-07-08 22:00:00.228001 :AF625FAA: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-07-08 22:00:00.228155 :AF625FB2: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-07-08 22:00:00.228415 :AF625FBA: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-07-08 22:00:00.228752 :AF625FC2: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-07-08 22:00:00.229009 :AF625FCA: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-07-08 22:00:00.229652 :AF625FD2: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-07-08 22:00:00.229888 :AF625FDA: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-07-08 22:00:00.230166 :AF625FE2: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-07-08 22:00:00.257951 :AF625FEA: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-07-08 22:00:01.237346*:AF62635A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000030183AD0 estart=1751983201 eid=20197184 ctx=0x0000000777968158 cwh=0x000000002B560AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-08 22:00:01.237346*:AF62635B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000030183AD0 curCtx=0x000000004C3AC4B8 pubTabIdxCnt=0 2025-07-08 22:00:01.237346*:AF62635C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000030183AD0 2025-07-08 22:00:01.237346*:AF62635D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000030183AD0 2025-07-08 22:00:01.237346*:AF62635E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000301BAAF8 estart=1751983200 eid=16777442 ctx=0x000000077B54EAC8 cwh=0x000000002B560608 cpu=765625 buffg=43298 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-08 22:00:01.237346*:AF62635F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000301BAAF8 curCtx=0x0000000033FB1240 pubTabIdxCnt=0 2025-07-08 22:00:01.237346*:AF626360:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000301BAAF8 2025-07-08 22:00:01.237346*:AF626361:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000301BAAF8 2025-07-08 22:00:01.237346*:AF626362:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000030183AD0 curCtx=0x000000004C3AC4B8 xsc->flg4=65792 flags=1 2025-07-08 22:00:01.237346*:AF626363:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000030183AD0 2025-07-08 22:00:01.987176*:AF62649C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000301BAAF8 curCtx=0x0000000033FB1240 xsc->flg4=65792 flags=2 2025-07-08 22:00:01.987176*:AF62649D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000301BAAF8 2025-07-08 22:00:01.993285 :AF62649E: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-07-08 22:00:02.013773 :AF6264A6:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 9, ext 0, nex 3 2025-07-08 22:00:02.013782 :AF6264A7:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00800357 where: kduwh01: kdusru 2025-07-08 22:00:02.013958 :AF6264A8: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-07-08 22:00:02.015961 :AF6264C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-08 22:00:02.016150 :AF6264C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-08 22:00:02.017035 :AF6264D2: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-07-08 22:00:02.017127 :AF6264DA: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-07-08 22:00:02.018019 :AF6264E2: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-07-08 22:00:02.019663 :AF6264EA: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-07-08 22:00:02.019954 :AF626500: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-07-08 22:00:02.080936*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-08 22:00:02.080936*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-08 22:00:02.080936*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-08 22:00:02.080936*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-08 22:00:02.080936*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-08 22:00:02.080936*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-08 22:00:02.080936*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-08 22:00:02.153790 :AF62658D: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-07-08 22:00:04.065865 :AF6266E5: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-07-08 22:00:04.065880 :AF6266E6:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0008.019.0000ce63 2025-07-08 22:00:06.814427 :AF626899: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-07-08 22:00:06.815866 :AF6268A1: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-07-08 22:00:06.826375 :AF6268A9: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-07-08 22:00:06.852352 :AF6268B1: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-07-08 22:00:08.065259 :AF6269D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-07-08 22:00:08.075724 :AF6269D7: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-07-08 22:00:08.076916 :AF6269DF: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: 16688, J003)