Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250906180418\orcl_j000_17424_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:35008M/63366M, Ph+PgF:39850M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 17424, image: ORACLE.EXE (J000) *** 2025-09-06 18:04:18.531 *** 2025-09-06 18:04:18.531 Process diagnostic dump for ORACLE.EXE (J000), OS id=17424, pid: 25, proc_ser: 105, 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-06 18:04:18.531 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 17424, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-06 18:01:45.820999 :C9DC5530:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:01:45.821926 :C9DC5531:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:02:06.145012 :C9DC5B3F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:02:06.134 2025-09-06 18:02:06.145015 :C9DC5B40:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22460 pso_num=25 pso_serial#=91 2025-09-06 18:02:06.145177 :C9DC5B41:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22460 (legacy spawn) 2025-09-06 18:02:06.152390 :C9DC5B42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:06.152611 :C9DC5B49:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:02:06.153583 :C9DC5B4C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:02:10.519370 :C9DC5CC7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:02:10.514 2025-09-06 18:02:10.519372 :C9DC5CC8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20932 pso_num=25 pso_serial#=92 2025-09-06 18:02:10.519508 :C9DC5CC9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20932 osp_idx=23 osp_ver=283155 osp_pg=0 (spawn #565276) 2025-09-06 18:02:10.519508 :C9DC5CCA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 18:02:10.519509 :C9DC5CCB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=187 time=1567381830 2025-09-06 18:02:10.519509 :C9DC5CCC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 18:02:10.519510 :C9DC5CCD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 18:02:10.519510 :C9DC5CCE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 18:02:10.519510 :C9DC5CCF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 18:02:10.519511 :C9DC5CD0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 18:02:10.520005 :C9DC5CD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 18:02:10.520643 :C9DC5CE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:10.534967 :C9DC5D15:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-06 18:02:10.535051 :C9DC5D16:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-06 18:02:10.535892 :C9DC5D17:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-06 18:02:10.536221 :C9DC5D18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:10.536263 :C9DC5D1B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-06 18:02:10.544174 :C9DC5D21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:10.544243 :C9DC5D24:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-09-06 18:02:10.544280 :C9DC5D25:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-09-06 18:02:10.544673 :C9DC5D2B:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 18:02:10.545038 :C9DC5D2C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 18:02:10.545234 :C9DC5D2D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-06 18:02:10.545481 :C9DC5D2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:10.545507 :C9DC5D31:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-06 18:02:10.547191 :C9DC5D37:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-06 18:02:10.547449 :C9DC5D38:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:10.547480 :C9DC5D3B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-06 18:02:10.548409 :C9DC5D41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:10.548542 :C9DC5D4A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:02:40.645727 :C9DC66FC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:02:46.999492 :C9DC68D8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:02:46.991 2025-09-06 18:02:46.999494 :C9DC68D9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24236 pso_num=25 pso_serial#=93 2025-09-06 18:02:46.999628 :C9DC68DA:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24236 (legacy spawn) 2025-09-06 18:02:47.006137 :C9DC68DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:47.006312 :C9DC68E4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:02:47.007156 :C9DC68E5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:02:55.404324 :C9DC6E6C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:02:55.390 2025-09-06 18:02:55.404326 :C9DC6E6D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20004 pso_num=25 pso_serial#=94 2025-09-06 18:02:55.404468 :C9DC6E6E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20004 (legacy spawn) 2025-09-06 18:02:55.410995 :C9DC6E6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:55.411171 :C9DC6E78:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:02:55.411959 :C9DC6E79:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:02:55.429911 :C9DC6E7C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:02:55.421 2025-09-06 18:02:55.429913 :C9DC6E7D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24140 pso_num=25 pso_serial#=95 2025-09-06 18:02:55.430046 :C9DC6E7E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24140 (legacy spawn) 2025-09-06 18:02:55.436605 :C9DC6E7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:55.436768 :C9DC6E87:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:02:55.437577 :C9DC6E89:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:02:58.490991 :C9DC6F82:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:02:58.478 2025-09-06 18:02:58.490993 :C9DC6F83:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21620 pso_num=25 pso_serial#=96 2025-09-06 18:02:58.491133 :C9DC6F84:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21620 (legacy spawn) 2025-09-06 18:02:58.497682 :C9DC6F85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:58.497861 :C9DC6F8A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:02:58.498657 :C9DC6F8F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:02:58.507425 :C9DC6F92:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:02:58.494 2025-09-06 18:02:58.507427 :C9DC6F93:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20476 pso_num=25 pso_serial#=97 2025-09-06 18:02:58.507607 :C9DC6F94:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20476 (legacy spawn) 2025-09-06 18:02:58.514073 :C9DC6F95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:02:58.514247 :C9DC6F9E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:02:58.515078 :C9DC6F9F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:03:10.607562 :C9DC7366:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:03:10.601 2025-09-06 18:03:10.607564 :C9DC7367:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23876 pso_num=25 pso_serial#=98 2025-09-06 18:03:10.607704 :C9DC7368:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23876 osp_idx=23 osp_ver=283161 osp_pg=0 (spawn #565278) 2025-09-06 18:03:10.607704 :C9DC7369:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 18:03:10.607705 :C9DC736A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=188 time=1567441908 2025-09-06 18:03:10.607705 :C9DC736B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 18:03:10.607706 :C9DC736C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 18:03:10.607706 :C9DC736D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 18:03:10.607706 :C9DC736E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 18:03:10.607707 :C9DC736F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 18:03:10.608237 :C9DC7370:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 18:03:10.608889 :C9DC7385:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:03:10.616436 :C9DC739C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-06 18:03:10.616808 :C9DC739D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:03:10.616837 :C9DC739E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-06 18:03:10.631588 :C9DC73AB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:03:40.702890 :C9DC7D0F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:03:48.252369 :C9DC7F45:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:03:48.243 2025-09-06 18:03:48.252371 :C9DC7F46:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23016 pso_num=25 pso_serial#=99 2025-09-06 18:03:48.252506 :C9DC7F47:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23016 (legacy spawn) 2025-09-06 18:03:48.258991 :C9DC7F48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:03:48.259160 :C9DC7F4D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:03:48.259951 :C9DC7F52:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:03:49.158367 :C9DC7F96:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:03:49.150 2025-09-06 18:03:49.158369 :C9DC7F97:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24036 pso_num=25 pso_serial#=100 2025-09-06 18:03:49.158508 :C9DC7F98:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24036 (legacy spawn) 2025-09-06 18:03:49.165508 :C9DC7F99:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:03:49.165696 :C9DC7F9E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:03:49.166599 :C9DC7FA3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:03:56.458545 :C9DC81BC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:03:56.450 2025-09-06 18:03:56.458547 :C9DC81BD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17500 pso_num=25 pso_serial#=101 2025-09-06 18:03:56.458683 :C9DC81BE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17500 (legacy spawn) 2025-09-06 18:03:56.465121 :C9DC81BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:03:56.465306 :C9DC81C8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:03:56.466115 :C9DC81C9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:04:07.996767 :C9DC8546:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:04:07.985 2025-09-06 18:04:07.996770 :C9DC8547:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22484 pso_num=25 pso_serial#=102 2025-09-06 18:04:07.996920 :C9DC8548:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22484 (legacy spawn) 2025-09-06 18:04:08.003962 :C9DC8549:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:08.004168 :C9DC8551:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:04:08.005086 :C9DC8552:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:04:08.014351 :C9DC8555:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:04:08.001 2025-09-06 18:04:08.014354 :C9DC8556:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23784 pso_num=25 pso_serial#=103 2025-09-06 18:04:08.014513 :C9DC8557:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23784 (legacy spawn) 2025-09-06 18:04:08.021657 :C9DC8558:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:08.021834 :C9DC8561:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:04:08.022847 :C9DC8562:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:04:10.521404 :C9DC8630:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:04:10.516 2025-09-06 18:04:10.521407 :C9DC8631:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22608 pso_num=25 pso_serial#=104 2025-09-06 18:04:10.521548 :C9DC8632:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22608 osp_idx=23 osp_ver=283167 osp_pg=0 (spawn #565280) 2025-09-06 18:04:10.521549 :C9DC8633:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 18:04:10.521550 :C9DC8634:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=189 time=1567501830 2025-09-06 18:04:10.521550 :C9DC8635:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 18:04:10.521550 :C9DC8636:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 18:04:10.521551 :C9DC8637:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 18:04:10.521551 :C9DC8638:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 18:04:10.521551 :C9DC8639:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 18:04:10.522108 :C9DC863A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.522157 :C9DC8640:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 18:04:10.543748 :C9DC86C6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 18:04:10.635073 :C9DC87C5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 18:04:10.625 2025-09-06 18:04:10.635075 :C9DC87C6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17424 pso_num=25 pso_serial#=105 2025-09-06 18:04:10.635674 :C9DC87C7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17424 osp_idx=23 osp_ver=283168 osp_pg=0 (spawn #565284) 2025-09-06 18:04:10.635676 :C9DC87C8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 18:04:10.635677 :C9DC87C9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=190 time=1567501940 2025-09-06 18:04:10.635678 :C9DC87CA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 18:04:10.635679 :C9DC87CB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 18:04:10.635679 :C9DC87CC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 18:04:10.635679 :C9DC87CD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 18:04:10.635681 :C9DC87CE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 18:04:10.636683 :C9DC87CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.642194 :C9DC87EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.642250 :C9DC87F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-09-06 18:04:10.660261 :C9DC8807:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.660480 :C9DC880F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.660644 :C9DC8817:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.660799 :C9DC881F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.661015 :C9DC8827:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.661253 :C9DC882F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.661397 :C9DC8837:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.661731 :C9DC883F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.661970 :C9DC8847:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.662293 :C9DC884F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.662440 :C9DC8857:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.662593 :C9DC885F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:10.681556 :C9DC8867:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:12.331995 :C9DC8AF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] 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-09-06 18:04:12.338890 :C9DC8B07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:12.359947 :C9DC8B29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:12.361947 :C9DC8B31:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-06 18:04:12.362180 :C9DC8B32:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-06 18:04:12.363107 :C9DC8B33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:12.363205 :C9DC8B36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:12.364088 :C9DC8B48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:12.365689 :C9DC8B50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:12.365974 :C9DC8B58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:12.416663*: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-06 18:04:12.416663*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-06 18:04:12.416663*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-06 18:04:12.463538*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-06 18:04:12.463538*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-06 18:04:12.463538*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-06 18:04:12.463538*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-06 18:04:12.527772 :C9DC8BE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:14.483859 :C9DC8C8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:14.483873 :C9DC8C8D:db_trace:ktur.c@3079:ktuabt(): [10444:25:201] ABORT TRANSACTION - xid: 0x000a.006.0017b8a2 2025-09-06 18:04:17.073828 :C9DC8E3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:17.075279 :C9DC8E47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:17.086064 :C9DC8E4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:17.115773 :C9DC8E57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:18.278929 :C9DC8F46:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-09-06 18:04:18.288940 :C9DC8F47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:18.289353 :C9DC8F4F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 17424, J000)