Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250621082903\orcl_j000_18420_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:36421M/63366M, Ph+PgF:42776M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 18420, image: ORACLE.EXE (J000) *** 2025-06-21 08:29:03.729 *** SESSION ID:(177.7441) 2025-06-21 08:29:03.729 *** 2025-06-21 08:29:03.729 Process diagnostic dump for ORACLE.EXE (J000), OS id=18420, pid: 22, proc_ser: 126, sid: 177, sess_ser: 7441 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18420 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=0.020832 sec, exc=0.020832 sec, total=0.020832 sec wait times: max=0.500000 sec, heur=26.092708 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=50 seq_num=51 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 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 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.000013 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.499981 sec, exc=0.499981 sec, total=0.499981 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=44 seq_num=45 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.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=43 seq_num=44 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.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=0.510097 sec, exc=0.510097 sec, total=0.510097 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time Sampled Session History of session 177 serial 7441 --------------------------------------------------- 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 --------------------------------------------------- [27 samples, 08:28:37 - 08:29:03] idle wait at each sample [session created at: 08:28:37] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-21 08:29:03.729 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 18420, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-21 08:26:19.567186 :A7963F9E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:26:20.494874 :A7963FEC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:26:20.487 2025-06-21 08:26:20.494876 :A7963FED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11816 pso_num=22 pso_serial#=106 2025-06-21 08:26:20.495014 :A7963FEE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11816 (legacy spawn) 2025-06-21 08:26:20.501446 :A7963FEF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:26:20.501619 :A7963FF4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:26:20.502419 :A7963FF9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:26:36.807475 :A7964511:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:26:36.793 2025-06-21 08:26:36.807476 :A7964512:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14972 pso_num=22 pso_serial#=107 2025-06-21 08:26:36.807615 :A7964513:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14972 (legacy spawn) 2025-06-21 08:26:36.814100 :A7964514:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:26:36.814270 :A7964519:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:26:36.815055 :A796451E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:26:36.823693 :A7964521:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:26:36.809 2025-06-21 08:26:36.823695 :A7964522:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17820 pso_num=22 pso_serial#=108 2025-06-21 08:26:36.823832 :A7964523:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17820 (legacy spawn) 2025-06-21 08:26:36.830640 :A7964524:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:26:36.830800 :A7964529:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:26:36.831736 :A796452E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:26:37.394685 :A796457A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:26:37.389 2025-06-21 08:26:37.394687 :A796457B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17772 pso_num=22 pso_serial#=109 2025-06-21 08:26:37.394831 :A796457C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17772 osp_idx=0 osp_ver=333211 osp_pg=0 (spawn #307271) 2025-06-21 08:26:37.394832 :A796457D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:26:37.394832 :A796457E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=10 time=3470140579 2025-06-21 08:26:37.394833 :A796457F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:26:37.394833 :A7964580:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:26:37.394834 :A7964581:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:26:37.394834 :A7964582:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:26:37.394834 :A7964583:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:26:37.395374 :A7964584:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:26:37.396045 :A7964599:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:26:37.407085 :A79645B5:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-21 08:26:37.407553 :A79645B6:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-21 08:26:37.409875 :A79645B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-21 08:26:37.410213 :A79645B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:26:37.410249 :A79645BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-21 08:26:37.411169 :A79645C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:26:37.411311 :A79645CA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:27:07.495319 :A7964FDC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:27:13.008485 :A796518A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:27:12.994 2025-06-21 08:27:13.008487 :A796518B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16224 pso_num=22 pso_serial#=110 2025-06-21 08:27:13.008617 :A796518C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16224 (legacy spawn) 2025-06-21 08:27:13.015072 :A796518D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:13.015273 :A7965192:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:27:13.016036 :A7965197:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:27:13.033426 :A796519A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:27:13.025 2025-06-21 08:27:13.033428 :A796519B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4200 pso_num=22 pso_serial#=111 2025-06-21 08:27:13.033567 :A796519C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4200 (legacy spawn) 2025-06-21 08:27:13.039963 :A796519D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:13.040121 :A79651A6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:27:13.040941 :A79651A7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:27:15.856059 :A7965283:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:27:15.848 2025-06-21 08:27:15.856061 :A7965284:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8164 pso_num=22 pso_serial#=112 2025-06-21 08:27:15.856197 :A7965285:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8164 (legacy spawn) 2025-06-21 08:27:15.862615 :A7965286:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:15.862788 :A796528B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:27:15.863592 :A7965290:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:27:16.189176 :A796529F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:27:16.176 2025-06-21 08:27:16.189179 :A79652A0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9744 pso_num=22 pso_serial#=113 2025-06-21 08:27:16.189315 :A79652A1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9744 (legacy spawn) 2025-06-21 08:27:16.195798 :A79652A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:16.195971 :A79652AB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:27:16.196747 :A79652AC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:27:16.205382 :A79652AF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:27:16.192 2025-06-21 08:27:16.205384 :A79652B0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13284 pso_num=22 pso_serial#=114 2025-06-21 08:27:16.205516 :A79652B1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13284 (legacy spawn) 2025-06-21 08:27:16.211934 :A79652BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:16.212093 :A79652C6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:27:16.212863 :A79652C7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:27:21.703571 :A796549C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:27:21.691 2025-06-21 08:27:21.703573 :A796549D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18148 pso_num=22 pso_serial#=115 2025-06-21 08:27:21.703706 :A796549E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18148 (legacy spawn) 2025-06-21 08:27:21.726885 :A796549F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:21.727055 :A79654A4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:27:21.727839 :A79654A9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:27:28.652023 :A79656CC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:27:28.643 2025-06-21 08:27:28.652025 :A79656CD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16268 pso_num=22 pso_serial#=116 2025-06-21 08:27:28.652164 :A79656CE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16268 (legacy spawn) 2025-06-21 08:27:28.658597 :A79656CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:28.658765 :A79656D4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:27:28.659560 :A79656D9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:27:37.504609 :A79659A9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:27:37.499 2025-06-21 08:27:37.504611 :A79659AA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17932 pso_num=22 pso_serial#=117 2025-06-21 08:27:37.504756 :A79659AB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17932 osp_idx=0 osp_ver=333219 osp_pg=0 (spawn #307273) 2025-06-21 08:27:37.504757 :A79659AC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:27:37.504757 :A79659AD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=11 time=3470200688 2025-06-21 08:27:37.504757 :A79659AE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:27:37.504758 :A79659AF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:27:37.504758 :A79659B0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:27:37.504758 :A79659B1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:27:37.504759 :A79659B2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:27:37.505233 :A79659B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:27:37.505886 :A79659C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:37.513413 :A79659E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-21 08:27:37.513790 :A79659E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:37.513826 :A79659E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-21 08:27:37.514876 :A79659EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:27:37.515005 :A79659F7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:28:07.610021 :A79663C0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:28:23.780733 :A79668BE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:28:23.778 2025-06-21 08:28:23.780735 :A79668BF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18312 pso_num=22 pso_serial#=118 2025-06-21 08:28:23.780882 :A79668C0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18312 (legacy spawn) 2025-06-21 08:28:23.787317 :A79668C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:23.787494 :A79668C6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:28:23.788311 :A79668CB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:28:23.797038 :A79668CE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:28:23.794 2025-06-21 08:28:23.797040 :A79668CF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17972 pso_num=22 pso_serial#=119 2025-06-21 08:28:23.797183 :A79668D0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17972 (legacy spawn) 2025-06-21 08:28:23.803575 :A79668D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:23.803603 :A79668D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:23.803751 :A79668DB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:28:23.804572 :A79668E8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:28:23.927268 :A796690E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:28:23.919 2025-06-21 08:28:23.927270 :A796690F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17148 pso_num=22 pso_serial#=120 2025-06-21 08:28:23.927413 :A7966910:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17148 (legacy spawn) 2025-06-21 08:28:23.933901 :A7966911:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:23.934060 :A796691A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:28:23.934886 :A796691B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:28:25.620991 :A79669A1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:28:25.606 2025-06-21 08:28:25.620993 :A79669A2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14440 pso_num=22 pso_serial#=121 2025-06-21 08:28:25.621128 :A79669A3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14440 (legacy spawn) 2025-06-21 08:28:25.627579 :A79669A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:25.627745 :A79669A9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:28:25.628570 :A79669AE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:28:25.637135 :A79669B1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:28:25.622 2025-06-21 08:28:25.637137 :A79669B2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10124 pso_num=22 pso_serial#=122 2025-06-21 08:28:25.637280 :A79669B3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10124 (legacy spawn) 2025-06-21 08:28:25.643742 :A79669B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:25.643910 :A79669BD:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:28:25.644705 :A79669BE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:28:28.718013 :A7966AB3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:28:28.715 2025-06-21 08:28:28.718015 :A7966AB4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17484 pso_num=22 pso_serial#=123 2025-06-21 08:28:28.718150 :A7966AB5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17484 (legacy spawn) 2025-06-21 08:28:28.724634 :A7966AB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:28.724811 :A7966ABB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:28:28.725643 :A7966AC0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:28:28.754507 :A7966AC3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:28:28.746 2025-06-21 08:28:28.754509 :A7966AC4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15408 pso_num=22 pso_serial#=124 2025-06-21 08:28:28.754646 :A7966AC5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15408 (legacy spawn) 2025-06-21 08:28:28.761036 :A7966AC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:28.761205 :A7966ACF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:28:28.762012 :A7966AD0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:28:36.832244 :A7966D60:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:28:36.823 2025-06-21 08:28:36.832246 :A7966D61:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17992 pso_num=22 pso_serial#=125 2025-06-21 08:28:36.832384 :A7966D62:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17992 (legacy spawn) 2025-06-21 08:28:36.838789 :A7966D63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:36.838977 :A7966D68:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-21 08:28:36.839760 :A7966D6D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-21 08:28:37.610305 :A7966DC1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-21 08:28:37.604 2025-06-21 08:28:37.610307 :A7966DC2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18420 pso_num=22 pso_serial#=126 2025-06-21 08:28:37.610446 :A7966DC3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18420 osp_idx=0 osp_ver=333228 osp_pg=0 (spawn #307275) 2025-06-21 08:28:37.610446 :A7966DC4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:28:37.610446 :A7966DC5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=12 time=3470260797 2025-06-21 08:28:37.610447 :A7966DC6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:28:37.610447 :A7966DC7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:28:37.610447 :A7966DC8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:28:37.610448 :A7966DC9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:28:37.610448 :A7966DCA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:28:37.610938 :A7966DCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:28:37.611599 :A7966DE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:37.625802 :A7966DFC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-21 08:28:37.625930 :A7966DFD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-21 08:28:37.626718 :A7966DFE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-21 08:28:37.627078 :A7966DFF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:37.627117 :A7966E02:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-21 08:28:37.635003 :A7966E08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:37.635071 :A7966E0B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-06-21 08:28:37.635116 :A7966E0C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-06-21 08:28:37.635468 :A7966E12:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-21 08:28:37.635853 :A7966E13:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-21 08:28:37.636046 :A7966E14:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-21 08:28:37.636303 :A7966E15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:37.636330 :A7966E18:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-21 08:28:37.637913 :A7966E1E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-21 08:28:37.638218 :A7966E1F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:37.638251 :A7966E22:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-21 08:28:37.639190 :A7966E28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 08:28:37.639328 :A7966E30:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 18420, J000)