Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250824220516\orcl_j000_17608_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:35246M/63366M, Ph+PgF:40375M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 17608, image: ORACLE.EXE (J000) *** 2025-08-24 22:05:16.530 *** SESSION ID:(193.46227) 2025-08-24 22:05:16.530 *** 2025-08-24 22:05:16.530 Process diagnostic dump for ORACLE.EXE (J000), OS id=17608, pid: 24, proc_ser: 210, sid: 193, sess_ser: 46227 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17608 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.470716 sec, exc=0.470716 sec, total=0.470716 sec wait times: max=0.500000 sec, heur=2.477349 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000013 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.510105 sec, exc=0.510105 sec, total=0.510105 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.496494 sec, exc=0.496494 sec, total=0.496494 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 46227 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 22:05:14 - 22:05:16] idle wait at each sample [session created at: 22:05:14] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-24 22:05:16.530 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 17608, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-24 22:02:35.596216 :C430893C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14400 (legacy spawn) 2025-08-24 22:02:35.603034 :C430893D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:02:35.603226 :C4308946:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:02:35.604178 :C4308947:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:02:35.613570 :C430894A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:02:35.614 2025-08-24 22:02:35.613573 :C430894B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19544 pso_num=24 pso_serial#=198 2025-08-24 22:02:35.613751 :C430894C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19544 (legacy spawn) 2025-08-24 22:02:35.620460 :C430894D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:02:35.620656 :C4308956:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:02:35.621485 :C4308957:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:02:48.369933 :C4308D4D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:02:48.361 2025-08-24 22:02:48.369935 :C4308D4E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15056 pso_num=24 pso_serial#=199 2025-08-24 22:02:48.370087 :C4308D4F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15056 (legacy spawn) 2025-08-24 22:02:48.377131 :C4308D50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:02:48.377335 :C4308D55:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:02:48.378262 :C4308D5A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:02:56.069319 :C4308FAF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:02:56.064 2025-08-24 22:02:56.069321 :C4308FB0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21800 pso_num=24 pso_serial#=200 2025-08-24 22:02:56.069458 :C4308FB1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21800 osp_idx=22 osp_ver=784590 osp_pg=0 (spawn #522498) 2025-08-24 22:02:56.069459 :C4308FB2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 22:02:56.069459 :C4308FB3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=42 time=458678080 2025-08-24 22:02:56.069459 :C4308FB4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 22:02:56.069460 :C4308FB5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 22:02:56.069460 :C4308FB6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 22:02:56.069461 :C4308FB7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 22:02:56.069461 :C4308FB8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 22:02:56.069953 :C4308FB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 22:02:56.070563 :C4308FCE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:02:56.081639 :C4308FEA:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-24 22:02:56.082104 :C4308FEB:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-24 22:02:56.084522 :C4308FEC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:02:56.084882 :C4308FED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:02:56.084922 :C4308FF0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:02:56.085793 :C4308FF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:02:56.085928 :C4308FFF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:03:26.178290 :C43099A5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:03:34.936790 :C4309C56:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:03:34.922 2025-08-24 22:03:34.936792 :C4309C57:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20684 pso_num=24 pso_serial#=201 2025-08-24 22:03:34.936962 :C4309C58:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20684 (legacy spawn) 2025-08-24 22:03:34.944205 :C4309C59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:03:34.944403 :C4309C5E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:03:34.945335 :C4309C63:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:03:45.186347 :C4309FC5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:03:45.171 2025-08-24 22:03:45.186350 :C4309FC6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20136 pso_num=24 pso_serial#=202 2025-08-24 22:03:45.186482 :C4309FC7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20136 (legacy spawn) 2025-08-24 22:03:45.204979 :C4309FC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:03:45.205154 :C4309FD1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:03:45.206014 :C4309FD2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:03:45.214727 :C4309FD5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:03:45.203 2025-08-24 22:03:45.214729 :C4309FD6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21564 pso_num=24 pso_serial#=203 2025-08-24 22:03:45.214866 :C4309FD7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21564 (legacy spawn) 2025-08-24 22:03:45.221315 :C4309FD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:03:45.221483 :C4309FE1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:03:45.222259 :C4309FE2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:03:48.390316 :C430A0D5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:03:48.377 2025-08-24 22:03:48.390318 :C430A0D6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16392 pso_num=24 pso_serial#=204 2025-08-24 22:03:48.390456 :C430A0D7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16392 (legacy spawn) 2025-08-24 22:03:48.396983 :C430A0D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:03:48.397157 :C430A0E1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:03:48.397958 :C430A0E2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:03:48.406603 :C430A0E5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:03:48.392 2025-08-24 22:03:48.406605 :C430A0E6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20404 pso_num=24 pso_serial#=205 2025-08-24 22:03:48.406744 :C430A0E7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20404 (legacy spawn) 2025-08-24 22:03:48.413273 :C430A0E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:03:48.413444 :C430A0F1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:03:48.414219 :C430A0F2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:03:50.609507 :C430A1A1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:03:50.601 2025-08-24 22:03:50.609509 :C430A1A2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22436 pso_num=24 pso_serial#=206 2025-08-24 22:03:50.609652 :C430A1A3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22436 (legacy spawn) 2025-08-24 22:03:50.616238 :C430A1A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:03:50.622466 :C430A1AD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:03:50.623440 :C430A1AE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:03:56.173684 :C430A361:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:03:56.168 2025-08-24 22:03:56.173685 :C430A362:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20744 pso_num=24 pso_serial#=207 2025-08-24 22:03:56.173820 :C430A363:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20744 osp_idx=22 osp_ver=784597 osp_pg=0 (spawn #522500) 2025-08-24 22:03:56.173820 :C430A364:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 22:03:56.173821 :C430A365:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=43 time=458738190 2025-08-24 22:03:56.173821 :C430A366:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 22:03:56.173821 :C430A367:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 22:03:56.173822 :C430A368:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 22:03:56.173822 :C430A369:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 22:03:56.173822 :C430A36A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 22:03:56.174296 :C430A36B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 22:03:56.174998 :C430A380:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:03:56.182480 :C430A39C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:03:56.182852 :C430A39D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:03:56.182907 :C430A3A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:03:56.183830 :C430A3A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:03:56.183995 :C430A3AD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:04:26.278287 :C430ADEF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:04:43.141791 :C430B359:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:04:43.126 2025-08-24 22:04:43.141793 :C430B35A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22160 pso_num=24 pso_serial#=208 2025-08-24 22:04:43.141932 :C430B35B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22160 (legacy spawn) 2025-08-24 22:04:43.148318 :C430B35C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:04:43.148491 :C430B361:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:04:43.149280 :C430B365:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:04:52.828212 :C430B646:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:04:52.820 2025-08-24 22:04:52.828214 :C430B647:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17528 pso_num=24 pso_serial#=209 2025-08-24 22:04:52.828361 :C430B648:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17528 (legacy spawn) 2025-08-24 22:04:52.835042 :C430B649:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:04:52.835254 :C430B64E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:04:52.836145 :C430B653:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-24 22:04:56.272937 :C430B753:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-24 22:04:56.267 2025-08-24 22:04:56.272940 :C430B754:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17608 pso_num=24 pso_serial#=210 2025-08-24 22:04:56.273080 :C430B755:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17608 osp_idx=22 osp_ver=784600 osp_pg=0 (spawn #522502) 2025-08-24 22:04:56.273080 :C430B756:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 22:04:56.273081 :C430B757:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=44 time=458798283 2025-08-24 22:04:56.273081 :C430B758:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 22:04:56.273081 :C430B759:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 22:04:56.273082 :C430B75A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 22:04:56.273082 :C430B75B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 22:04:56.273082 :C430B75C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 22:04:56.273585 :C430B75D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-24 22:04:56.274237 :C430B772:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:04:56.285287 :C430B78E:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-24 22:04:56.285762 :C430B78F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-24 22:04:56.288144 :C430B790:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:04:56.288489 :C430B791:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:04:56.288524 :C430B794:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:04:56.289393 :C430B79A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:04:56.289517 :C430B7A3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 22:05:12.306726 :C430BE6D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-24 22:05:12.411835 :C430C1C6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-24 22:05:12.411835*:C430C1D5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1756044311 eid=16786714 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-24 22:05:12.411835*:C430C1D6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-08-24 22:05:12.411835*:C430C1D7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-08-24 22:05:12.411835*:C430C1D8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-08-24 22:05:12.411835*:C430C1D9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-08-24 22:05:12.411835*:C430C1DA:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-08-24 22:05:12.413835 :C430C1DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:12.413873 :C430C1DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-24 22:05:12.417843 :C430C1F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:12.511556 :C430C20C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:12.639483 :C430C214:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:12.769435 :C430C21C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.902197 :C430C404:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.903386 :C430C40C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.905651 :C430C414:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.908611 :C430C41C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.911676 :C430C424:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.928895 :C430C42C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.929790 :C430C434:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.930484 :C430C43C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.931047 :C430C444:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.934340 :C430C44C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.935356 :C430C454:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:05:13.936297 :C430C455:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:05:13.937176 :C430C456:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:05:13.937648 :C430C457:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:05:13.937655 :C430C458:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:05:13.938338 :C430C459:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:05:13.938982 :C430C45A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:05:13.939418 :C430C45B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:05:13.940765 :C430C45C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:05:13.978423 :C430C45D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.978480 :C430C460:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:05:13.979484 :C430C466:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:13.980485 :C430C46E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:05:13.980980 :C430C46F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.001419 :C430C477:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.001470 :C430C47A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:05:14.002432 :C430C480:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.003319 :C430C488:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:05:14.003828 :C430C489:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.021759 :C430C493:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.021816 :C430C496:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:05:14.022713 :C430C4A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.023718 :C430C4AE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-24 22:05:14.024194 :C430C4AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.046039 :C430C4BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.046086 :C430C4C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-24 22:05:14.046934 :C430C4C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.047396 :C430C4D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.047749 :C430C4DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.047928 :C430C4E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.048073 :C430C4EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.048225 :C430C4F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-24 22:05:14.052367 :C430C51B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 22:05:14.052447 :C430C523:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 17608, J000)