Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629080356\orcl_j000_15532_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:36541M/63366M, Ph+PgF:42621M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15532, image: ORACLE.EXE (J000) *** 2025-06-29 08:03:57.027 *** SESSION ID:(193.59935) 2025-06-29 08:03:57.027 *** 2025-06-29 08:03:57.027 Process diagnostic dump for ORACLE.EXE (J000), OS id=15532, pid: 24, proc_ser: 34, sid: 193, sess_ser: 59935 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15532 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.237566 sec, exc=0.237566 sec, total=0.237566 sec wait times: max=0.500000 sec, heur=12.296910 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=23 seq_num=24 snap_id=1 wait times: snap=0.510099 sec, exc=0.510099 sec, total=0.510099 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=22 seq_num=23 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 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=21 seq_num=22 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.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=19 seq_num=20 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 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=18 seq_num=19 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=0.510110 sec, exc=0.510110 sec, total=0.510110 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.510103 sec, exc=0.510103 sec, total=0.510103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 193 serial 59935 --------------------------------------------------- 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 --------------------------------------------------- [13 samples, 08:03:44 - 08:03:57] idle wait at each sample [session created at: 08:03:44] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-29 08:03:57.027 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15532, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 08:00:44.506979 :AB2089E6: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-06-29 08:00:44.507125 :AB2089EF: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-06-29 08:01:14.612517 :AB20936D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:01:27.998913 :AB2097BB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:01:27.991 2025-06-29 08:01:27.998915 :AB2097BC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16080 pso_num=24 pso_serial#=14 2025-06-29 08:01:27.999054 :AB2097BD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16080 (legacy spawn) 2025-06-29 08:01:28.005569 :AB2097BE: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-06-29 08:01:28.005744 :AB2097C3: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-06-29 08:01:28.006564 :AB2097C8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:01:40.494302 :AB209B70:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:01:40.479 2025-06-29 08:01:40.494304 :AB209B71:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16732 pso_num=24 pso_serial#=15 2025-06-29 08:01:40.494438 :AB209B72:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16732 (legacy spawn) 2025-06-29 08:01:40.515833 :AB209B73: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-06-29 08:01:40.516010 :AB209B78: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-06-29 08:01:40.516839 :AB209B7D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:01:40.525492 :AB209B80:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:01:40.511 2025-06-29 08:01:40.525494 :AB209B81:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13936 pso_num=24 pso_serial#=16 2025-06-29 08:01:40.525638 :AB209B82:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13936 (legacy spawn) 2025-06-29 08:01:40.532174 :AB209B83: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-06-29 08:01:40.532341 :AB209B8C: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-06-29 08:01:40.533135 :AB209B8D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:01:42.161078 :AB209C1C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:01:42.152 2025-06-29 08:01:42.161079 :AB209C1D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3388 pso_num=24 pso_serial#=17 2025-06-29 08:01:42.161222 :AB209C1E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3388 (legacy spawn) 2025-06-29 08:01:42.167712 :AB209C1F: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-06-29 08:01:42.167883 :AB209C24: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-06-29 08:01:42.168685 :AB209C29:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:01:43.790644 :AB209C90:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:01:43.777 2025-06-29 08:01:43.790646 :AB209C91:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16840 pso_num=24 pso_serial#=18 2025-06-29 08:01:43.790780 :AB209C92:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16840 (legacy spawn) 2025-06-29 08:01:43.797232 :AB209C9E: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-06-29 08:01:43.797414 :AB209CA7: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-06-29 08:01:43.798245 :AB209CA8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:01:43.806907 :AB209CAB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:01:43.792 2025-06-29 08:01:43.806909 :AB209CAC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18360 pso_num=24 pso_serial#=19 2025-06-29 08:01:43.807045 :AB209CAD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18360 (legacy spawn) 2025-06-29 08:01:43.813551 :AB209CAE: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-06-29 08:01:43.813739 :AB209CB6: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-06-29 08:01:43.814525 :AB209CB8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:01:44.541613 :AB209D0E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:01:44.535 2025-06-29 08:01:44.541614 :AB209D0F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10508 pso_num=24 pso_serial#=20 2025-06-29 08:01:44.541756 :AB209D10:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10508 osp_idx=22 osp_ver=482235 osp_pg=0 (spawn #333906) 2025-06-29 08:01:44.541756 :AB209D11:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:01:44.541757 :AB209D12:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=129 time=4159847735 2025-06-29 08:01:44.541757 :AB209D13:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:01:44.541758 :AB209D14:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:01:44.541758 :AB209D15:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:01:44.541758 :AB209D16:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:01:44.541759 :AB209D17:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:01:44.542260 :AB209D18: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-06-29 08:01:44.542909 :AB209D2D: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-06-29 08:01:44.550425 :AB209D49:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 08:01:44.550783 :AB209D4A: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-06-29 08:01:44.550821 :AB209D4D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 08:01:44.551772 :AB209D5F: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-06-29 08:01:44.551892 :AB209D68: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-06-29 08:02:14.625600 :AB20A68B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:02:16.462181 :AB20A70B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:02:16.448 2025-06-29 08:02:16.462183 :AB20A70C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18092 pso_num=24 pso_serial#=21 2025-06-29 08:02:16.462318 :AB20A70D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18092 (legacy spawn) 2025-06-29 08:02:16.468690 :AB20A70E: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-06-29 08:02:16.469022 :AB20A713: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-06-29 08:02:16.469879 :AB20A718:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:02:16.837126 :AB20A727:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:02:16.837 2025-06-29 08:02:16.837128 :AB20A728:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14372 pso_num=24 pso_serial#=22 2025-06-29 08:02:16.837272 :AB20A729:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14372 (legacy spawn) 2025-06-29 08:02:16.864091 :AB20A735: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-06-29 08:02:16.864260 :AB20A73E: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-06-29 08:02:16.865124 :AB20A73F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:02:16.873700 :AB20A742:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:02:16.863 2025-06-29 08:02:16.873702 :AB20A743:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13120 pso_num=24 pso_serial#=23 2025-06-29 08:02:16.873832 :AB20A744:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13120 (legacy spawn) 2025-06-29 08:02:16.880259 :AB20A758: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-06-29 08:02:16.880424 :AB20A760: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-06-29 08:02:16.881210 :AB20A762:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:02:20.094887 :AB20A857:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:02:20.082 2025-06-29 08:02:20.094889 :AB20A858:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10908 pso_num=24 pso_serial#=24 2025-06-29 08:02:20.095023 :AB20A859:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10908 (legacy spawn) 2025-06-29 08:02:20.101503 :AB20A85A: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-06-29 08:02:20.101689 :AB20A862: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-06-29 08:02:20.102517 :AB20A864:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:02:20.111124 :AB20A867:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:02:20.097 2025-06-29 08:02:20.111126 :AB20A868:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9440 pso_num=24 pso_serial#=25 2025-06-29 08:02:20.111261 :AB20A869:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9440 (legacy spawn) 2025-06-29 08:02:20.117814 :AB20A86A: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-06-29 08:02:20.117981 :AB20A873: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-06-29 08:02:20.118776 :AB20A874:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:02:30.185173 :AB20ABA6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:02:30.176 2025-06-29 08:02:30.185175 :AB20ABA7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17944 pso_num=24 pso_serial#=26 2025-06-29 08:02:30.185315 :AB20ABA8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17944 (legacy spawn) 2025-06-29 08:02:30.209858 :AB20ABA9: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-06-29 08:02:30.210043 :AB20ABAE: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-06-29 08:02:30.210939 :AB20ABB3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:02:44.617789 :AB20B00D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:02:44.612 2025-06-29 08:02:44.617791 :AB20B00E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9900 pso_num=24 pso_serial#=27 2025-06-29 08:02:44.617929 :AB20B00F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9900 osp_idx=22 osp_ver=482242 osp_pg=0 (spawn #333908) 2025-06-29 08:02:44.617930 :AB20B010:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:02:44.617930 :AB20B011:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=130 time=4159907813 2025-06-29 08:02:44.617931 :AB20B012:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:02:44.617932 :AB20B013:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:02:44.617932 :AB20B014:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:02:44.617933 :AB20B015:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:02:44.617933 :AB20B016:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:02:44.618452 :AB20B017: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-06-29 08:02:44.619105 :AB20B02C: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-06-29 08:02:44.633364 :AB20B048:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 08:02:44.633449 :AB20B049:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 08:02:44.634243 :AB20B04A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 08:02:44.634664 :AB20B04B: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-06-29 08:02:44.634703 :AB20B04E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 08:02:44.642582 :AB20B054: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-06-29 08:02:44.642645 :AB20B057:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-06-29 08:02:44.642681 :AB20B058:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-06-29 08:02:44.643086 :AB20B05E:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 08:02:44.643441 :AB20B05F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 08:02:44.643646 :AB20B060:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 08:02:44.643905 :AB20B061: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-06-29 08:02:44.643934 :AB20B064:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 08:02:44.645495 :AB20B06A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 08:02:44.645711 :AB20B06B: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-06-29 08:02:44.645739 :AB20B06E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 08:02:44.646604 :AB20B074: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-06-29 08:02:44.646721 :AB20B07D: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-06-29 08:03:14.724407 :AB20BA44:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:03:24.465064 :AB20BD47:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:03:24.456 2025-06-29 08:03:24.465066 :AB20BD48:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15772 pso_num=24 pso_serial#=28 2025-06-29 08:03:24.465210 :AB20BD49:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15772 (legacy spawn) 2025-06-29 08:03:24.471643 :AB20BD4A: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-06-29 08:03:24.471822 :AB20BD4F: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-06-29 08:03:24.472664 :AB20BD54:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:03:29.509933 :AB20BEC9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:03:29.495 2025-06-29 08:03:29.509935 :AB20BECA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14488 pso_num=24 pso_serial#=29 2025-06-29 08:03:29.510075 :AB20BECB:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14488 (legacy spawn) 2025-06-29 08:03:29.516507 :AB20BECC: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-06-29 08:03:29.516683 :AB20BED1: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-06-29 08:03:29.517509 :AB20BED6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:03:29.526188 :AB20BED9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:03:29.511 2025-06-29 08:03:29.526190 :AB20BEDA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6552 pso_num=24 pso_serial#=30 2025-06-29 08:03:29.526331 :AB20BEDB:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6552 (legacy spawn) 2025-06-29 08:03:29.532821 :AB20BEDC: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-06-29 08:03:29.532991 :AB20BEE5: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-06-29 08:03:29.533786 :AB20BEE6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:03:32.348475 :AB20BFD3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:03:32.340 2025-06-29 08:03:32.348477 :AB20BFD4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4640 pso_num=24 pso_serial#=31 2025-06-29 08:03:32.348617 :AB20BFD5:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=4640 (legacy spawn) 2025-06-29 08:03:32.373296 :AB20BFD6: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-06-29 08:03:32.373483 :AB20BFDB: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-06-29 08:03:32.374360 :AB20BFE0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:03:32.619184 :AB20C00B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:03:32.606 2025-06-29 08:03:32.619186 :AB20C00C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3404 pso_num=24 pso_serial#=32 2025-06-29 08:03:32.619332 :AB20C00D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3404 (legacy spawn) 2025-06-29 08:03:32.625915 :AB20C00E: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-06-29 08:03:32.626097 :AB20C017: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-06-29 08:03:32.626931 :AB20C018:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:03:32.635811 :AB20C01B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:03:32.624 2025-06-29 08:03:32.635814 :AB20C01C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9420 pso_num=24 pso_serial#=33 2025-06-29 08:03:32.635957 :AB20C01D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9420 (legacy spawn) 2025-06-29 08:03:32.642756 :AB20C01E: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-06-29 08:03:32.642932 :AB20C026: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-06-29 08:03:32.643783 :AB20C028:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 08:03:44.721861 :AB20C3DB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 08:03:44.716 2025-06-29 08:03:44.721863 :AB20C3DC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15532 pso_num=24 pso_serial#=34 2025-06-29 08:03:44.722005 :AB20C3DD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15532 osp_idx=22 osp_ver=482249 osp_pg=0 (spawn #333910) 2025-06-29 08:03:44.722005 :AB20C3DE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:03:44.722005 :AB20C3DF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=131 time=4159967907 2025-06-29 08:03:44.722006 :AB20C3E0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:03:44.722006 :AB20C3E1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:03:44.722006 :AB20C3E2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:03:44.722007 :AB20C3E3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:03:44.722007 :AB20C3E4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:03:44.722489 :AB20C3E5: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-06-29 08:03:44.723123 :AB20C3FA: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-06-29 08:03:44.730633 :AB20C416:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 08:03:44.731002 :AB20C417: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-06-29 08:03:44.731040 :AB20C41A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 08:03:44.731870 :AB20C420: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-06-29 08:03:44.731988 :AB20C428: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: 15532, J000)