Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250510060006\orcl_vkrm_12672_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:37501M/63366M, Ph+PgF:44880M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 12672, image: ORACLE.EXE (VKRM) *** 2025-05-10 06:00:06.135 *** SESSION ID:(193.63253) 2025-05-10 06:00:06.135 *** 2025-05-10 06:00:06.135 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=12672, pid: 24, proc_ser: 34, sid: 193, sess_ser: 63253 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=6.123226 sec, exc=6.123226 sec, total=6.123226 sec wait times: max=infinite, heur=6.123226 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000000 sec since current wait Sampled Session History of session 193 serial 63253 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 06:00:00 - 06:00:06] idle wait at each sample [session created at: 06:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-10 06:00:06.135 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 12672, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-10 05:56:13.954838 :95184D39:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-10 05:56:13.955794 :95184D3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:56:13.955933 :95184D48:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:56:44.059729 :951856E5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:56:45.802247 :9518578D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:56:45.794 2025-05-10 05:56:45.802249 :9518578E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12000 pso_num=24 pso_serial#=15 2025-05-10 05:56:45.802394 :9518578F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12000 (legacy spawn) 2025-05-10 05:56:45.808868 :95185790:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:56:45.809035 :95185799:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:56:45.809896 :9518579A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:56:46.408101 :951857A9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:56:46.400 2025-05-10 05:56:46.408103 :951857AA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13032 pso_num=24 pso_serial#=16 2025-05-10 05:56:46.408247 :951857AB:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13032 (legacy spawn) 2025-05-10 05:56:46.414635 :951857AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:56:46.414806 :951857B3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:56:46.415659 :951857B6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:57:03.323147 :95185C98:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:57:03.320 2025-05-10 05:57:03.323149 :95185C99:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11708 pso_num=24 pso_serial#=17 2025-05-10 05:57:03.323290 :95185C9A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11708 (legacy spawn) 2025-05-10 05:57:03.329715 :95185C9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:57:03.329890 :95185CA4:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:57:03.330688 :95185CA5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:57:03.359891 :95185CA8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:57:03.351 2025-05-10 05:57:03.359894 :95185CA9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12292 pso_num=24 pso_serial#=18 2025-05-10 05:57:03.360030 :95185CAA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12292 (legacy spawn) 2025-05-10 05:57:03.366446 :95185CAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:57:03.366616 :95185CB4:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:57:03.367408 :95185CB5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:57:06.436266 :95185DA4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:57:06.423 2025-05-10 05:57:06.436268 :95185DA5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2132 pso_num=24 pso_serial#=19 2025-05-10 05:57:06.436411 :95185DA6:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=2132 (legacy spawn) 2025-05-10 05:57:06.442871 :95185DA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:57:06.443055 :95185DAF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:57:06.443850 :95185DB1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:57:06.452421 :95185DB4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:57:06.439 2025-05-10 05:57:06.452423 :95185DB5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12848 pso_num=24 pso_serial#=20 2025-05-10 05:57:06.452559 :95185DB6:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12848 (legacy spawn) 2025-05-10 05:57:06.459095 :95185DB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:57:06.459273 :95185DC0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:57:06.460064 :95185DC1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:57:14.053137 :95186041:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:57:14.048 2025-05-10 05:57:14.053139 :95186042:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11800 pso_num=24 pso_serial#=21 2025-05-10 05:57:14.053331 :95186043:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11800 osp_idx=22 osp_ver=254000 osp_pg=0 (spawn #166787) 2025-05-10 05:57:14.053332 :95186044:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 05:57:14.053333 :95186045:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=74 time=4127411218 2025-05-10 05:57:14.053333 :95186046:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 05:57:14.053333 :95186047:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 05:57:14.053334 :95186048:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 05:57:14.053334 :95186049:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 05:57:14.053335 :9518604A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 05:57:14.053845 :9518604B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 05:57:14.054508 :95186060:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:57:14.061994 :9518607C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-10 05:57:14.062375 :9518607D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:57:14.062417 :95186080:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-10 05:57:14.063298 :95186086:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:57:14.063418 :9518608F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:57:44.185128 :95186AB9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:57:48.588812 :95186BE7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:57:48.580 2025-05-10 05:57:48.588814 :95186BE8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11212 pso_num=24 pso_serial#=22 2025-05-10 05:57:48.588955 :95186BE9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11212 (legacy spawn) 2025-05-10 05:57:48.595399 :95186BEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:57:48.595571 :95186BF1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:57:48.596392 :95186BF4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:57:53.973280 :95186D7E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:57:53.960 2025-05-10 05:57:53.973282 :95186D7F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8604 pso_num=24 pso_serial#=23 2025-05-10 05:57:53.973421 :95186D80:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8604 (legacy spawn) 2025-05-10 05:57:53.979830 :95186D81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:57:53.980009 :95186D86:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:57:53.980804 :95186D8B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:58:14.143615 :951873E3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:58:14.138 2025-05-10 05:58:14.143617 :951873E4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8392 pso_num=24 pso_serial#=24 2025-05-10 05:58:14.143755 :951873E5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8392 osp_idx=22 osp_ver=254003 osp_pg=0 (spawn #166789) 2025-05-10 05:58:14.143756 :951873E6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 05:58:14.143756 :951873E7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=75 time=4127471312 2025-05-10 05:58:14.143757 :951873E8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 05:58:14.143757 :951873E9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 05:58:14.143757 :951873EA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 05:58:14.143759 :951873EB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 05:58:14.143760 :951873EC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 05:58:14.144265 :951873ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 05:58:14.144910 :95187402:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:58:14.155911 :9518741E:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-10 05:58:14.156377 :9518741F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-10 05:58:14.158683 :95187420:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-10 05:58:14.159050 :95187421:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:58:14.159102 :95187424:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-10 05:58:14.159984 :9518742A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:58:14.160113 :95187433:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:58:44.275107 :95187DD0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:58:50.781801 :95187FBF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:58:50.774 2025-05-10 05:58:50.781803 :95187FC0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11780 pso_num=24 pso_serial#=25 2025-05-10 05:58:50.781943 :95187FC1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11780 (legacy spawn) 2025-05-10 05:58:50.788427 :95187FC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:58:50.788598 :95187FC7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:58:50.789551 :95187FCC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:58:52.079496 :95188066:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:58:52.065 2025-05-10 05:58:52.079498 :95188067:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11544 pso_num=24 pso_serial#=26 2025-05-10 05:58:52.079640 :95188068:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11544 (legacy spawn) 2025-05-10 05:58:52.086051 :95188069:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:58:52.086212 :95188072:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:58:52.087007 :95188073:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:58:52.103658 :95188076:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:58:52.096 2025-05-10 05:58:52.103660 :95188077:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13244 pso_num=24 pso_serial#=27 2025-05-10 05:58:52.103789 :95188078:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13244 (legacy spawn) 2025-05-10 05:58:52.110254 :95188079:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:58:52.110419 :95188082:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:58:52.111226 :95188083:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:58:55.233293 :95188176:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:58:55.231 2025-05-10 05:58:55.233295 :95188177:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1820 pso_num=24 pso_serial#=28 2025-05-10 05:58:55.233440 :95188178:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=1820 (legacy spawn) 2025-05-10 05:58:55.239874 :95188179:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:58:55.240041 :95188182:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:58:55.240838 :95188183:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:58:55.249497 :95188186:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:58:55.247 2025-05-10 05:58:55.249499 :95188187:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12228 pso_num=24 pso_serial#=29 2025-05-10 05:58:55.249631 :95188188:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12228 (legacy spawn) 2025-05-10 05:58:55.255973 :95188189:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:58:55.256140 :95188192:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:58:55.256933 :95188193:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:59:02.190091 :9518836C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:59:02.180 2025-05-10 05:59:02.190093 :9518836D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11376 pso_num=24 pso_serial#=30 2025-05-10 05:59:02.190230 :9518836E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11376 (legacy spawn) 2025-05-10 05:59:02.196599 :9518836F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:59:02.196885 :95188374:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:59:02.197705 :95188379:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:59:04.215497 :9518844C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:59:04.203 2025-05-10 05:59:04.215500 :9518844D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11488 pso_num=24 pso_serial#=31 2025-05-10 05:59:04.215633 :9518844E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11488 (legacy spawn) 2025-05-10 05:59:04.222139 :9518844F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:59:04.222307 :95188454:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:59:04.223147 :95188459:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:59:04.231854 :9518845C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:59:04.219 2025-05-10 05:59:04.231856 :9518845D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10388 pso_num=24 pso_serial#=32 2025-05-10 05:59:04.231989 :9518845E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10388 (legacy spawn) 2025-05-10 05:59:04.238468 :95188478:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:59:04.238647 :95188481:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:59:04.239440 :95188482:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 05:59:09.244123 :951885CC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 05:59:09.238 2025-05-10 05:59:09.244126 :951885CD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12300 pso_num=24 pso_serial#=33 2025-05-10 05:59:09.244262 :951885CE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12300 osp_idx=22 osp_ver=254012 osp_pg=0 (spawn #166791) 2025-05-10 05:59:09.244263 :951885CF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 05:59:09.244264 :951885D0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=76 time=4127526406 2025-05-10 05:59:09.244264 :951885D1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 05:59:09.244265 :951885D2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 05:59:09.244265 :951885D3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 05:59:09.244265 :951885D4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 05:59:09.244266 :951885D5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 05:59:09.244756 :951885D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 05:59:09.245372 :951885EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:59:09.250885 :95188607:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:59:09.251875 :9518860F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:59:09.252007 :95188618:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:59:14.255147 :951887C3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:59:14.255165*:951887C6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002494BB58 estart=1746827954 eid=16853016 ctx=0x000000077BD98A08 cwh=0x00000000232802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-10 05:59:14.255165*:951887C7:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002494BB58 curCtx=0x000000002328F300 pubTabIdxCnt=0 2025-05-10 05:59:14.255165*:951887C8:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002494BB58 2025-05-10 05:59:14.255165*:951887C9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002494BB58 2025-05-10 05:59:14.255165*:951887CA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002494BB58 curCtx=0x000000002328F300 xsc->flg4=65792 flags=2 2025-05-10 05:59:14.255165*:951887CB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002494BB58 2025-05-10 05:59:14.255691 :951887CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:59:14.262963 :951887D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-10 05:59:14.263328 :951887D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:59:14.263370 :951887D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-10 05:59:14.264265 :951887DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 05:59:14.264391 :951887E7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 05:59:44.363363 :951891F1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-10 06:00:00.017832 :951896B3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-10 06:00:00.011 2025-05-10 06:00:00.017835 :951896B4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12672 pso_num=24 pso_serial#=34 2025-05-10 06:00:00.017951 :951896B5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12672 osp_idx=22 osp_ver=254013 osp_pg=0 (spawn #166794) 2025-05-10 06:00:00.017952 :951896B6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 06:00:00.017952 :951896B7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=77 time=4127577187 2025-05-10 06:00:00.017953 :951896B8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 06:00:00.017953 :951896B9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 06:00:00.017953 :951896BA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 06:00:00.017954 :951896BB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 06:00:00.017954 :951896BC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 06:00:00.018032 :951896BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 06:00:00.018039 :951896BE:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:24:0] kso: spawn diagnostics: init time=0 secs ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 12672, VKRM)