Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250425221409\orcl_j000_9520_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:37623M/63366M, Ph+PgF:45334M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 9520, image: ORACLE.EXE (J000) *** 2025-04-25 22:14:09.982 *** SESSION ID:(193.4945) 2025-04-25 22:14:09.982 *** 2025-04-25 22:14:09.982 Process diagnostic dump for ORACLE.EXE (J000), OS id=9520, pid: 24, proc_ser: 46, sid: 193, sess_ser: 4945 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9520 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=41 seq_num=42 snap_id=1 wait times: snap=0.405777 sec, exc=0.405777 sec, total=0.405777 sec wait times: max=0.500000 sec, heur=20.983959 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=40 seq_num=41 snap_id=1 wait times: snap=0.501071 sec, exc=0.501071 sec, total=0.501071 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=39 seq_num=40 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.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.500170 sec, exc=0.500170 sec, total=0.500170 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=37 seq_num=38 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.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=36 seq_num=37 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.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.500123 sec, exc=0.500123 sec, total=0.500123 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=33 seq_num=34 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.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.507784 sec, exc=0.507784 sec, total=0.507784 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=31 seq_num=32 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.000007 sec of elapsed time Sampled Session History of session 193 serial 4945 --------------------------------------------------- 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 --------------------------------------------------- [21 samples, 22:13:49 - 22:14:09] idle wait at each sample [session created at: 22:13:49] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.015000 sec (max dump time=30.000000 sec) *** 2025-04-25 22:14:09.998 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 9520, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-25 22:10:48.811205 :8EBCD093:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.811233 :8EBCD094:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.811329 :8EBCD095:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.811357 :8EBCD096:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.811460 :8EBCD097:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.811491 :8EBCD098:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.811535 :8EBCD099:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.811787 :8EBCD09A: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-04-25 22:10:48.811824 :8EBCD09D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.818367 :8EBCD0A3: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-04-25 22:10:48.818438 :8EBCD0A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-04-25 22:10:48.818469 :8EBCD0A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-04-25 22:10:48.820250 :8EBCD0AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.820295 :8EBCD0AE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.820429 :8EBCD0AF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.820458 :8EBCD0B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.820483 :8EBCD0B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.820691 :8EBCD0B2: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-04-25 22:10:48.820724 :8EBCD0B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.825212 :8EBCD0BB: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-04-25 22:10:48.825274 :8EBCD0BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-04-25 22:10:48.825306 :8EBCD0BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-04-25 22:10:48.827143 :8EBCD0C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.827217 :8EBCD0C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.827635 :8EBCD0C7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.827870 :8EBCD0C8: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-04-25 22:10:48.827905 :8EBCD0CB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.833447 :8EBCD0D1: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-04-25 22:10:48.833505 :8EBCD0D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-04-25 22:10:48.833537 :8EBCD0D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-04-25 22:10:48.833838 :8EBCD0DB:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-25 22:10:48.834173 :8EBCD0DC:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-25 22:10:48.834301 :8EBCD0DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.834518 :8EBCD0DE: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-04-25 22:10:48.834543 :8EBCD0E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.836157 :8EBCD0E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:10:48.836378 :8EBCD0E8: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-04-25 22:10:48.836405 :8EBCD0EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:10:48.837314 :8EBCD0F1: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-04-25 22:10:48.837430 :8EBCD0FA: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-04-25 22:11:18.939452 :8EBCDABB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:11:38.189634 :8EBCE06E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:11:38.182 2025-04-25 22:11:38.189637 :8EBCE06F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9812 pso_num=24 pso_serial#=31 2025-04-25 22:11:38.189769 :8EBCE070:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9812 (legacy spawn) 2025-04-25 22:11:38.201539 :8EBCE071: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-04-25 22:11:38.201721 :8EBCE076: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-04-25 22:11:38.202569 :8EBCE07B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:11:38.207562 :8EBCE07E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:11:38.197 2025-04-25 22:11:38.207563 :8EBCE07F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6076 pso_num=24 pso_serial#=32 2025-04-25 22:11:38.207700 :8EBCE080:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6076 (legacy spawn) 2025-04-25 22:11:38.214215 :8EBCE081: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-04-25 22:11:38.214379 :8EBCE08A: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-04-25 22:11:38.215169 :8EBCE08B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:11:38.223742 :8EBCE08E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:11:38.213 2025-04-25 22:11:38.223744 :8EBCE08F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8224 pso_num=24 pso_serial#=33 2025-04-25 22:11:38.223880 :8EBCE090:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8224 (legacy spawn) 2025-04-25 22:11:38.230413 :8EBCE091: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-04-25 22:11:38.230580 :8EBCE09A: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-04-25 22:11:38.231373 :8EBCE09B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:11:41.256381 :8EBCE18F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:11:41.243 2025-04-25 22:11:41.256383 :8EBCE190:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9684 pso_num=24 pso_serial#=34 2025-04-25 22:11:41.256522 :8EBCE191:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9684 (legacy spawn) 2025-04-25 22:11:41.263039 :8EBCE192: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-04-25 22:11:41.263220 :8EBCE197: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-04-25 22:11:41.264012 :8EBCE19C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:11:41.272654 :8EBCE19F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:11:41.259 2025-04-25 22:11:41.272656 :8EBCE1A0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10508 pso_num=24 pso_serial#=35 2025-04-25 22:11:41.272798 :8EBCE1A1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10508 (legacy spawn) 2025-04-25 22:11:41.279311 :8EBCE1A2: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-04-25 22:11:41.279480 :8EBCE1AB: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-04-25 22:11:41.280301 :8EBCE1AC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:11:44.856758 :8EBCE315:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:11:44.856 2025-04-25 22:11:44.856760 :8EBCE316:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6600 pso_num=24 pso_serial#=36 2025-04-25 22:11:44.856904 :8EBCE317:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6600 (legacy spawn) 2025-04-25 22:11:44.863340 :8EBCE318: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-04-25 22:11:44.863515 :8EBCE321: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-04-25 22:11:44.864318 :8EBCE322:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:11:48.833493 :8EBCE46E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:11:48.827 2025-04-25 22:11:48.833495 :8EBCE46F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7072 pso_num=24 pso_serial#=37 2025-04-25 22:11:48.833622 :8EBCE470:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7072 osp_idx=22 osp_ver=172416 osp_pg=0 (spawn #118230) 2025-04-25 22:11:48.833622 :8EBCE471:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 22:11:48.833623 :8EBCE472:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=170 time=2889910625 2025-04-25 22:11:48.833623 :8EBCE473:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 22:11:48.833623 :8EBCE474:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 22:11:48.833624 :8EBCE475:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 22:11:48.833624 :8EBCE476:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 22:11:48.833625 :8EBCE477:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 22:11:48.834109 :8EBCE478: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-04-25 22:11:48.834742 :8EBCE48D: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-04-25 22:11:48.842188 :8EBCE4A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:11:48.842539 :8EBCE4AA: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-04-25 22:11:48.842574 :8EBCE4AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:11:48.843489 :8EBCE4B3: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-04-25 22:11:48.843607 :8EBCE4BC: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-04-25 22:12:18.979896 :8EBCEE90:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:12:40.432405 :8EBCF521:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:12:40.424 2025-04-25 22:12:40.432408 :8EBCF522:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10600 pso_num=24 pso_serial#=38 2025-04-25 22:12:40.432540 :8EBCF523:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10600 (legacy spawn) 2025-04-25 22:12:40.439050 :8EBCF524: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-04-25 22:12:40.439231 :8EBCF529: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-04-25 22:12:40.440069 :8EBCF52E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:12:48.947379 :8EBCF81A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:12:48.942 2025-04-25 22:12:48.947382 :8EBCF81B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9828 pso_num=24 pso_serial#=39 2025-04-25 22:12:48.947521 :8EBCF81C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9828 osp_idx=22 osp_ver=172418 osp_pg=0 (spawn #118232) 2025-04-25 22:12:48.947521 :8EBCF81D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 22:12:48.947522 :8EBCF81E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=171 time=2889970750 2025-04-25 22:12:48.947523 :8EBCF81F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 22:12:48.947523 :8EBCF820:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 22:12:48.947523 :8EBCF821:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 22:12:48.947524 :8EBCF822:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 22:12:48.947524 :8EBCF823:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 22:12:48.948018 :8EBCF824: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-04-25 22:12:48.948661 :8EBCF839: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-04-25 22:12:48.959609 :8EBCF855:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-25 22:12:48.960073 :8EBCF856:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-25 22:12:48.962420 :8EBCF857:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:12:48.962766 :8EBCF858: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-04-25 22:12:48.962805 :8EBCF85B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:12:48.963690 :8EBCF861: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-04-25 22:12:48.963818 :8EBCF86A: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-04-25 22:13:19.034510 :8EBD0218:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:13:27.291893 :8EBD04A9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:13:27.277 2025-04-25 22:13:27.291895 :8EBD04AA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4884 pso_num=24 pso_serial#=40 2025-04-25 22:13:27.292036 :8EBD04AB:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=4884 (legacy spawn) 2025-04-25 22:13:27.298485 :8EBD04AC: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-04-25 22:13:27.298659 :8EBD04B5: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-04-25 22:13:27.299445 :8EBD04B6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:13:27.308015 :8EBD04B9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:13:27.293 2025-04-25 22:13:27.308017 :8EBD04BA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9460 pso_num=24 pso_serial#=41 2025-04-25 22:13:27.308148 :8EBD04BB:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9460 (legacy spawn) 2025-04-25 22:13:27.328612 :8EBD04BC: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-04-25 22:13:27.328786 :8EBD04C5: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-04-25 22:13:27.329559 :8EBD04C6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:13:27.336961 :8EBD04C9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:13:27.324 2025-04-25 22:13:27.336963 :8EBD04CA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2512 pso_num=24 pso_serial#=42 2025-04-25 22:13:27.337103 :8EBD04CB:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=2512 (legacy spawn) 2025-04-25 22:13:27.343523 :8EBD04D8: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-04-25 22:13:27.343713 :8EBD04E1: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-04-25 22:13:27.344493 :8EBD04E2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:13:30.595854 :8EBD05DE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:13:30.582 2025-04-25 22:13:30.595856 :8EBD05DF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11040 pso_num=24 pso_serial#=43 2025-04-25 22:13:30.595990 :8EBD05E0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11040 (legacy spawn) 2025-04-25 22:13:30.602390 :8EBD05E1: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-04-25 22:13:30.602559 :8EBD05EA: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-04-25 22:13:30.603342 :8EBD05EB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:13:30.620404 :8EBD05EE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:13:30.613 2025-04-25 22:13:30.620406 :8EBD05EF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8760 pso_num=24 pso_serial#=44 2025-04-25 22:13:30.620536 :8EBD05F0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8760 (legacy spawn) 2025-04-25 22:13:30.626937 :8EBD05F1: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-04-25 22:13:30.627102 :8EBD05FA: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-04-25 22:13:30.627886 :8EBD05FB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:13:42.609167 :8EBD09A0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:13:42.601 2025-04-25 22:13:42.609169 :8EBD09A1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2180 pso_num=24 pso_serial#=45 2025-04-25 22:13:42.609305 :8EBD09A2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=2180 (legacy spawn) 2025-04-25 22:13:42.615765 :8EBD09A3: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-04-25 22:13:42.615959 :8EBD09AA: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-04-25 22:13:42.616791 :8EBD09AD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-25 22:13:49.001852 :8EBD0BA8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-25 22:13:48.996 2025-04-25 22:13:49.001854 :8EBD0BA9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9520 pso_num=24 pso_serial#=46 2025-04-25 22:13:49.002003 :8EBD0BAA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9520 osp_idx=22 osp_ver=172425 osp_pg=0 (spawn #118234) 2025-04-25 22:13:49.002004 :8EBD0BAB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 22:13:49.002004 :8EBD0BAC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=172 time=2890030796 2025-04-25 22:13:49.002005 :8EBD0BAD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 22:13:49.002005 :8EBD0BAE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 22:13:49.002006 :8EBD0BAF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 22:13:49.002006 :8EBD0BB0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 22:13:49.002006 :8EBD0BB1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 22:13:49.002517 :8EBD0BB2: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-04-25 22:13:49.003212 :8EBD0BC7: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-04-25 22:13:49.010939 :8EBD0BE3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-25 22:13:49.011295 :8EBD0BE4: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-04-25 22:13:49.011335 :8EBD0BE7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-25 22:13:49.012227 :8EBD0BED: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-04-25 22:13:49.012360 :8EBD0BF5: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: 9520, J000)