Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250906222049\orcl_j000_23632_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:35016M/63366M, Ph+PgF:39836M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 23632, image: ORACLE.EXE (J000) *** 2025-09-06 22:20:49.979 *** SESSION ID:(201.40121) 2025-09-06 22:20:49.979 *** 2025-09-06 22:20:49.979 Process diagnostic dump for ORACLE.EXE (J000), OS id=23632, pid: 25, proc_ser: 139, sid: 201, sess_ser: 40121 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23632 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.317436 sec, exc=0.317436 sec, total=0.317436 sec wait times: max=0.500000 sec, heur=17.358061 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.502015 sec, exc=0.502015 sec, total=0.502015 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.499984 sec, exc=0.499984 sec, total=0.499984 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.500184 sec, exc=0.500184 sec, total=0.500184 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=29 seq_num=30 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=27 seq_num=28 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 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.500161 sec, exc=0.500161 sec, total=0.500161 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 201 serial 40121 --------------------------------------------------- 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 --------------------------------------------------- [18 samples, 22:20:32 - 22:20:49] idle wait at each sample [session created at: 22:20:32] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-06 22:20:49.979 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 23632, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-06 22:17:07.434274 :C9F0081C: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-09-06 22:17:07.435090 :C9F0081D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:17:10.586081 :C9F008FD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:17:10.572 2025-09-06 22:17:10.586083 :C9F008FE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24340 pso_num=25 pso_serial#=120 2025-09-06 22:17:10.586220 :C9F008FF:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24340 (legacy spawn) 2025-09-06 22:17:10.592655 :C9F00900: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-09-06 22:17:10.592819 :C9F00909: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-09-06 22:17:10.593603 :C9F0090A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:17:10.602207 :C9F0090D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:17:10.588 2025-09-06 22:17:10.602208 :C9F0090E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20628 pso_num=25 pso_serial#=121 2025-09-06 22:17:10.602340 :C9F0090F:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20628 (legacy spawn) 2025-09-06 22:17:10.608773 :C9F00910: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-09-06 22:17:10.608942 :C9F00919: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-09-06 22:17:10.609749 :C9F0091A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:17:12.528156 :C9F009AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:17:12.520 2025-09-06 22:17:12.528158 :C9F009AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24364 pso_num=25 pso_serial#=122 2025-09-06 22:17:12.528297 :C9F009AD:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24364 (legacy spawn) 2025-09-06 22:17:12.534790 :C9F009AE: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-09-06 22:17:12.534964 :C9F009B6: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-09-06 22:17:12.535816 :C9F009B7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:17:19.477288 :C9F00C0D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:17:19.474 2025-09-06 22:17:19.477290 :C9F00C0E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20732 pso_num=25 pso_serial#=123 2025-09-06 22:17:19.477545 :C9F00C0F:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20732 (legacy spawn) 2025-09-06 22:17:19.484624 :C9F00C10: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-09-06 22:17:19.484828 :C9F00C19: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-09-06 22:17:19.485739 :C9F00C1A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:17:24.090359 :C9F00D3C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:17:24.077 2025-09-06 22:17:24.090361 :C9F00D3D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22300 pso_num=25 pso_serial#=124 2025-09-06 22:17:24.090513 :C9F00D3E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22300 (legacy spawn) 2025-09-06 22:17:24.097151 :C9F00D3F: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-09-06 22:17:24.097343 :C9F00D44: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-09-06 22:17:24.098163 :C9F00D49:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:17:24.107042 :C9F00D4C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:17:24.092 2025-09-06 22:17:24.107045 :C9F00D4D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23116 pso_num=25 pso_serial#=125 2025-09-06 22:17:24.107189 :C9F00D4E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23116 (legacy spawn) 2025-09-06 22:17:24.113773 :C9F00D4F: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-09-06 22:17:24.113941 :C9F00D58: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-09-06 22:17:24.114766 :C9F00D59:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:17:32.326259 :C9F01024:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:17:32.320 2025-09-06 22:17:32.326261 :C9F01025:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22720 pso_num=25 pso_serial#=126 2025-09-06 22:17:32.326402 :C9F01026:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22720 osp_idx=23 osp_ver=284719 osp_pg=0 (spawn #565873) 2025-09-06 22:17:32.326403 :C9F01027:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 22:17:32.326403 :C9F01028:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=186 time=1582703627 2025-09-06 22:17:32.326403 :C9F01029:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 22:17:32.326404 :C9F0102A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 22:17:32.326404 :C9F0102B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 22:17:32.326405 :C9F0102C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 22:17:32.326405 :C9F0102D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 22:17:32.326893 :C9F0102E: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-09-06 22:17:32.327522 :C9F01043: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-09-06 22:17:32.335050 :C9F0105F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-06 22:17:32.335409 :C9F01060: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-09-06 22:17:32.335446 :C9F01063:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-06 22:17:32.336270 :C9F01069: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-09-06 22:17:32.336383 :C9F01072: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-09-06 22:18:02.460788 :C9F01A8C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:18:14.709807 :C9F01E26:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:18:14.701 2025-09-06 22:18:14.709809 :C9F01E27:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19808 pso_num=25 pso_serial#=127 2025-09-06 22:18:14.709947 :C9F01E28:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19808 (legacy spawn) 2025-09-06 22:18:14.716467 :C9F01E29: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-09-06 22:18:14.716638 :C9F01E2E: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-09-06 22:18:14.717468 :C9F01E33:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:18:17.420451 :C9F01F2C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:18:17.414 2025-09-06 22:18:17.420453 :C9F01F2D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24316 pso_num=25 pso_serial#=128 2025-09-06 22:18:17.420592 :C9F01F2E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24316 osp_idx=23 osp_ver=284721 osp_pg=0 (spawn #565875) 2025-09-06 22:18:17.420593 :C9F01F2F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 22:18:17.420593 :C9F01F30:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=187 time=1582748721 2025-09-06 22:18:17.420594 :C9F01F31:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 22:18:17.420594 :C9F01F32:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 22:18:17.420595 :C9F01F33:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 22:18:17.420595 :C9F01F34:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 22:18:17.420595 :C9F01F35:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 22:18:17.421106 :C9F01F36: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-09-06 22:18:17.421766 :C9F01F4B: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-09-06 22:18:17.427393 :C9F01F67: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-09-06 22:18:17.428397 :C9F01F6F: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-09-06 22:18:17.428523 :C9F01F78: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-09-06 22:18:32.451205 :C9F0245B: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-09-06 22:18:32.451227*:C9F0245E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1757168311 eid=17042899 ctx=0x000000077BD98A08 cwh=0x0000000026B902A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-06 22:18:32.451227*:C9F0245F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F300 pubTabIdxCnt=0 2025-09-06 22:18:32.451227*:C9F02460:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-09-06 22:18:32.451227*:C9F02461:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-09-06 22:18:32.451227*:C9F02462:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F300 xsc->flg4=65792 flags=2 2025-09-06 22:18:32.451227*:C9F02463:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-09-06 22:18:32.451780 :C9F02464: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-09-06 22:18:32.462645 :C9F0246C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 22:18:32.463121 :C9F0246D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 22:18:32.465421 :C9F0246E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-06 22:18:32.465793 :C9F0246F: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-09-06 22:18:32.465831 :C9F02472:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-06 22:18:32.466858 :C9F02478: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-09-06 22:18:32.466994 :C9F02481: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-09-06 22:19:02.532087 :C9F02DED:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:19:16.847567 :C9F0322A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:19:16.834 2025-09-06 22:19:16.847569 :C9F0322B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23200 pso_num=25 pso_serial#=129 2025-09-06 22:19:16.847706 :C9F0322C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23200 (legacy spawn) 2025-09-06 22:19:16.854153 :C9F0322D: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-09-06 22:19:16.854341 :C9F03232: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-09-06 22:19:16.855153 :C9F03237:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:19:32.501406 :C9F036CF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:19:32.493 2025-09-06 22:19:32.501408 :C9F036D0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23232 pso_num=25 pso_serial#=130 2025-09-06 22:19:32.501550 :C9F036D1:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23232 (legacy spawn) 2025-09-06 22:19:32.508061 :C9F036D2: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-09-06 22:19:32.508092 :C9F036D5: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-09-06 22:19:32.508251 :C9F036D8: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-09-06 22:19:32.509085 :C9F036E9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:19:32.517861 :C9F036EC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:19:32.509 2025-09-06 22:19:32.517863 :C9F036ED:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22696 pso_num=25 pso_serial#=131 2025-09-06 22:19:32.518005 :C9F036EE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22696 (legacy spawn) 2025-09-06 22:19:32.524533 :C9F036F7: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-09-06 22:19:32.524697 :C9F03700: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-09-06 22:19:32.525517 :C9F03711:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:19:32.535785 :C9F0373B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:19:32.524 2025-09-06 22:19:32.535787 :C9F0373C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23968 pso_num=25 pso_serial#=132 2025-09-06 22:19:32.535918 :C9F0373D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23968 osp_idx=23 osp_ver=284725 osp_pg=0 (spawn #565878) 2025-09-06 22:19:32.535919 :C9F0373E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 22:19:32.535919 :C9F0373F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=188 time=1582823830 2025-09-06 22:19:32.535919 :C9F03740:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 22:19:32.535920 :C9F03741:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 22:19:32.535920 :C9F03742:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 22:19:32.535920 :C9F03743:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 22:19:32.535921 :C9F03744:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 22:19:32.536420 :C9F03745: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-09-06 22:20:02.625924 :C9F04192:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:20:09.081502 :C9F0437C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:20:09.073 2025-09-06 22:20:09.081504 :C9F0437D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24532 pso_num=25 pso_serial#=133 2025-09-06 22:20:09.081639 :C9F0437E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24532 (legacy spawn) 2025-09-06 22:20:09.088159 :C9F0437F: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-09-06 22:20:09.088349 :C9F04384: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-09-06 22:20:09.089209 :C9F04389:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:20:09.098317 :C9F0438C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:20:09.088 2025-09-06 22:20:09.098319 :C9F0438D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21988 pso_num=25 pso_serial#=134 2025-09-06 22:20:09.098454 :C9F0438E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21988 (legacy spawn) 2025-09-06 22:20:09.105075 :C9F0438F: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-09-06 22:20:09.105250 :C9F04398: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-09-06 22:20:09.106040 :C9F04399:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:20:09.973147 :C9F043CE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:20:09.963 2025-09-06 22:20:09.973149 :C9F043CF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23344 pso_num=25 pso_serial#=135 2025-09-06 22:20:09.973295 :C9F043D0:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23344 (legacy spawn) 2025-09-06 22:20:09.980459 :C9F043D1: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-09-06 22:20:09.980645 :C9F043D9: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-09-06 22:20:09.981601 :C9F043DB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:20:12.227944 :C9F0448A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:20:12.215 2025-09-06 22:20:12.227947 :C9F0448B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21304 pso_num=25 pso_serial#=136 2025-09-06 22:20:12.228088 :C9F0448C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21304 (legacy spawn) 2025-09-06 22:20:12.234536 :C9F0448D: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-09-06 22:20:12.234713 :C9F04492: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-09-06 22:20:12.235504 :C9F04497:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:20:12.244205 :C9F0449A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:20:12.230 2025-09-06 22:20:12.244207 :C9F0449B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17424 pso_num=25 pso_serial#=137 2025-09-06 22:20:12.244341 :C9F0449C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17424 (legacy spawn) 2025-09-06 22:20:12.250967 :C9F0449D: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-09-06 22:20:12.251147 :C9F044A6: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-09-06 22:20:12.251962 :C9F044A7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:20:19.091280 :C9F046C5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:20:19.078 2025-09-06 22:20:19.091282 :C9F046C6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23240 pso_num=25 pso_serial#=138 2025-09-06 22:20:19.091426 :C9F046C7:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23240 (legacy spawn) 2025-09-06 22:20:19.097967 :C9F046C8: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-09-06 22:20:19.098153 :C9F046CD: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-09-06 22:20:19.098980 :C9F046D2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 22:20:32.606510 :C9F04B00:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 22:20:32.601 2025-09-06 22:20:32.606512 :C9F04B01:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23632 pso_num=25 pso_serial#=139 2025-09-06 22:20:32.606651 :C9F04B02:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23632 osp_idx=23 osp_ver=284732 osp_pg=0 (spawn #565880) 2025-09-06 22:20:32.606652 :C9F04B03:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 22:20:32.606652 :C9F04B04:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=189 time=1582883908 2025-09-06 22:20:32.606652 :C9F04B05:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 22:20:32.606653 :C9F04B06:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 22:20:32.606653 :C9F04B07:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 22:20:32.606653 :C9F04B08:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 22:20:32.606654 :C9F04B09:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 22:20:32.607159 :C9F04B0A: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-09-06 22:20:32.607841 :C9F04B1F: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-09-06 22:20:32.619160 :C9F04B3B:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 22:20:32.619683 :C9F04B3C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 22:20:32.622165 :C9F04B3D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-06 22:20:32.622535 :C9F04B3E: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-09-06 22:20:32.622576 :C9F04B41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-06 22:20:32.623496 :C9F04B47: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-09-06 22:20:32.623627 :C9F04B50: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 23632, J000)