Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250813220004\orcl_j000_1216_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:35512M/63366M, Ph+PgF:40874M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 1216, image: ORACLE.EXE (J000) *** 2025-08-13 22:00:04.516 *** SESSION ID:(193.32749) 2025-08-13 22:00:04.516 *** 2025-08-13 22:00:04.516 Process diagnostic dump for ORACLE.EXE (J000), OS id=1216, pid: 24, proc_ser: 248, sid: 193, sess_ser: 32749 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 1216 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.015571 sec, exc=0.015571 sec, total=0.015571 sec wait times: max=0.500000 sec, heur=0.501569 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000006 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.485992 sec, exc=0.485992 sec, total=0.485992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 32749 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:00:04] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-08-13 22:00:04.532 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 1216, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-13 21:58:35.407576 :BF581E86: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-08-13 21:58:35.407754 :BF581E8B: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-08-13 21:58:35.408569 :BF581E90:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:58:36.469993 :BF581EC2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:58:36.455 2025-08-13 21:58:36.469995 :BF581EC3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20492 pso_num=24 pso_serial#=236 2025-08-13 21:58:36.470135 :BF581EC4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20492 (legacy spawn) 2025-08-13 21:58:36.476549 :BF581EC5: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-08-13 21:58:36.476722 :BF581ECA: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-08-13 21:58:36.477509 :BF581ECF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:58:36.494036 :BF581ED2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:58:36.487 2025-08-13 21:58:36.494038 :BF581ED3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19052 pso_num=24 pso_serial#=237 2025-08-13 21:58:36.494175 :BF581ED4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19052 (legacy spawn) 2025-08-13 21:58:36.500644 :BF581ED5: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-08-13 21:58:36.500814 :BF581EDE: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-08-13 21:58:36.501659 :BF581EDF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:58:39.600582 :BF581FCC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:58:39.588 2025-08-13 21:58:39.600584 :BF581FCD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20792 pso_num=24 pso_serial#=238 2025-08-13 21:58:39.600721 :BF581FCE:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20792 (legacy spawn) 2025-08-13 21:58:39.607206 :BF581FCF: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-08-13 21:58:39.607388 :BF581FD4: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-08-13 21:58:39.608184 :BF581FD9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:58:39.616832 :BF581FDC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:58:39.603 2025-08-13 21:58:39.616835 :BF581FDD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19636 pso_num=24 pso_serial#=239 2025-08-13 21:58:39.616968 :BF581FDE:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19636 (legacy spawn) 2025-08-13 21:58:39.623705 :BF581FDF: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-08-13 21:58:39.623885 :BF581FE8: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-08-13 21:58:39.624862 :BF581FE9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:58:40.280775 :BF58205E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:58:40.275 2025-08-13 21:58:40.280777 :BF58205F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7196 pso_num=24 pso_serial#=240 2025-08-13 21:58:40.280916 :BF582060:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7196 osp_idx=22 osp_ver=722665 osp_pg=0 (spawn #485803) 2025-08-13 21:58:40.280917 :BF582061:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-13 21:58:40.280917 :BF582062:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=44 time=3802989579 2025-08-13 21:58:40.280918 :BF582063:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-13 21:58:40.280918 :BF582064:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-13 21:58:40.280919 :BF582065:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-13 21:58:40.280919 :BF582066:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-13 21:58:40.280919 :BF582067:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-13 21:58:40.281442 :BF582068: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-08-13 21:58:40.282123 :BF58207D: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-08-13 21:58:40.296506 :BF582099:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-13 21:58:40.296592 :BF58209A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-13 21:58:40.297405 :BF58209B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-13 21:58:40.297887 :BF58209C: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-08-13 21:58:40.297928 :BF58209F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-13 21:58:40.306104 :BF5820A5: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-08-13 21:58:40.306215 :BF5820A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-13 21:58:40.306279 :BF5820A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-13 21:58:40.306692 :BF5820AF:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-13 21:58:40.307058 :BF5820B0:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-13 21:58:40.307258 :BF5820B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-13 21:58:40.307510 :BF5820B2: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-08-13 21:58:40.307537 :BF5820B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-13 21:58:40.309179 :BF5820BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-13 21:58:40.309421 :BF5820BC: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-08-13 21:58:40.309452 :BF5820BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-13 21:58:40.310458 :BF5820C5: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-08-13 21:58:40.310588 :BF5820CE: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-08-13 21:59:10.398649 :BF582A24:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:59:12.124298 :BF582AA2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:59:12.119 2025-08-13 21:59:12.124300 :BF582AA3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17460 pso_num=24 pso_serial#=241 2025-08-13 21:59:12.124438 :BF582AA4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17460 osp_idx=22 osp_ver=722666 osp_pg=0 (spawn #485805) 2025-08-13 21:59:12.124439 :BF582AA5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-13 21:59:12.124439 :BF582AA6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=45 time=3803021423 2025-08-13 21:59:12.124440 :BF582AA7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-13 21:59:12.124440 :BF582AA8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-13 21:59:12.124440 :BF582AA9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-13 21:59:12.124441 :BF582AAA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-13 21:59:12.124441 :BF582AAB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-13 21:59:12.124950 :BF582AAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-13 21:59:12.124993 :BF582AB2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-13 21:59:12.129109 :BF582AC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-13 21:59:12.129152 :BF582AC2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-13 21:59:12.129412 :BF582AC3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-13 21:59:12.129429 :BF582AC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-13 21:59:12.129651 :BF582AC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-13 21:59:12.129664 :BF582AC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-13 21:59:12.129871 :BF582AC7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-13 21:59:12.129883 :BF582AC8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-13 21:59:12.130091 :BF582AC9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-13 21:59:12.130104 :BF582ACA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-13 21:59:12.130311 :BF582ACB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-13 21:59:12.130323 :BF582ACC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-13 21:59:12.132833 :BF582ACD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:59:12.689787 :BF582ADC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:59:12.687 2025-08-13 21:59:12.689789 :BF582ADD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19376 pso_num=24 pso_serial#=242 2025-08-13 21:59:12.689936 :BF582ADE:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19376 (legacy spawn) 2025-08-13 21:59:12.696830 :BF582ADF: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-08-13 21:59:12.697002 :BF582AE8: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-08-13 21:59:12.697968 :BF582AE9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:59:12.726335 :BF582AEC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:59:12.718 2025-08-13 21:59:12.726337 :BF582AED:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4492 pso_num=24 pso_serial#=243 2025-08-13 21:59:12.726478 :BF582AEE:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=4492 (legacy spawn) 2025-08-13 21:59:12.732988 :BF582AEF: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-08-13 21:59:12.733159 :BF582AF8: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-08-13 21:59:12.733986 :BF582AF9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:59:15.867988 :BF582BF0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:59:15.853 2025-08-13 21:59:15.867990 :BF582BF1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20988 pso_num=24 pso_serial#=244 2025-08-13 21:59:15.868137 :BF582BF2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20988 (legacy spawn) 2025-08-13 21:59:15.874988 :BF582BF3: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-08-13 21:59:15.875166 :BF582BF8: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-08-13 21:59:15.876120 :BF582BFD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:59:15.885579 :BF582C00:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:59:15.885 2025-08-13 21:59:15.885582 :BF582C01:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21020 pso_num=24 pso_serial#=245 2025-08-13 21:59:15.885736 :BF582C02:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21020 (legacy spawn) 2025-08-13 21:59:15.892659 :BF582C03: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-08-13 21:59:15.892846 :BF582C0B: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-08-13 21:59:15.893704 :BF582C0C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:59:31.189305 :BF5830A8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:59:31.180 2025-08-13 21:59:31.189307 :BF5830A9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20404 pso_num=24 pso_serial#=246 2025-08-13 21:59:31.189446 :BF5830AA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20404 (legacy spawn) 2025-08-13 21:59:31.195945 :BF5830AB: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-08-13 21:59:31.196123 :BF5830B0: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-08-13 21:59:31.212166 :BF5830B5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:59:37.591765 :BF58330F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:59:37.582 2025-08-13 21:59:37.591768 :BF583310:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19812 pso_num=24 pso_serial#=247 2025-08-13 21:59:37.591922 :BF583311:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19812 (legacy spawn) 2025-08-13 21:59:37.598779 :BF583312: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-08-13 21:59:37.598970 :BF583317: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-08-13 21:59:37.599904 :BF58331C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-13 21:59:40.369181 :BF5833E0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-13 21:59:40.363 2025-08-13 21:59:40.369183 :BF5833E1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1216 pso_num=24 pso_serial#=248 2025-08-13 21:59:40.369345 :BF5833E2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=1216 osp_idx=22 osp_ver=722673 osp_pg=0 (spawn #485806) 2025-08-13 21:59:40.369345 :BF5833E3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-13 21:59:40.369346 :BF5833E4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=46 time=3803049673 2025-08-13 21:59:40.369346 :BF5833E5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-13 21:59:40.369347 :BF5833E6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-13 21:59:40.369347 :BF5833E7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-13 21:59:40.369347 :BF5833E8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-13 21:59:40.369348 :BF5833E9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-13 21:59:40.369847 :BF5833EA: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-08-13 21:59:40.370501 :BF5833FF: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-08-13 21:59:40.378034 :BF58341B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-13 21:59:40.378402 :BF58341C: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-08-13 21:59:40.378439 :BF58341F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-13 21:59:40.379302 :BF583425: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-08-13 21:59:40.379432 :BF58342E: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-08-13 22:00:00.418778 :BF583BDC: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-08-13 22:00:00.418778*:BF583BED:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1755093599 eid=16786025 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-13 22:00:00.418778*:BF583BEE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-08-13 22:00:00.418778*:BF583BEF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-08-13 22:00:00.418778*:BF583BF0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-08-13 22:00:00.418778*:BF583BF1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-08-13 22:00:00.418778*:BF583BF2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-08-13 22:00:00.424516 :BF583BFD: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-08-13 22:00:00.424566 :BF583C00:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-13 22:00:00.436545 :BF583D8C: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-08-13 22:00:00.441831 :BF583DCD: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-08-13 22:00:00.442602 :BF583DE3: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-08-13 22:00:00.448477 :BF583E4A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-13 22:00:00.448636 :BF583E61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-13 22:00:00.449333 :BF583E89: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-08-13 22:00:00.449539 :BF583EA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-13 22:00:00.450195 :BF583EBF: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-08-13 22:00:00.451499 :BF583F11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] 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-08-13 22:00:00.451541 :BF583F13: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-08-13 22:00:00.451574 :BF583F1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-13 22:00:00.451635 :BF583F27: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-08-13 22:00:00.543574 :BF583F5B: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-08-13 22:00:04.017381 :BF5843E2: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-08-13 22:00:04.017381*:BF5843E5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1755093604 eid=16786031 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-13 22:00:04.017381*:BF5843E6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-08-13 22:00:04.017381*:BF5843E7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-08-13 22:00:04.017381*:BF5843E8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-08-13 22:00:04.017381*:BF5843E9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-08-13 22:00:04.017381*:BF5843EA:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-08-13 22:00:04.019533 :BF5843EB: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-08-13 22:00:04.019574 :BF5843EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-13 22:00:04.026250 :BF5843F4: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-08-13 22:00:04.026982 :BF5843FC: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-08-13 22:00:04.027707 :BF584404: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-08-13 22:00:04.027938 :BF58440C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-13 22:00:04.029358 :BF58440D: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-08-13 22:00:04.029405 :BF584410:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-13 22:00:04.029505 :BF58441D: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 1216, J000)