Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250828220010\orcl_j003_21864_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:35152M/63366M, Ph+PgF:40187M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 21864, image: ORACLE.EXE (J003) *** 2025-08-28 22:00:10.551 *** 2025-08-28 22:00:10.551 Process diagnostic dump for ORACLE.EXE (J003), OS id=21864, pid: 29, proc_ser: 254, 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-08-28 22:00:10.551 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 21864, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-28 00:04:48.228477 :C5885BC9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:04:48.237155 :C5885BCC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:04:48.223 2025-08-28 00:04:48.237157 :C5885BCD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22368 pso_num=29 pso_serial#=240 2025-08-28 00:04:48.237291 :C5885BCE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22368 (legacy spawn) 2025-08-28 00:04:48.243847 :C5885BCF: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-08-28 00:04:48.244011 :C5885BD8: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-08-28 00:04:48.244812 :C5885BD9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:04:48.809563 :C5885BEC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:04:48.801 2025-08-28 00:04:48.809565 :C5885BED:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18716 pso_num=29 pso_serial#=241 2025-08-28 00:04:48.809709 :C5885BEE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18716 (legacy spawn) 2025-08-28 00:04:48.816651 :C5885BEF: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-08-28 00:04:48.816855 :C5885BF4: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-08-28 00:04:48.817850 :C5885BF9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:24.487198 :C588678C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:24.474 2025-08-28 00:05:24.487200 :C588678D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20168 pso_num=29 pso_serial#=242 2025-08-28 00:05:24.487335 :C588678E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20168 (legacy spawn) 2025-08-28 00:05:24.493918 :C588678F: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-08-28 00:05:24.494088 :C5886794: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-08-28 00:05:24.494880 :C5886799:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:24.503577 :C588679C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:24.489 2025-08-28 00:05:24.503579 :C588679D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22852 pso_num=29 pso_serial#=243 2025-08-28 00:05:24.503710 :C588679E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22852 (legacy spawn) 2025-08-28 00:05:24.510183 :C588679F: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-08-28 00:05:24.510345 :C58867A7: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-08-28 00:05:24.511115 :C58867A9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:50.408415 :C5887135:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:50.403 2025-08-28 00:05:50.408417 :C5887136:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13028 pso_num=29 pso_serial#=244 2025-08-28 00:05:50.408557 :C5887137:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13028 osp_idx=27 osp_ver=16563 osp_pg=0 (spawn #532781) 2025-08-28 00:05:50.408558 :C5887138:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 00:05:50.408558 :C5887139:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=243 time=725201705 2025-08-28 00:05:50.408559 :C588713A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 00:05:50.408559 :C588713B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 00:05:50.408559 :C588713C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 00:05:50.408560 :C588713D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 00:05:50.408560 :C588713E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 00:05:50.409066 :C588713F: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-08-28 00:05:50.409111 :C5887145: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-08-28 00:05:50.430127 :C58871B2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:51.049937 :C58871CC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:51.042 2025-08-28 00:05:51.049939 :C58871CD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22612 pso_num=29 pso_serial#=245 2025-08-28 00:05:51.050077 :C58871CE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22612 (legacy spawn) 2025-08-28 00:05:51.056580 :C58871CF: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-08-28 00:05:51.056755 :C58871D4: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-08-28 00:05:51.057622 :C58871D9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:51.832296 :C58871E8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:51.823 2025-08-28 00:05:51.832297 :C58871E9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22784 pso_num=29 pso_serial#=246 2025-08-28 00:05:51.832433 :C58871EA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22784 (legacy spawn) 2025-08-28 00:05:51.838895 :C58871EB: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-08-28 00:05:51.839056 :C58871F4: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-08-28 00:05:51.839851 :C58871F5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:21:46.735832 :C589AF37:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:21:46.733 2025-08-28 00:21:46.735834 :C589AF38:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23176 pso_num=29 pso_serial#=247 2025-08-28 00:21:46.735969 :C589AF39:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=23176 (legacy spawn) 2025-08-28 00:21:46.753330 :C589AF47: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-08-28 00:21:46.753491 :C589AF50: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-08-28 00:21:46.754283 :C589AF51:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:35:54.307676 :C58AC5AC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:35:54.295 2025-08-28 00:35:54.307678 :C58AC5AD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23368 pso_num=29 pso_serial#=248 2025-08-28 00:35:54.307812 :C58AC5AE:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23368 osp_idx=27 osp_ver=16567 osp_pg=0 (spawn #532849) 2025-08-28 00:35:54.307813 :C58AC5AF:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 00:35:54.307814 :C58AC5B0:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=244 time=727005612 2025-08-28 00:35:54.307814 :C58AC5B1:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 00:35:54.307814 :C58AC5B2:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 00:35:54.307815 :C58AC5B3:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 00:35:54.307815 :C58AC5B4:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 00:35:54.307816 :C58AC5B5:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 00:35:54.308329 :C58AC5B6: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-08-28 00:35:54.308369 :C58AC5BC: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-08-28 00:35:54.312552 :C58AC5D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.312593 :C58AC5D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.312856 :C58AC5D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.312872 :C58AC5D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.313084 :C58AC5D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.313099 :C58AC5DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.313305 :C58AC5DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.313318 :C58AC5DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.313519 :C58AC5DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.313531 :C58AC5DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.313805 :C58AC5DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.313818 :C58AC5E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.316114 :C58AC5E1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:16.671806 :C58CA394:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:16.665 2025-08-28 01:00:16.671808 :C58CA395:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19888 pso_num=29 pso_serial#=249 2025-08-28 01:00:16.671983 :C58CA396:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19888 (legacy spawn) 2025-08-28 01:00:16.678485 :C58CA397: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-08-28 01:00:16.678653 :C58CA3A0: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-08-28 01:00:16.679522 :C58CA3A1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:26.959215 :C58CA6AE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:26.950 2025-08-28 01:00:26.959217 :C58CA6AF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17404 pso_num=29 pso_serial#=250 2025-08-28 01:00:26.959358 :C58CA6B0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17404 (legacy spawn) 2025-08-28 01:00:26.966244 :C58CA6B1: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-08-28 01:00:26.966424 :C58CA6B6: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-08-28 01:00:26.967437 :C58CA6BB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:28.537743 :C58CA83A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:28.530 2025-08-28 01:00:28.537745 :C58CA83B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21988 pso_num=29 pso_serial#=251 2025-08-28 01:00:28.537882 :C58CA83C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21988 (legacy spawn) 2025-08-28 01:00:28.544344 :C58CA83D: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-08-28 01:00:28.544510 :C58CA846: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-08-28 01:00:28.545336 :C58CA847:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:32.086865 :C58CA92D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:32.072 2025-08-28 01:00:32.086867 :C58CA92E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22724 pso_num=29 pso_serial#=252 2025-08-28 01:00:32.087002 :C58CA92F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22724 (legacy spawn) 2025-08-28 01:00:32.093572 :C58CA93D: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-08-28 01:00:32.093738 :C58CA942: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-08-28 01:00:32.094527 :C58CA947:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:32.103266 :C58CA94A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:32.088 2025-08-28 01:00:32.103268 :C58CA94B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23456 pso_num=29 pso_serial#=253 2025-08-28 01:00:32.103427 :C58CA94C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=23456 (legacy spawn) 2025-08-28 01:00:32.110117 :C58CA94D: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-08-28 01:00:32.110282 :C58CA955: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-08-28 01:00:32.111077 :C58CA957:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 22:00:02.047713 :C5EF2938:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 22:00:02.041 2025-08-28 22:00:02.047715 :C5EF2939:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21864 pso_num=29 pso_serial#=254 2025-08-28 22:00:02.047860 :C5EF293A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21864 osp_idx=27 osp_ver=16573 osp_pg=0 (spawn #535817) 2025-08-28 22:00:02.047861 :C5EF293B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 22:00:02.047862 :C5EF293C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=245 time=804053346 2025-08-28 22:00:02.047863 :C5EF293D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 22:00:02.047863 :C5EF293E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 22:00:02.047864 :C5EF293F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 22:00:02.047864 :C5EF2940:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 22:00:02.047865 :C5EF2941:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 22:00:02.048439 :C5EF294B: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-08-28 22:00:02.053573 :C5EF2972: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-08-28 22:00:02.053611 :C5EF2977:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-28 22:00:02.071184 :C5EF2B58: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-08-28 22:00:02.071387 :C5EF2B60: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-08-28 22:00:02.071555 :C5EF2B68: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-08-28 22:00:02.071722 :C5EF2B70: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-08-28 22:00:02.071901 :C5EF2B78: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-08-28 22:00:02.072071 :C5EF2B80: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-08-28 22:00:02.072230 :C5EF2B88: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-08-28 22:00:02.072400 :C5EF2B90: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-08-28 22:00:02.072562 :C5EF2B98: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-08-28 22:00:02.072727 :C5EF2BA0: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-08-28 22:00:02.072904 :C5EF2BA8: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-08-28 22:00:02.073125 :C5EF2BB0: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-08-28 22:00:02.073335 :C5EF2BB8: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-08-28 22:00:02.073513 :C5EF2BC0: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-08-28 22:00:02.073673 :C5EF2BC8: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-08-28 22:00:02.073838 :C5EF2BD0: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-08-28 22:00:02.074192 :C5EF2BD8: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-08-28 22:00:02.074374 :C5EF2BE0: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-08-28 22:00:02.074537 :C5EF2BE8: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-08-28 22:00:02.074819 :C5EF2BF0: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-08-28 22:00:02.075097 :C5EF2BF8: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-08-28 22:00:02.075338 :C5EF2C00: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-08-28 22:00:02.075975 :C5EF2C08: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-08-28 22:00:02.103978 :C5EF2C10: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-08-28 22:00:03.807129 :C5EF2F23: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-08-28 22:00:03.829638 :C5EF2F2B: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-08-28 22:00:03.831870 :C5EF2F33:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-28 22:00:03.832118 :C5EF2F34:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-28 22:00:03.833150 :C5EF2F35: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-08-28 22:00:03.833349 :C5EF2F3D: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-08-28 22:00:03.834346 :C5EF2F45: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-08-28 22:00:03.836115 :C5EF2F4D: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-08-28 22:00:03.836482 :C5EF2F55: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-08-28 22:00:03.848070*:C5EF2F5E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000005AF1D688 estart=1756389602 eid=16778437 ctx=0x000000079F4EAD08 cwh=0x000000005ADCBB10 cpu=15625 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-08-28 22:00:03.848070*:C5EF2F61:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000005AF1D688 curCtx=0x000000005AF1C958 pubTabIdxCnt=0 2025-08-28 22:00:03.848070*:C5EF2F63:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000005AF1D688 2025-08-28 22:00:03.848070*:C5EF2F65:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000005AF1D688 2025-08-28 22:00:03.848070*:C5EF2F69:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000005AF1D688 curCtx=0x000000005AF1C958 xsc->flg4=65792 flags=1 2025-08-28 22:00:03.848070*:C5EF2F6A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000005AF1D688 2025-08-28 22:00:03.894947*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-08-28 22:00:03.894947*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-28 22:00:03.894947*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-28 22:00:03.910569*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-28 22:00:03.910569*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-28 22:00:03.910569*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-28 22:00:03.910569*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-28 22:00:03.986999 :C5EF2F71: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-08-28 22:00:05.897611 :C5EF315C: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-08-28 22:00:05.897622 :C5EF315D:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0007.000.0000cb2e 2025-08-28 22:00:08.328453 :C5EF331B: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-08-28 22:00:08.329832 :C5EF3323: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-08-28 22:00:08.340947 :C5EF332B: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-08-28 22:00:08.370225 :C5EF3333: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-08-28 22:00:09.599686 :C5EF3438:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-08-28 22:00:09.612514 :C5EF3439: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-08-28 22:00:09.614426 :C5EF3441: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: 21864, J003)