Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250510100014\orcl_ora_12084_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:37531M/63366M, Ph+PgF:44936M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12084, image: ORACLE.EXE (SHAD) *** 2025-05-10 10:00:14.641 *** 2025-05-10 10:00:14.641 Process diagnostic dump for ORACLE.EXE (SHAD), OS id=12084, pid: 25, proc_ser: 146, 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-05-10 10:00:14.641 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12084) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-10 09:50:09.393125 :952AF966:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:50:35.708020 :952B0205:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:50:35.696 2025-05-10 09:50:35.708023 :952B0206:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11320 pso_num=25 pso_serial#=134 2025-05-10 09:50:35.708165 :952B0207:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11320 osp_idx=23 osp_ver=85048 osp_pg=0 (spawn #167352) 2025-05-10 09:50:35.708166 :952B0208:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:50:35.708166 :952B0209:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=74 time=4141412875 2025-05-10 09:50:35.708167 :952B020A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:50:35.708167 :952B020B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:50:35.708167 :952B020C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:50:35.708168 :952B020D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:50:35.708168 :952B020E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:50:35.708653 :952B020F: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-05-10 09:51:05.807680 :952B0B6B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:51:35.795992 :952B1518:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:51:35.785 2025-05-10 09:51:35.795994 :952B1519:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10960 pso_num=25 pso_serial#=135 2025-05-10 09:51:35.796122 :952B151A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10960 osp_idx=23 osp_ver=85049 osp_pg=0 (spawn #167354) 2025-05-10 09:51:35.796123 :952B151B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:51:35.796124 :952B151C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=75 time=4141472953 2025-05-10 09:51:35.796124 :952B151D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:51:35.796124 :952B151E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:51:35.796125 :952B151F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:51:35.796125 :952B1520:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:51:35.796125 :952B1521:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:51:35.796630 :952B1522: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-05-10 09:52:05.877004 :952B1EB4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:52:35.865058 :952B2882:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:52:35.853 2025-05-10 09:52:35.865060 :952B2883:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12232 pso_num=25 pso_serial#=136 2025-05-10 09:52:35.865195 :952B2884:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12232 osp_idx=23 osp_ver=85050 osp_pg=0 (spawn #167356) 2025-05-10 09:52:35.865196 :952B2885:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:52:35.865196 :952B2886:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=76 time=4141533031 2025-05-10 09:52:35.865196 :952B2887:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:52:35.865197 :952B2888:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:52:35.865197 :952B2889:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:52:35.865197 :952B288A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:52:35.865198 :952B288B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:52:35.865675 :952B288C: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-05-10 09:53:05.952280 :952B3260:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:53:35.965346 :952B3C1A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:53:35.954 2025-05-10 09:53:35.965348 :952B3C1B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11996 pso_num=25 pso_serial#=137 2025-05-10 09:53:35.965474 :952B3C1C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11996 osp_idx=23 osp_ver=85051 osp_pg=0 (spawn #167358) 2025-05-10 09:53:35.965474 :952B3C1D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:53:35.965474 :952B3C1E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=77 time=4141593125 2025-05-10 09:53:35.965475 :952B3C1F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:53:35.965475 :952B3C20:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:53:35.965477 :952B3C21:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:53:35.965477 :952B3C22:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:53:35.965478 :952B3C23:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:53:35.965941 :952B3C24: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-05-10 09:54:06.074346 :952B45B0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:54:36.060181 :952B4F49:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:54:36.049 2025-05-10 09:54:36.060183 :952B4F4A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10608 pso_num=25 pso_serial#=138 2025-05-10 09:54:36.060313 :952B4F4B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10608 osp_idx=23 osp_ver=85052 osp_pg=0 (spawn #167361) 2025-05-10 09:54:36.060314 :952B4F4C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:54:36.060314 :952B4F4D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=78 time=4141653218 2025-05-10 09:54:36.060315 :952B4F4E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:54:36.060315 :952B4F4F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:54:36.060315 :952B4F50:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:54:36.060316 :952B4F51:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:54:36.060316 :952B4F52:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:54:36.060798 :952B4F53: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-05-10 09:55:06.163549 :952B59EC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:55:36.165569 :952B643E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:55:36.154 2025-05-10 09:55:36.165571 :952B643F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12820 pso_num=25 pso_serial#=139 2025-05-10 09:55:36.165711 :952B6440:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12820 osp_idx=23 osp_ver=85053 osp_pg=0 (spawn #167363) 2025-05-10 09:55:36.165712 :952B6441:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:55:36.165713 :952B6442:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=79 time=4141713328 2025-05-10 09:55:36.165713 :952B6443:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:55:36.165714 :952B6444:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:55:36.165714 :952B6445:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:55:36.165714 :952B6446:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:55:36.165715 :952B6447:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:55:36.166218 :952B6448: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-05-10 09:56:06.224938 :952B6D9B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:56:36.251603 :952B772F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:56:36.246 2025-05-10 09:56:36.251604 :952B7730:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11900 pso_num=25 pso_serial#=140 2025-05-10 09:56:36.251729 :952B7731:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11900 osp_idx=23 osp_ver=85054 osp_pg=0 (spawn #167364) 2025-05-10 09:56:36.251729 :952B7732:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:56:36.251730 :952B7733:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=80 time=4141773421 2025-05-10 09:56:36.251730 :952B7734:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:56:36.251731 :952B7735:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:56:36.251731 :952B7736:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:56:36.251731 :952B7737:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:56:36.251732 :952B7738:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:56:36.252187 :952B7739: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-05-10 09:56:36.253188 :952B774E: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-05-10 09:56:36.264429 :952B776A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-10 09:56:36.264941 :952B776B:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-10 09:56:36.267267 :952B776C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-10 09:56:36.267606 :952B776D: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-05-10 09:56:36.267642 :952B7770:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-10 09:56:36.268506 :952B7776: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-05-10 09:56:36.268632 :952B777F: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-05-10 09:57:06.319861 :952B80E8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:57:36.320195 :952B8AB9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:57:36.309 2025-05-10 09:57:36.320197 :952B8ABA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11828 pso_num=25 pso_serial#=141 2025-05-10 09:57:36.320330 :952B8ABB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11828 osp_idx=23 osp_ver=85055 osp_pg=0 (spawn #167367) 2025-05-10 09:57:36.320330 :952B8ABC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:57:36.320331 :952B8ABD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=81 time=4141833484 2025-05-10 09:57:36.320331 :952B8ABE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:57:36.320332 :952B8ABF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:57:36.320332 :952B8AC0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:57:36.320333 :952B8AC1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:57:36.320333 :952B8AC2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:57:36.320897 :952B8AC3: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-05-10 09:58:06.409437 :952B950B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:58:36.400787 :952B9EB0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:58:36.389 2025-05-10 09:58:36.400789 :952B9EB1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9964 pso_num=25 pso_serial#=142 2025-05-10 09:58:36.400918 :952B9EB2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9964 osp_idx=23 osp_ver=85056 osp_pg=0 (spawn #167369) 2025-05-10 09:58:36.400918 :952B9EB3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:58:36.400919 :952B9EB4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=82 time=4141893562 2025-05-10 09:58:36.400919 :952B9EB5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:58:36.400920 :952B9EB6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:58:36.400920 :952B9EB7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:58:36.400920 :952B9EB8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:58:36.400921 :952B9EB9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:58:36.401433 :952B9EBA: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-05-10 09:59:06.520532 :952BA876:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 09:59:31.481200 :952BB077:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 09:59:31.469 2025-05-10 09:59:31.481202 :952BB078:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12792 pso_num=25 pso_serial#=143 2025-05-10 09:59:31.481329 :952BB079:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12792 osp_idx=23 osp_ver=85057 osp_pg=0 (spawn #167371) 2025-05-10 09:59:31.481330 :952BB07A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:59:31.481330 :952BB07B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=83 time=4141948640 2025-05-10 09:59:31.481331 :952BB07C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:59:31.481331 :952BB07D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:59:31.481332 :952BB07E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:59:31.481332 :952BB07F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:59:31.481332 :952BB080:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:59:31.481806 :952BB081: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-05-10 09:59:36.480194 :952BB248:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-10 09:59:36.480223*:952BB249:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DCBB58 estart=1746842376 eid=16853280 ctx=0x000000077BD98A08 cwh=0x0000000024D801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-10 09:59:36.480223*:952BB24A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 pubTabIdxCnt=0 2025-05-10 09:59:36.480223*:952BB24B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DCBB58 2025-05-10 09:59:36.480223*:952BB24C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DCBB58 2025-05-10 09:59:36.480223*:952BB24D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 xsc->flg4=65792 flags=2 2025-05-10 09:59:36.480223*:952BB24E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DCBB58 2025-05-10 09:59:36.480750 :952BB24F: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-05-10 09:59:36.488339 :952BB257:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-10 09:59:36.488707 :952BB258: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-05-10 09:59:36.488748 :952BB25B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-10 09:59:36.489576 :952BB261: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-05-10 09:59:36.489712 :952BB26A: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-05-10 10:00:06.602740 :952BBC1C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 10:00:10.641056 :952BBDB9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 10:00:10.630 2025-05-10 10:00:10.641057 :952BBDBA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7672 pso_num=25 pso_serial#=144 2025-05-10 10:00:10.641185 :952BBDBC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7672 osp_idx=23 osp_ver=85058 osp_pg=0 (spawn #167373) 2025-05-10 10:00:10.641186 :952BBDBD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 10:00:10.641186 :952BBDBE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=84 time=4141987796 2025-05-10 10:00:10.641187 :952BBDBF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 10:00:10.641187 :952BBDC0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 10:00:10.641188 :952BBDC1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 10:00:10.641188 :952BBDC2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 10:00:10.641188 :952BBDC3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 10:00:10.641687 :952BBDC7: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-05-10 10:00:10.641726 :952BBDCE: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-05-10 10:00:10.647186 :952BBDE7: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-05-10 10:00:10.681871 :952BBE50:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286484495 8482801) 2025-05-10 10:00:10.681879 :952BBE51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.681931 :952BBE59:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-10 10:00:10.681981 :952BBE69: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-05-10 10:00:10.696528 :952BBE71: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-05-10 10:00:10.723199 :952BBEE3: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-05-10 10:00:10.726921 :952BBEF3: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-05-10 10:00:10.730538 :952BBF03: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-05-10 10:00:10.734726 :952BBF29: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-05-10 10:00:10.735618 :952BBF47: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-05-10 10:00:10.789612 :952BC0C4: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-05-10 10:00:10.790245 :952BC0E6: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-05-10 10:00:10.790399 :952BC0F0: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-05-10 10:00:10.793912 :952BC19A: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-05-10 10:00:11.980116 :952BC282: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-05-10 10:00:12.005908 :952BC28A: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-05-10 10:00:12.006251 :952BC292: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-05-10 10:00:12.006532 :952BC29A: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-05-10 10:00:12.006548 :952BC29D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:12.006609 :952BC2AA: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-05-10 10:00:12.012604 :952BC2B7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 10:00:14.542826 :952BC4E1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 10:00:14.531 2025-05-10 10:00:14.542828 :952BC4E2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6556 pso_num=25 pso_serial#=145 2025-05-10 10:00:14.542966 :952BC4E3:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=6556 (legacy spawn) 2025-05-10 10:00:14.554696 :952BC4E9: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-05-10 10:00:14.554844 :952BC4F2: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-05-10 10:00:14.555683 :952BC4F4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-10 10:00:14.565097 :952BC4FB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-10 10:00:14.563 2025-05-10 10:00:14.565099 :952BC4FC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12084 pso_num=25 pso_serial#=146 2025-05-10 10:00:14.565242 :952BC4FD:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12084 (legacy spawn) 2025-05-10 10:00:14.580364 :952BC506: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-05-10 10:00:14.580518 :952BC50F: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-05-10 10:00:14.581309 :952BC510: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: 12084)