Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250816140746\orcl_j002_12684_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:35461M/63366M, Ph+PgF:40806M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12684, image: ORACLE.EXE (J002) *** 2025-08-16 14:07:46.687 *** SESSION ID:(177.56747) 2025-08-16 14:07:46.687 *** 2025-08-16 14:07:46.687 Process diagnostic dump for ORACLE.EXE (J002), OS id=12684, pid: 22, proc_ser: 138, sid: 177, sess_ser: 56747 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12684 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.336664 sec, exc=0.336664 sec, total=0.336664 sec wait times: max=0.500000 sec, heur=7.858015 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=14 seq_num=15 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000017 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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=9 seq_num=10 snap_id=1 wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.505088 sec, exc=0.505088 sec, total=0.505088 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000012 sec of elapsed time Sampled Session History of session 177 serial 56747 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 14:07:38 - 14:07:46] idle wait at each sample [session created at: 14:07:38] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-16 14:07:46.687 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12684, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-16 14:05:05.637550 :C084E065:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:05:07.088679 :C084E0C7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:05:07.074 2025-08-16 14:05:07.088681 :C084E0C8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19960 pso_num=22 pso_serial#=118 2025-08-16 14:05:07.088820 :C084E0C9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19960 (legacy spawn) 2025-08-16 14:05:07.095208 :C084E0CA: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-08-16 14:05:07.095386 :C084E0CF: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-08-16 14:05:07.096189 :C084E0D4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:05:07.104955 :C084E0D7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:05:07.089 2025-08-16 14:05:07.104957 :C084E0D8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11772 pso_num=22 pso_serial#=119 2025-08-16 14:05:07.105089 :C084E0D9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11772 (legacy spawn) 2025-08-16 14:05:07.111581 :C084E0DA: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-08-16 14:05:07.111741 :C084E0E3: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-08-16 14:05:07.112541 :C084E0E4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:05:10.184274 :C084E1FB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:05:10.171 2025-08-16 14:05:10.184276 :C084E1FC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20108 pso_num=22 pso_serial#=120 2025-08-16 14:05:10.184411 :C084E1FD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20108 (legacy spawn) 2025-08-16 14:05:10.204254 :C084E1FE: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-08-16 14:05:10.204424 :C084E203: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-08-16 14:05:10.205272 :C084E208:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:05:10.213951 :C084E20B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:05:10.202 2025-08-16 14:05:10.213953 :C084E20C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22092 pso_num=22 pso_serial#=121 2025-08-16 14:05:10.214090 :C084E20D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22092 (legacy spawn) 2025-08-16 14:05:10.220585 :C084E20E: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-08-16 14:05:10.220748 :C084E216: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-08-16 14:05:10.221554 :C084E218:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:05:11.195035 :C084E266:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:05:11.187 2025-08-16 14:05:11.195037 :C084E267:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20584 pso_num=22 pso_serial#=122 2025-08-16 14:05:11.195177 :C084E268:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20584 (legacy spawn) 2025-08-16 14:05:11.201653 :C084E269: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-08-16 14:05:11.201818 :C084E271: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-08-16 14:05:11.202656 :C084E273:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:05:32.252658 :C084EEE8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:05:32.247 2025-08-16 14:05:32.252660 :C084EEE9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20616 pso_num=22 pso_serial#=123 2025-08-16 14:05:32.252809 :C084EEEA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20616 osp_idx=0 osp_ver=520650 osp_pg=0 (spawn #494717) 2025-08-16 14:05:32.252809 :C084EEEB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 14:05:32.252810 :C084EEEC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=246 time=4033801564 2025-08-16 14:05:32.252810 :C084EEED:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 14:05:32.252811 :C084EEEE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 14:05:32.252811 :C084EEEF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 14:05:32.252811 :C084EEF0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 14:05:32.252811 :C084EEF1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 14:05:32.253306 :C084EEF2: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-08-16 14:05:32.253957 :C084EF07: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-08-16 14:05:32.261532 :C084EF23:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-16 14:05:32.261912 :C084EF24: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-08-16 14:05:32.261948 :C084EF27:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-16 14:05:32.262801 :C084EF2D: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-08-16 14:05:32.262919 :C084EF36: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-08-16 14:06:02.366919 :C084FB2E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:06:13.393209 :C084FEB4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:06:13.385 2025-08-16 14:06:13.393212 :C084FEB5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18656 pso_num=22 pso_serial#=124 2025-08-16 14:06:13.393365 :C084FEB6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18656 (legacy spawn) 2025-08-16 14:06:13.417290 :C084FEB7: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-08-16 14:06:13.417469 :C084FEBC: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-08-16 14:06:13.418366 :C084FEC1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:06:13.721123 :C084FEDA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:06:13.713 2025-08-16 14:06:13.721125 :C084FEDB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21024 pso_num=22 pso_serial#=125 2025-08-16 14:06:13.721266 :C084FEDC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21024 (legacy spawn) 2025-08-16 14:06:13.727781 :C084FEDD: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-08-16 14:06:13.727964 :C084FEE6: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-08-16 14:06:13.728777 :C084FEE7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:06:19.679105 :C08500D0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:06:19.664 2025-08-16 14:06:19.679107 :C08500D1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21476 pso_num=22 pso_serial#=126 2025-08-16 14:06:19.679241 :C08500D2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21476 (legacy spawn) 2025-08-16 14:06:19.685714 :C08500D3: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-08-16 14:06:19.685882 :C08500D8: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-08-16 14:06:19.686692 :C08500DD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:06:19.695387 :C08500E0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:06:19.680 2025-08-16 14:06:19.695389 :C08500E1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20876 pso_num=22 pso_serial#=127 2025-08-16 14:06:19.695548 :C08500E2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20876 (legacy spawn) 2025-08-16 14:06:19.701966 :C08500E3: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-08-16 14:06:19.702131 :C08500EB: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-08-16 14:06:19.702943 :C08500ED:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:06:22.788057 :C08501EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:06:22.775 2025-08-16 14:06:22.788059 :C08501ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21172 pso_num=22 pso_serial#=128 2025-08-16 14:06:22.788206 :C08501EE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21172 (legacy spawn) 2025-08-16 14:06:22.794789 :C08501EF: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-08-16 14:06:22.794958 :C08501F4: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-08-16 14:06:22.795749 :C08501F9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:06:22.814748 :C08501FC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:06:22.806 2025-08-16 14:06:22.814750 :C08501FD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19544 pso_num=22 pso_serial#=129 2025-08-16 14:06:22.814886 :C08501FE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19544 (legacy spawn) 2025-08-16 14:06:22.821467 :C08501FF: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-08-16 14:06:22.821627 :C0850208: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-08-16 14:06:22.822443 :C0850209:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:06:32.339906 :C0850519:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:06:32.334 2025-08-16 14:06:32.339909 :C085051A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13960 pso_num=22 pso_serial#=130 2025-08-16 14:06:32.340043 :C085051B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13960 osp_idx=0 osp_ver=520657 osp_pg=0 (spawn #494719) 2025-08-16 14:06:32.340044 :C085051C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 14:06:32.340044 :C085051D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=247 time=4033861642 2025-08-16 14:06:32.340044 :C085051E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 14:06:32.340045 :C085051F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 14:06:32.340045 :C0850520:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 14:06:32.340045 :C0850521:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 14:06:32.340046 :C0850522:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 14:06:32.340587 :C0850523: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-08-16 14:06:32.341324 :C0850538: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-08-16 14:06:32.352405 :C0850554:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-16 14:06:32.352877 :C0850555:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-16 14:06:32.355199 :C0850556:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-16 14:06:32.355532 :C0850557: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-08-16 14:06:32.355568 :C085055A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-16 14:06:32.356493 :C0850560: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-08-16 14:06:32.356630 :C0850569: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-08-16 14:07:02.481477 :C0850F4C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:07:15.638651 :C085134E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:07:15.630 2025-08-16 14:07:15.638653 :C085134F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12108 pso_num=22 pso_serial#=131 2025-08-16 14:07:15.638797 :C0851350:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12108 (legacy spawn) 2025-08-16 14:07:15.645981 :C0851351: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-08-16 14:07:15.646187 :C0851356: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-08-16 14:07:15.647059 :C085135B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:07:22.124413 :C0851854:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:07:22.116 2025-08-16 14:07:22.124415 :C0851855:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21104 pso_num=22 pso_serial#=132 2025-08-16 14:07:22.124549 :C0851856:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21104 (legacy spawn) 2025-08-16 14:07:22.131079 :C0851857: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-08-16 14:07:22.131262 :C085185C: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-08-16 14:07:22.132131 :C0851861:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:07:32.407556 :C0851BA7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:07:32.393 2025-08-16 14:07:32.407558 :C0851BA8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21564 pso_num=22 pso_serial#=133 2025-08-16 14:07:32.407699 :C0851BA9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21564 (legacy spawn) 2025-08-16 14:07:32.414244 :C0851BAA: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-08-16 14:07:32.414420 :C0851BAF: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-08-16 14:07:32.415221 :C0851BB4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:07:32.424050 :C0851BD2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:07:32.424 2025-08-16 14:07:32.424051 :C0851BD3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19584 pso_num=22 pso_serial#=134 2025-08-16 14:07:32.424194 :C0851BD4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19584 (legacy spawn) 2025-08-16 14:07:32.430767 :C0851C04: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-08-16 14:07:32.430935 :C0851C0E: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-08-16 14:07:32.431781 :C0851C1B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:07:35.671602 :C0851D6F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:07:35.659 2025-08-16 14:07:35.671604 :C0851D70:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20956 pso_num=22 pso_serial#=135 2025-08-16 14:07:35.671738 :C0851D71:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20956 (legacy spawn) 2025-08-16 14:07:35.678292 :C0851D72: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-08-16 14:07:35.678459 :C0851D7B: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-08-16 14:07:35.679264 :C0851D7C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:07:35.687995 :C0851D7F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:07:35.674 2025-08-16 14:07:35.687997 :C0851D80:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21612 pso_num=22 pso_serial#=136 2025-08-16 14:07:35.688135 :C0851D81:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21612 (legacy spawn) 2025-08-16 14:07:35.708602 :C0851D82: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-08-16 14:07:35.708778 :C0851D8B: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-08-16 14:07:35.709596 :C0851D8C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:07:38.634544 :C0851E99:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:07:38.629 2025-08-16 14:07:38.634546 :C0851E9A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20996 pso_num=22 pso_serial#=137 2025-08-16 14:07:38.634693 :C0851E9B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20996 osp_idx=0 osp_ver=520664 osp_pg=0 (spawn #494723) 2025-08-16 14:07:38.634694 :C0851E9C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 14:07:38.634695 :C0851E9D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=248 time=4033927939 2025-08-16 14:07:38.634696 :C0851E9E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 14:07:38.634697 :C0851E9F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 14:07:38.634697 :C0851EA0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 14:07:38.634698 :C0851EA1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 14:07:38.634698 :C0851EA2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 14:07:38.635221 :C0851EA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 14:07:38.635270 :C0851EA9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-16 14:07:38.639622 :C0851EBB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-16 14:07:38.639679 :C0851EBC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-16 14:07:38.639945 :C0851EBD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-16 14:07:38.639962 :C0851EBE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-16 14:07:38.640233 :C0851EBF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-16 14:07:38.640255 :C0851EC0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-16 14:07:38.640512 :C0851EC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-16 14:07:38.640525 :C0851EC2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-16 14:07:38.640748 :C0851EC3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-16 14:07:38.640762 :C0851EC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-16 14:07:38.640983 :C0851EC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-16 14:07:38.640996 :C0851EC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-16 14:07:38.643592 :C0851EF4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 14:07:38.837576 :C0852068:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 14:07:38.832 2025-08-16 14:07:38.837578 :C0852069:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12684 pso_num=22 pso_serial#=138 2025-08-16 14:07:38.837713 :C085206C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12684 osp_idx=0 osp_ver=520665 osp_pg=0 (spawn #494725) 2025-08-16 14:07:38.837713 :C085206D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 14:07:38.837714 :C085206E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=249 time=4033928142 2025-08-16 14:07:38.837714 :C085206F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 14:07:38.837715 :C0852070:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 14:07:38.837715 :C0852071:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 14:07:38.837715 :C0852072:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 14:07:38.837716 :C0852073:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 14:07:38.838201 :C085207D: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 12684, J002)