Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250708080852\orcl_m001_14420_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:36545M/63366M, Ph+PgF:42618M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 14420, image: ORACLE.EXE (M001) *** 2025-07-08 08:08:53.031 *** 2025-07-08 08:08:53.031 Process diagnostic dump for ORACLE.EXE (M001), OS id=14420, pid: 29, proc_ser: 88, 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 08:08:53.031 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 14420, M001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-07 22:00:19.233582 :AEEF707D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13572 pso_num=29 pso_serial#=69 2025-07-07 22:00:19.233711 :AEEF707E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13572 (legacy spawn) 2025-07-07 22:00:19.240097 :AEEF707F: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-07 22:00:19.240249 :AEEF7087: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-07 22:00:19.241052 :AEEF7089:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-07 22:00:26.139411 :AEEF72F1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-07 22:00:26.131 2025-07-07 22:00:26.139413 :AEEF72F2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15328 pso_num=29 pso_serial#=70 2025-07-07 22:00:26.139562 :AEEF72F3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15328 (legacy spawn) 2025-07-07 22:00:26.146094 :AEEF72F4: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-07 22:00:26.146264 :AEEF72FC: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-07 22:00:26.147072 :AEEF72FD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-07 22:00:28.996569 :AEEF73FB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-07 22:00:28.991 2025-07-07 22:00:28.996571 :AEEF73FC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13920 pso_num=29 pso_serial#=71 2025-07-07 22:00:28.996703 :AEEF73FD:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13920 osp_idx=27 osp_ver=14095 osp_pg=0 (spawn #362515) 2025-07-07 22:00:28.996704 :AEEF73FE: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-07 22:00:28.996705 :AEEF73FF:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=112 time=606404892 2025-07-07 22:00:28.996705 :AEEF7400:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-07 22:00:28.996705 :AEEF7401:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-07 22:00:28.996706 :AEEF7402:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-07 22:00:28.996706 :AEEF7403:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-07 22:00:28.996706 :AEEF7404:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-07 22:00:28.997197 :AEEF7405: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-07 22:00:28.997247 :AEEF740B: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-07 22:00:29.005488 :AEEF741A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-07 23:59:07.205504 :AEF8DCD9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-07 23:59:07.196 2025-07-07 23:59:07.205506 :AEF8DCDA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14876 pso_num=29 pso_serial#=72 2025-07-07 23:59:07.205646 :AEF8DCDB:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14876 (legacy spawn) 2025-07-07 23:59:07.212398 :AEF8DCDC: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-07 23:59:07.212581 :AEF8DCE1: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-07 23:59:07.213419 :AEF8DCE6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-07 23:59:07.222559 :AEF8DCE9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-07 23:59:07.212 2025-07-07 23:59:07.222562 :AEF8DCEA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17944 pso_num=29 pso_serial#=73 2025-07-07 23:59:07.222703 :AEF8DCEB:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17944 (legacy spawn) 2025-07-07 23:59:07.229542 :AEF8DCEC: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-07 23:59:07.229704 :AEF8DCF5: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-07 23:59:07.230547 :AEF8DCF6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-07 23:59:09.936291 :AEF8DDEE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-07 23:59:09.932 2025-07-07 23:59:09.936292 :AEF8DDEF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=1112 pso_num=29 pso_serial#=74 2025-07-07 23:59:09.936443 :AEF8DDF0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=1112 (legacy spawn) 2025-07-07 23:59:09.952403 :AEF8DDF1: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-07 23:59:13.332489 :AEF8DF43: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-07 23:59:13.332671 :AEF8DF48: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-07 23:59:13.333851 :AEF8DF4D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-07 23:59:13.597428 :AEF8DF67:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-07 23:59:13.592 2025-07-07 23:59:13.597431 :AEF8DF68:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2680 pso_num=29 pso_serial#=75 2025-07-07 23:59:13.597571 :AEF8DF69:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=2680 (legacy spawn) 2025-07-07 23:59:13.612477 :AEF8DF6A: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-07 23:59:46.705843 :AEF8EBD2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-07 23:59:46.740304 :AEF8EBE8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-07 23:59:46.734 2025-07-07 23:59:46.740307 :AEF8EBE9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13948 pso_num=29 pso_serial#=76 2025-07-07 23:59:46.740435 :AEF8EBEA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13948 (legacy spawn) 2025-07-07 23:59:46.758437 :AEF8EBEB: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-07 23:59:46.762043 :AEF8EBF4: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-07 23:59:50.095379 :AEF8ED1B: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-07 23:59:50.095548 :AEF8ED24: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-07 23:59:50.096777 :AEF8ED25:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-07 23:59:50.354413 :AEF8ED3F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-07 23:59:50.349 2025-07-07 23:59:50.354415 :AEF8ED40:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12364 pso_num=29 pso_serial#=77 2025-07-07 23:59:50.354568 :AEF8ED41:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12364 (legacy spawn) 2025-07-07 23:59:50.369315 :AEF8ED42: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 00:00:23.475642 :AEF8F9E8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 01:00:04.681798 :AEFDBA30:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 01:00:04.669 2025-07-08 01:00:04.681800 :AEFDBA31:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13380 pso_num=29 pso_serial#=78 2025-07-08 01:00:04.681934 :AEFDBA32:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13380 (legacy spawn) 2025-07-08 01:00:04.688482 :AEFDBA33: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:04.688653 :AEFDBA39: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:04.689449 :AEFDBA3D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 01:00:06.250234 :AEFDBA85:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 01:00:06.241 2025-07-08 01:00:06.250237 :AEFDBA86:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15856 pso_num=29 pso_serial#=79 2025-07-08 01:00:06.250374 :AEFDBA87:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15856 (legacy spawn) 2025-07-08 01:00:06.257162 :AEFDBA94: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:06.257337 :AEFDBA99: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:06.258177 :AEFDBA9E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-08 01:00:10.208335 :AEFDBC22:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-08 01:00:10.203 2025-07-08 01:00:10.208337 :AEFDBC23:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15552 pso_num=29 pso_serial#=80 2025-07-08 01:00:10.208476 :AEFDBC24:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15552 osp_idx=27 osp_ver=14104 osp_pg=0 (spawn #362929) 2025-07-08 01:00:10.208477 :AEFDBC25: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 01:00:10.208477 :AEFDBC26:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=113 time=617186095 2025-07-08 01:00:10.208477 :AEFDBC27:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 01:00:10.208478 :AEFDBC28:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 01:00:10.208478 :AEFDBC29:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 01:00:10.208478 :AEFDBC2A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 01:00:10.208479 :AEFDBC2B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 01:00:10.208995 :AEFDBC2C: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 01:00:10.209050 :AEFDBC32: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 01:00:10.213507 :AEFDBC45: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:10.238964 :AEFDBC4D:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286467894 8499408) 2025-07-08 01:00:10.238971 :AEFDBC4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 01:00:10.239037 :AEFDBC56:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-08 01:00:10.239077 :AEFDBC66: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:10.254470 :AEFDBC6E: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:10.283633 :AEFDBC82: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:10.287575 :AEFDBC8A: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:10.291290 :AEFDBC92: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:10.296647 :AEFDBC9A:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 0, nex 2 2025-07-08 01:00:10.296657 :AEFDBC9B:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00813a72 where: kdiwh22: kdifind 2025-07-08 01:00:10.296952 :AEFDBC9C: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:10.297993 :AEFDBCA4: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.245496*:AEFDBCC2:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B569D30 estart=1751907610 eid=16779788 ctx=0x000000077BA4B1C8 cwh=0x000000002B520500 cpu=312500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-07-08 01:00:11.245496*:AEFDBCC3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B569D30 curCtx=0x000000002DB3AC28 pubTabIdxCnt=0 2025-07-08 01:00:11.245496*:AEFDBCC4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B569D30 2025-07-08 01:00:11.245496*:AEFDBCC5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B569D30 2025-07-08 01:00:11.245496*:AEFDBCC6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B5673A0 estart=1751907610 eid=16779773 ctx=0x0000000787AD5BF0 cwh=0x000000002B520828 cpu=312500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-07-08 01:00:11.245496*:AEFDBCC7:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B5673A0 curCtx=0x000000002DB3CB30 pubTabIdxCnt=0 2025-07-08 01:00:11.245496*:AEFDBCC8:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B5673A0 2025-07-08 01:00:11.245496*:AEFDBCC9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B5673A0 2025-07-08 01:00:11.479877*:AEFDBCE1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B569D30 curCtx=0x000000002DB3AC28 xsc->flg4=65792 flags=1 2025-07-08 01:00:11.479877*:AEFDBCE2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B569D30 2025-07-08 01:00:11.495495*:AEFDBCE3:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B5673A0 curCtx=0x000000002DB3CB30 xsc->flg4=65792 flags=2 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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 14420, M001)