Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250824081207\orcl_j000_22464_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:35248M/63366M, Ph+PgF:40377M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 22464, image: ORACLE.EXE (J000) *** 2025-08-24 08:12:07.626 *** SESSION ID:(193.34139) 2025-08-24 08:12:07.626 *** 2025-08-24 08:12:07.626 Process diagnostic dump for ORACLE.EXE (J000), OS id=22464, pid: 24, proc_ser: 188, sid: 193, sess_ser: 34139 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22464 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=0.284760 sec, exc=0.284760 sec, total=0.284760 sec wait times: max=0.500000 sec, heur=27.859760 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=0.499975 sec, exc=0.499975 sec, total=0.499975 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=0.510120 sec, exc=0.510120 sec, total=0.510120 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.510093 sec, exc=0.510093 sec, total=0.510093 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time Sampled Session History of session 193 serial 34139 --------------------------------------------------- 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 --------------------------------------------------- [28 samples, 08:11:39 - 08:12:07] idle wait at each sample [session created at: 08:11:39] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-24 08:12:07.626 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 22464, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-24 08:08:17.833291 :C3EDFDFA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7572 (legacy spawn) 2025-08-24 08:08:17.839912 :C3EDFDFB: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-24 08:08:17.840083 :C3EDFE03: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-24 08:08:17.840915 :C3EDFE05:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:08:21.498308 :C3EDFF76:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:08:21.493 2025-08-24 08:08:21.498311 :C3EDFF77:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13244 pso_num=24 pso_serial#=171 2025-08-24 08:08:21.498487 :C3EDFF78:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13244 osp_idx=22 osp_ver=779461 osp_pg=0 (spawn #520564) 2025-08-24 08:08:21.498487 :C3EDFF79: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-24 08:08:21.498488 :C3EDFF7A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=167 time=408603502 2025-08-24 08:08:21.498489 :C3EDFF7B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:08:21.498490 :C3EDFF7C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:08:21.498490 :C3EDFF7D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:08:21.498490 :C3EDFF7E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:08:21.498491 :C3EDFF7F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:08:21.498990 :C3EDFF80: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-24 08:08:21.499040 :C3EDFF86: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-24 08:08:21.519103 :C3EDFFB0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:08:39.440261 :C3EE0587:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:08:39.435 2025-08-24 08:08:39.440263 :C3EE0588:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21740 pso_num=24 pso_serial#=172 2025-08-24 08:08:39.440421 :C3EE0589:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21740 osp_idx=22 osp_ver=779462 osp_pg=0 (spawn #520565) 2025-08-24 08:08:39.440422 :C3EE058A: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-24 08:08:39.440422 :C3EE058B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=168 time=408621455 2025-08-24 08:08:39.440422 :C3EE058C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:08:39.440423 :C3EE058D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:08:39.440423 :C3EE058E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:08:39.440423 :C3EE058F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:08:39.440424 :C3EE0590:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:08:39.440960 :C3EE0591: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-24 08:08:39.441644 :C3EE05A6: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-24 08:08:39.452913 :C3EE05C2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-24 08:08:39.453428 :C3EE05C3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-24 08:08:39.455815 :C3EE05C4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 08:08:39.456177 :C3EE05C5: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-24 08:08:39.456214 :C3EE05C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 08:08:39.457159 :C3EE05CE: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-24 08:08:39.457323 :C3EE05D6: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-24 08:09:09.524016 :C3EE0FAC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:09:19.969300 :C3EE12F6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:09:19.960 2025-08-24 08:09:19.969302 :C3EE12F7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19796 pso_num=24 pso_serial#=173 2025-08-24 08:09:19.969438 :C3EE12F8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19796 (legacy spawn) 2025-08-24 08:09:19.975992 :C3EE12F9: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-24 08:09:19.976159 :C3EE12FE: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-24 08:09:19.977070 :C3EE1303:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:09:25.281330 :C3EE14E1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:09:25.273 2025-08-24 08:09:25.281332 :C3EE14E2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21948 pso_num=24 pso_serial#=174 2025-08-24 08:09:25.281472 :C3EE14E3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21948 (legacy spawn) 2025-08-24 08:09:25.287936 :C3EE14E4: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-24 08:09:25.288114 :C3EE14E9: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-24 08:09:25.288907 :C3EE14EE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:09:25.297633 :C3EE14F1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:09:25.289 2025-08-24 08:09:25.297636 :C3EE14F2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19728 pso_num=24 pso_serial#=175 2025-08-24 08:09:25.297827 :C3EE14F3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19728 (legacy spawn) 2025-08-24 08:09:25.304251 :C3EE14F4: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-24 08:09:25.304408 :C3EE14F9: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-24 08:09:25.305264 :C3EE14FE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:09:25.469196 :C3EE150C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:09:25.461 2025-08-24 08:09:25.469198 :C3EE150D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21996 pso_num=24 pso_serial#=176 2025-08-24 08:09:25.469341 :C3EE150E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21996 (legacy spawn) 2025-08-24 08:09:25.475929 :C3EE150F: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-24 08:09:25.476098 :C3EE1518: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-24 08:09:25.476931 :C3EE1519:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:09:28.474829 :C3EE1601:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:09:28.461 2025-08-24 08:09:28.474831 :C3EE1602:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17200 pso_num=24 pso_serial#=177 2025-08-24 08:09:28.474968 :C3EE1603:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17200 (legacy spawn) 2025-08-24 08:09:28.481434 :C3EE1604: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-24 08:09:28.481612 :C3EE1609: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-24 08:09:28.482401 :C3EE160E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:09:28.499964 :C3EE1611:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:09:28.492 2025-08-24 08:09:28.499966 :C3EE1612:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21524 pso_num=24 pso_serial#=178 2025-08-24 08:09:28.500106 :C3EE1613:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21524 (legacy spawn) 2025-08-24 08:09:28.506566 :C3EE1614: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-24 08:09:28.506741 :C3EE161D: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-24 08:09:28.507542 :C3EE161E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:09:39.525072 :C3EE1983:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:09:39.519 2025-08-24 08:09:39.525074 :C3EE1984:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16984 pso_num=24 pso_serial#=179 2025-08-24 08:09:39.525211 :C3EE1985:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16984 osp_idx=22 osp_ver=779469 osp_pg=0 (spawn #520567) 2025-08-24 08:09:39.525212 :C3EE1986: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-24 08:09:39.525212 :C3EE1987:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=169 time=408681533 2025-08-24 08:09:39.525213 :C3EE1988:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:09:39.525213 :C3EE1989:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:09:39.525213 :C3EE198A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:09:39.525214 :C3EE198B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:09:39.525214 :C3EE198C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:09:39.525689 :C3EE198D: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-24 08:09:39.526386 :C3EE19A2: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-24 08:09:39.533856 :C3EE19BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 08:09:39.534205 :C3EE19BF: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-24 08:09:39.534243 :C3EE19C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 08:09:39.535213 :C3EE19D3: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-24 08:09:39.535343 :C3EE19DC: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-24 08:09:54.548959 :C3EE1EE0: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-24 08:09:54.548982*:C3EE1EE5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D4BB58 estart=1755994194 eid=17021338 ctx=0x000000077BD98A08 cwh=0x00000000232802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-24 08:09:54.548982*:C3EE1EE6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D4BB58 curCtx=0x000000002328F300 pubTabIdxCnt=0 2025-08-24 08:09:54.548982*:C3EE1EE7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D4BB58 2025-08-24 08:09:54.548982*:C3EE1EE8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D4BB58 2025-08-24 08:09:54.548982*:C3EE1EE9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D4BB58 curCtx=0x000000002328F300 xsc->flg4=65792 flags=2 2025-08-24 08:09:54.548982*:C3EE1EEA:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D4BB58 2025-08-24 08:09:54.549526 :C3EE1EEB: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-24 08:09:54.554852 :C3EE1EF3: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-24 08:09:54.555679 :C3EE1EFB: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-24 08:09:54.555807 :C3EE1F04: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-24 08:10:24.630739 :C3EE28F1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:10:33.757449 :C3EE2BC7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:10:33.749 2025-08-24 08:10:33.757451 :C3EE2BC8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20744 pso_num=24 pso_serial#=180 2025-08-24 08:10:33.757605 :C3EE2BC9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20744 (legacy spawn) 2025-08-24 08:10:33.764604 :C3EE2BE0: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-24 08:10:33.764150 :C3EE2BF7: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-24 08:10:33.765184 :C3EE2C02:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:10:37.887152 :C3EE2D39:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:10:37.872 2025-08-24 08:10:37.887155 :C3EE2D3A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21124 pso_num=24 pso_serial#=181 2025-08-24 08:10:37.887290 :C3EE2D3B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21124 (legacy spawn) 2025-08-24 08:10:37.893742 :C3EE2D3C: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-24 08:10:37.893912 :C3EE2D41: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-24 08:10:37.894687 :C3EE2D46:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:10:37.903269 :C3EE2D49:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:10:37.888 2025-08-24 08:10:37.903271 :C3EE2D4A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16236 pso_num=24 pso_serial#=182 2025-08-24 08:10:37.903413 :C3EE2D4B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16236 (legacy spawn) 2025-08-24 08:10:37.909905 :C3EE2D4C: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-24 08:10:37.910077 :C3EE2D55: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-24 08:10:37.910861 :C3EE2D56:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:10:39.641135 :C3EE2DD8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:10:39.635 2025-08-24 08:10:39.641137 :C3EE2DD9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20116 pso_num=24 pso_serial#=183 2025-08-24 08:10:39.641279 :C3EE2DDA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20116 osp_idx=22 osp_ver=779473 osp_pg=0 (spawn #520569) 2025-08-24 08:10:39.641280 :C3EE2DDB: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-24 08:10:39.641281 :C3EE2DDC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=170 time=408741643 2025-08-24 08:10:39.641282 :C3EE2DDD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:10:39.641282 :C3EE2DDE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:10:39.641283 :C3EE2DDF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:10:39.641283 :C3EE2DE0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:10:39.641283 :C3EE2DE1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:10:39.641795 :C3EE2DE2: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-24 08:10:39.642408 :C3EE2DF7: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-24 08:10:39.653516 :C3EE2E13:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-24 08:10:39.653974 :C3EE2E14:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-24 08:10:39.656298 :C3EE2E15:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 08:10:39.656634 :C3EE2E16: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-24 08:10:39.656673 :C3EE2E19:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 08:10:39.657549 :C3EE2E1F: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-24 08:10:39.657680 :C3EE2E28: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-24 08:11:09.764659 :C3EE37F8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:11:14.178487 :C3EE3959:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:11:14.164 2025-08-24 08:11:14.178489 :C3EE395A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21896 pso_num=24 pso_serial#=184 2025-08-24 08:11:14.178623 :C3EE395B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21896 (legacy spawn) 2025-08-24 08:11:14.185186 :C3EE395C: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-24 08:11:14.185364 :C3EE3961: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-24 08:11:14.186211 :C3EE3966:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:11:14.194966 :C3EE3969:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:11:14.195 2025-08-24 08:11:14.194969 :C3EE396A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22064 pso_num=24 pso_serial#=185 2025-08-24 08:11:14.195112 :C3EE396B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22064 (legacy spawn) 2025-08-24 08:11:14.201599 :C3EE396C: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-24 08:11:14.201767 :C3EE3975: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-24 08:11:14.202561 :C3EE3976:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:11:17.421116 :C3EE3A6F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:11:17.408 2025-08-24 08:11:17.421118 :C3EE3A70:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20088 pso_num=24 pso_serial#=186 2025-08-24 08:11:17.421259 :C3EE3A71:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20088 (legacy spawn) 2025-08-24 08:11:17.427774 :C3EE3A72: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-24 08:11:17.427955 :C3EE3A77: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-24 08:11:17.428749 :C3EE3A7C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:11:17.437437 :C3EE3A7F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:11:17.424 2025-08-24 08:11:17.437439 :C3EE3A80:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19732 pso_num=24 pso_serial#=187 2025-08-24 08:11:17.437571 :C3EE3A81:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19732 (legacy spawn) 2025-08-24 08:11:17.444118 :C3EE3A82: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-24 08:11:17.444277 :C3EE3A87: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-24 08:11:17.445100 :C3EE3A8C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 08:11:39.758115 :C3EE41C9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 08:11:39.752 2025-08-24 08:11:39.758118 :C3EE41CA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22464 pso_num=24 pso_serial#=188 2025-08-24 08:11:39.758261 :C3EE41CB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22464 osp_idx=22 osp_ver=779478 osp_pg=0 (spawn #520571) 2025-08-24 08:11:39.758262 :C3EE41CC: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-24 08:11:39.758262 :C3EE41CD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=171 time=408801768 2025-08-24 08:11:39.758262 :C3EE41CE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:11:39.758263 :C3EE41CF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:11:39.758263 :C3EE41D0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:11:39.758263 :C3EE41D1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:11:39.758264 :C3EE41D2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:11:39.758764 :C3EE41D3: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-24 08:11:39.759407 :C3EE41E8: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-24 08:11:39.766993 :C3EE4205:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 08:11:39.767334 :C3EE4206: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-24 08:11:39.767370 :C3EE4209:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 08:11:39.768246 :C3EE420F: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-24 08:11:39.768376 :C3EE4218: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: 22464, J000)