Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250705220714\orcl_j000_14672_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:37326M/63366M, Ph+PgF:43284M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14672, image: ORACLE.EXE (J000) *** 2025-07-05 22:07:14.209 *** SESSION ID:(201.11991) 2025-07-05 22:07:14.209 *** 2025-07-05 22:07:14.209 Process diagnostic dump for ORACLE.EXE (J000), OS id=14672, pid: 25, proc_ser: 204, sid: 201, sess_ser: 11991 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14672 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.225476 sec, exc=0.225476 sec, total=0.225476 sec wait times: max=0.500000 sec, heur=14.244358 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=27 seq_num=28 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 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.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 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.000008 sec of elapsed time 4: 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 5: 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.000014 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=21 seq_num=22 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.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=20 seq_num=21 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.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=19 seq_num=20 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=18 seq_num=19 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.000010 sec of elapsed time Sampled Session History of session 201 serial 11991 --------------------------------------------------- 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 --------------------------------------------------- [15 samples, 22:06:59 - 22:07:14] idle wait at each sample [session created at: 22:06:59] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-05 22:07:14.209 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14672, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-05 22:03:55.929891 :AE07CA07: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-07-05 22:03:55.930064 :AE07CA10: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-07-05 22:03:55.930888 :AE07CA11:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:03:59.660896 :AE07CB2C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:03:59.654 2025-07-05 22:03:59.660898 :AE07CB2D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13760 pso_num=25 pso_serial#=184 2025-07-05 22:03:59.661037 :AE07CB2E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13760 osp_idx=23 osp_ver=183131 osp_pg=0 (spawn #355838) 2025-07-05 22:03:59.661038 :AE07CB2F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 22:03:59.661038 :AE07CB30:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=208 time=433815548 2025-07-05 22:03:59.661039 :AE07CB31:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 22:03:59.661039 :AE07CB32:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 22:03:59.661040 :AE07CB33:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 22:03:59.661040 :AE07CB34:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 22:03:59.661040 :AE07CB35:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 22:03:59.661592 :AE07CB36: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-07-05 22:03:59.662271 :AE07CB4B: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-07-05 22:03:59.670338 :AE07CB67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-07-05 22:03:59.670726 :AE07CB68: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-07-05 22:03:59.670762 :AE07CB6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-07-05 22:03:59.671673 :AE07CB71: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-07-05 22:03:59.671791 :AE07CB7A: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-07-05 22:04:29.751483 :AE07D577:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:04:32.218256 :AE07D666:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:04:32.204 2025-07-05 22:04:32.218258 :AE07D667:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10660 pso_num=25 pso_serial#=185 2025-07-05 22:04:32.218400 :AE07D668:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10660 (legacy spawn) 2025-07-05 22:04:32.224966 :AE07D669: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-07-05 22:04:32.225134 :AE07D66E: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-07-05 22:04:32.225926 :AE07D673:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:04:32.242823 :AE07D676:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:04:32.235 2025-07-05 22:04:32.242825 :AE07D677:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17780 pso_num=25 pso_serial#=186 2025-07-05 22:04:32.242963 :AE07D678:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17780 (legacy spawn) 2025-07-05 22:04:32.252336 :AE07D679: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-07-05 22:04:32.252514 :AE07D67E: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-07-05 22:04:32.253353 :AE07D683:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:04:37.525600 :AE07D836:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:04:37.516 2025-07-05 22:04:37.525602 :AE07D837:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12524 pso_num=25 pso_serial#=187 2025-07-05 22:04:37.525743 :AE07D838:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12524 (legacy spawn) 2025-07-05 22:04:37.532599 :AE07D839: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-07-05 22:04:37.532770 :AE07D83E: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-07-05 22:04:37.533578 :AE07D843:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:04:47.513203 :AE07DB68:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:04:47.504 2025-07-05 22:04:47.513205 :AE07DB69:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14052 pso_num=25 pso_serial#=188 2025-07-05 22:04:47.513343 :AE07DB6A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14052 (legacy spawn) 2025-07-05 22:04:47.520101 :AE07DB6B: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-07-05 22:04:47.520278 :AE07DB70: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-07-05 22:04:47.521087 :AE07DB75:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:04:49.719778 :AE07DC41:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:04:49.710 2025-07-05 22:04:49.719780 :AE07DC42:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18332 pso_num=25 pso_serial#=189 2025-07-05 22:04:49.719924 :AE07DC43:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=18332 (legacy spawn) 2025-07-05 22:04:49.727081 :AE07DC44: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-07-05 22:04:49.727266 :AE07DC49: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-07-05 22:04:49.728186 :AE07DC4E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:04:59.778519 :AE07DF67:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:04:59.772 2025-07-05 22:04:59.778521 :AE07DF68:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7676 pso_num=25 pso_serial#=190 2025-07-05 22:04:59.778673 :AE07DF69:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7676 osp_idx=23 osp_ver=183137 osp_pg=0 (spawn #355841) 2025-07-05 22:04:59.778674 :AE07DF6A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 22:04:59.778674 :AE07DF6B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=209 time=433875673 2025-07-05 22:04:59.778675 :AE07DF6C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 22:04:59.778676 :AE07DF6D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 22:04:59.778676 :AE07DF6E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 22:04:59.778676 :AE07DF6F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 22:04:59.778677 :AE07DF70:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 22:04:59.779268 :AE07DF71: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-07-05 22:04:59.780112 :AE07DF86: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-07-05 22:04:59.792352 :AE07DFA2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-05 22:04:59.792943 :AE07DFA3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-05 22:04:59.796060 :AE07DFA4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-07-05 22:04:59.796469 :AE07DFA5: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-07-05 22:04:59.796513 :AE07DFA8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-07-05 22:04:59.797692 :AE07DFAE: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-07-05 22:04:59.797824 :AE07DFB7: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-07-05 22:05:29.863931 :AE07E9F1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:05:41.789015 :AE07EEB9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:05:41.774 2025-07-05 22:05:41.789017 :AE07EEBA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4160 pso_num=25 pso_serial#=191 2025-07-05 22:05:41.789158 :AE07EEBB:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=4160 (legacy spawn) 2025-07-05 22:05:41.795794 :AE07EEBC: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-07-05 22:05:41.795969 :AE07EEC1: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-07-05 22:05:41.796794 :AE07EEC6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:05:41.805707 :AE07EEC9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:05:41.805 2025-07-05 22:05:41.805709 :AE07EECA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8904 pso_num=25 pso_serial#=192 2025-07-05 22:05:41.805847 :AE07EECB:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8904 (legacy spawn) 2025-07-05 22:05:41.812427 :AE07EECC: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-07-05 22:05:41.812593 :AE07EED5: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-07-05 22:05:41.813381 :AE07EED6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:05:44.860317 :AE07F2B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:05:44.846 2025-07-05 22:05:44.860319 :AE07F2B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15208 pso_num=25 pso_serial#=193 2025-07-05 22:05:44.860460 :AE07F2B8:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=15208 (legacy spawn) 2025-07-05 22:05:44.867318 :AE07F2C6: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-07-05 22:05:44.867486 :AE07F2CB: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-07-05 22:05:44.868279 :AE07F2D0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:05:44.877112 :AE07F2D3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:05:44.862 2025-07-05 22:05:44.877114 :AE07F2D4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8692 pso_num=25 pso_serial#=194 2025-07-05 22:05:44.877248 :AE07F2D5:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8692 (legacy spawn) 2025-07-05 22:05:44.883945 :AE07F2D6: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-07-05 22:05:44.884113 :AE07F2DF: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-07-05 22:05:44.884926 :AE07F2E0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:05:45.809770 :AE07F3B8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:05:45.799 2025-07-05 22:05:45.809773 :AE07F3B9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12008 pso_num=25 pso_serial#=195 2025-07-05 22:05:45.809920 :AE07F3BA:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12008 (legacy spawn) 2025-07-05 22:05:45.816754 :AE07F3BB: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-07-05 22:05:45.816932 :AE07F3C0: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-07-05 22:05:45.817771 :AE07F3C5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:05:51.895349 :AE07F662:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:05:51.887 2025-07-05 22:05:51.895351 :AE07F663:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3068 pso_num=25 pso_serial#=196 2025-07-05 22:05:51.895488 :AE07F664:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=3068 (legacy spawn) 2025-07-05 22:05:51.902198 :AE07F665: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-07-05 22:05:51.902365 :AE07F66E: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-07-05 22:05:51.903181 :AE07F66F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:05:59.879859 :AE07F8FF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:05:59.874 2025-07-05 22:05:59.879861 :AE07F900:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16084 pso_num=25 pso_serial#=197 2025-07-05 22:05:59.879998 :AE07F901:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16084 osp_idx=23 osp_ver=183144 osp_pg=0 (spawn #355843) 2025-07-05 22:05:59.879998 :AE07F902:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 22:05:59.879999 :AE07F903:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=210 time=433935767 2025-07-05 22:05:59.879999 :AE07F904:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 22:05:59.880000 :AE07F905:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 22:05:59.880000 :AE07F906:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 22:05:59.880000 :AE07F907:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 22:05:59.880000 :AE07F908:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 22:05:59.880578 :AE07F909: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-07-05 22:05:59.881279 :AE07F91E: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-07-05 22:05:59.889770 :AE07F93A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-07-05 22:05:59.890174 :AE07F93B: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-07-05 22:05:59.890210 :AE07F93E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-07-05 22:05:59.891170 :AE07F944: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-07-05 22:05:59.891318 :AE07F94D: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-07-05 22:06:29.975316 :AE08053F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:06:54.026670 :AE080D03:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:06:54.017 2025-07-05 22:06:54.026673 :AE080D04:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10808 pso_num=25 pso_serial#=198 2025-07-05 22:06:54.026813 :AE080D05:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10808 (legacy spawn) 2025-07-05 22:06:54.033814 :AE080D06: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-07-05 22:06:54.033994 :AE080D0B: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-07-05 22:06:54.034852 :AE080D10:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:06:54.087961 :AE080D13:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:06:54.079 2025-07-05 22:06:54.087963 :AE080D14:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15892 pso_num=25 pso_serial#=199 2025-07-05 22:06:54.088104 :AE080D15:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=15892 (legacy spawn) 2025-07-05 22:06:54.094763 :AE080D16: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-07-05 22:06:54.094933 :AE080D1F: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-07-05 22:06:54.095764 :AE080D20:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:06:54.890526 :AE080D58:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:06:54.876 2025-07-05 22:06:54.890527 :AE080D59:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16112 pso_num=25 pso_serial#=200 2025-07-05 22:06:54.890678 :AE080D5A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16112 (legacy spawn) 2025-07-05 22:06:54.897327 :AE080D5B: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-07-05 22:06:54.897496 :AE080D64: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-07-05 22:06:54.898300 :AE080D65:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:06:54.915379 :AE080D68:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:06:54.907 2025-07-05 22:06:54.915381 :AE080D69:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16212 pso_num=25 pso_serial#=201 2025-07-05 22:06:54.915516 :AE080D6A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16212 (legacy spawn) 2025-07-05 22:06:54.922130 :AE080D6B: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-07-05 22:06:54.922302 :AE080D74: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-07-05 22:06:54.923120 :AE080D75:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:06:58.084458 :AE080E94:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:06:58.071 2025-07-05 22:06:58.084460 :AE080E95:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8928 pso_num=25 pso_serial#=202 2025-07-05 22:06:58.084599 :AE080E96:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8928 (legacy spawn) 2025-07-05 22:06:58.091199 :AE080E97: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-07-05 22:06:58.091375 :AE080E9D: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-07-05 22:06:58.092215 :AE080EA1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:06:58.101180 :AE080EA4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:06:58.087 2025-07-05 22:06:58.101183 :AE080EA5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11148 pso_num=25 pso_serial#=203 2025-07-05 22:06:58.101321 :AE080EA6:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11148 (legacy spawn) 2025-07-05 22:06:58.107929 :AE080EA7: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-07-05 22:06:58.108096 :AE080EB0: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-07-05 22:06:58.108908 :AE080EB1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-05 22:06:59.952044 :AE080F5F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-05 22:06:59.946 2025-07-05 22:06:59.952045 :AE080F60:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14672 pso_num=25 pso_serial#=204 2025-07-05 22:06:59.952190 :AE080F61:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14672 osp_idx=23 osp_ver=183151 osp_pg=0 (spawn #355846) 2025-07-05 22:06:59.952190 :AE080F62:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 22:06:59.952191 :AE080F63:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=211 time=433995845 2025-07-05 22:06:59.952191 :AE080F64:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 22:06:59.952191 :AE080F65:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 22:06:59.952192 :AE080F66:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 22:06:59.952192 :AE080F67:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 22:06:59.952192 :AE080F68:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 22:06:59.952730 :AE080F69: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-07-05 22:06:59.953571 :AE080F7E: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-07-05 22:06:59.965214 :AE080F9B:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-05 22:06:59.965684 :AE080F9C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-05 22:06:59.968477 :AE080F9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-07-05 22:06:59.968832 :AE080F9E: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-07-05 22:06:59.968868 :AE080FA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-07-05 22:06:59.969798 :AE080FA7: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-07-05 22:06:59.969930 :AE080FB0: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: 14672, J000)