Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250610220006\orcl_j000_16964_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:36782M/63366M, Ph+PgF:43320M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 16964, image: ORACLE.EXE (J000) *** 2025-06-10 22:00:06.189 *** SESSION ID:(177.47401) 2025-06-10 22:00:06.189 *** 2025-06-10 22:00:06.189 Process diagnostic dump for ORACLE.EXE (J000), OS id=16964, pid: 22, proc_ser: 78, sid: 177, sess_ser: 47401 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16964 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.166313 sec, exc=0.166313 sec, total=0.166313 sec wait times: max=0.500000 sec, heur=2.173782 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.000014 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.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.000011 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.000009 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.510101 sec, exc=0.510101 sec, total=0.510101 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.497332 sec, exc=0.497332 sec, total=0.497332 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 177 serial 47401 --------------------------------------------------- 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:00:04 - 22:00:06] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-10 22:00:06.189 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 16964, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-10 21:57:54.900112 :A2FD75DF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:57:55.427138 :A2FD75E2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:57:55.414 2025-06-10 21:57:55.427140 :A2FD75E3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12656 pso_num=22 pso_serial#=62 2025-06-10 21:57:55.427272 :A2FD75E4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12656 (legacy spawn) 2025-06-10 21:57:55.434038 :A2FD75E5: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-10 21:57:55.434215 :A2FD75EA: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-10 21:57:55.435044 :A2FD75EF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:57:55.443770 :A2FD75F2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:57:55.429 2025-06-10 21:57:55.443773 :A2FD75F3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16884 pso_num=22 pso_serial#=63 2025-06-10 21:57:55.443912 :A2FD75F4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16884 (legacy spawn) 2025-06-10 21:57:55.450442 :A2FD75F5: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-10 21:57:55.450597 :A2FD75FE: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-10 21:57:55.451402 :A2FD75FF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:57:56.863193 :A2FD76DF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:57:56.863 2025-06-10 21:57:56.863195 :A2FD76E0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17144 pso_num=22 pso_serial#=64 2025-06-10 21:57:56.863337 :A2FD76E1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17144 osp_idx=0 osp_ver=294661 osp_pg=0 (spawn #272467) 2025-06-10 21:57:56.863338 :A2FD76E2: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-10 21:57:56.863338 :A2FD76E3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=29 time=2568420047 2025-06-10 21:57:56.863339 :A2FD76E4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 21:57:56.863339 :A2FD76E5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 21:57:56.863340 :A2FD76E6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 21:57:56.863340 :A2FD76E7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 21:57:56.863341 :A2FD76E8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 21:57:56.863841 :A2FD76E9: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-10 21:57:56.864657 :A2FD76FE: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-10 21:57:56.872211 :A2FD771A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-10 21:57:56.872573 :A2FD771B: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-10 21:57:56.872612 :A2FD771E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-10 21:57:56.873439 :A2FD7724: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-10 21:57:56.873555 :A2FD772D: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-10 21:58:26.958385 :A2FD8058:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:58:28.519513 :A2FD8072:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:58:28.505 2025-06-10 21:58:28.519515 :A2FD8073:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16368 pso_num=22 pso_serial#=65 2025-06-10 21:58:28.519653 :A2FD8074:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16368 (legacy spawn) 2025-06-10 21:58:28.526055 :A2FD8075: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-10 21:58:28.526236 :A2FD807A: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-10 21:58:28.527059 :A2FD807F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:58:28.535639 :A2FD8082:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:58:28.520 2025-06-10 21:58:28.535641 :A2FD8083:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16580 pso_num=22 pso_serial#=66 2025-06-10 21:58:28.535774 :A2FD8084:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16580 (legacy spawn) 2025-06-10 21:58:28.542578 :A2FD8085: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-10 21:58:28.542609 :A2FD8088: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-10 21:58:28.542743 :A2FD808E: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-10 21:58:28.543508 :A2FD809C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:58:28.937220 :A2FD80F8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:58:28.927 2025-06-10 21:58:28.937222 :A2FD80F9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14208 pso_num=22 pso_serial#=67 2025-06-10 21:58:28.937358 :A2FD80FA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14208 (legacy spawn) 2025-06-10 21:58:28.943880 :A2FD80FB: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-10 21:58:28.944040 :A2FD8104: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-10 21:58:28.944844 :A2FD8105:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:58:31.583850 :A2FD8185:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:58:31.570 2025-06-10 21:58:31.583852 :A2FD8186:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9028 pso_num=22 pso_serial#=68 2025-06-10 21:58:31.583994 :A2FD8187:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9028 (legacy spawn) 2025-06-10 21:58:31.590415 :A2FD8188: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-10 21:58:31.590593 :A2FD818D: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-10 21:58:31.591394 :A2FD8192:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:58:31.600060 :A2FD8195:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:58:31.586 2025-06-10 21:58:31.600062 :A2FD8196:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14196 pso_num=22 pso_serial#=69 2025-06-10 21:58:31.600200 :A2FD8197:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14196 (legacy spawn) 2025-06-10 21:58:31.606647 :A2FD8198: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-10 21:58:31.606806 :A2FD81A1: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-10 21:58:31.607598 :A2FD81A2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:58:48.130067 :A2FD86EF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:58:48.121 2025-06-10 21:58:48.130068 :A2FD86F0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17396 pso_num=22 pso_serial#=70 2025-06-10 21:58:48.130201 :A2FD86F1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17396 (legacy spawn) 2025-06-10 21:58:48.136674 :A2FD86F2: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-10 21:58:48.136850 :A2FD86FB: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-10 21:58:48.137670 :A2FD86FC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:58:56.975140 :A2FD89C2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:58:56.969 2025-06-10 21:58:56.975142 :A2FD89C3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16132 pso_num=22 pso_serial#=71 2025-06-10 21:58:56.975277 :A2FD89C4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16132 osp_idx=0 osp_ver=294668 osp_pg=0 (spawn #272469) 2025-06-10 21:58:56.975277 :A2FD89C5: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-10 21:58:56.975278 :A2FD89C6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=30 time=2568480157 2025-06-10 21:58:56.975278 :A2FD89C7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 21:58:56.975279 :A2FD89C8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 21:58:56.975279 :A2FD89C9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 21:58:56.975280 :A2FD89CA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 21:58:56.975280 :A2FD89CB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 21:58:56.975762 :A2FD89CC: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-10 21:58:56.976388 :A2FD89E1: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-10 21:58:56.987505 :A2FD89FD:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-10 21:58:56.987955 :A2FD89FE:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-10 21:58:56.990335 :A2FD89FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-10 21:58:56.990692 :A2FD8A00: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-10 21:58:56.990735 :A2FD8A03:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-10 21:58:56.991604 :A2FD8A09: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-10 21:58:56.991734 :A2FD8A12: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-10 21:59:27.078125 :A2FD936F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:59:37.323838 :A2FD9645:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:59:37.315 2025-06-10 21:59:37.323840 :A2FD9646:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14876 pso_num=22 pso_serial#=72 2025-06-10 21:59:37.323974 :A2FD9647:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14876 (legacy spawn) 2025-06-10 21:59:37.330370 :A2FD9648: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-10 21:59:37.330550 :A2FD964D: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-10 21:59:37.331365 :A2FD9652:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:59:41.079925 :A2FD978C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:59:41.066 2025-06-10 21:59:41.079927 :A2FD978D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16768 pso_num=22 pso_serial#=73 2025-06-10 21:59:41.080063 :A2FD978E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16768 (legacy spawn) 2025-06-10 21:59:41.086460 :A2FD978F: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-10 21:59:41.086642 :A2FD9794: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-10 21:59:41.087447 :A2FD9799:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:59:41.096012 :A2FD979C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:59:41.082 2025-06-10 21:59:41.096014 :A2FD979D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14872 pso_num=22 pso_serial#=74 2025-06-10 21:59:41.096154 :A2FD979E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14872 (legacy spawn) 2025-06-10 21:59:41.102563 :A2FD979F: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-10 21:59:41.102719 :A2FD97A8: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-10 21:59:41.103503 :A2FD97A9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:59:44.207245 :A2FD9897:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:59:44.193 2025-06-10 21:59:44.207248 :A2FD9898:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10512 pso_num=22 pso_serial#=75 2025-06-10 21:59:44.207390 :A2FD9899:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10512 (legacy spawn) 2025-06-10 21:59:44.214317 :A2FD989A: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-10 21:59:44.214488 :A2FD989F: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-10 21:59:44.215298 :A2FD98A4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:59:44.224059 :A2FD98A7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:59:44.208 2025-06-10 21:59:44.224061 :A2FD98A8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14548 pso_num=22 pso_serial#=76 2025-06-10 21:59:44.224198 :A2FD98A9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14548 (legacy spawn) 2025-06-10 21:59:44.242017 :A2FD98AA: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-10 21:59:44.242188 :A2FD98B3: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-10 21:59:44.243054 :A2FD98B4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:59:50.313770 :A2FD9A81:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:59:50.305 2025-06-10 21:59:50.313772 :A2FD9A82:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16840 pso_num=22 pso_serial#=77 2025-06-10 21:59:50.313908 :A2FD9A83:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16840 (legacy spawn) 2025-06-10 21:59:50.320460 :A2FD9A84: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-10 21:59:50.320628 :A2FD9A89: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-10 21:59:50.321438 :A2FD9A8E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-10 21:59:57.069897 :A2FD9CFD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-10 21:59:57.064 2025-06-10 21:59:57.069899 :A2FD9CFE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16964 pso_num=22 pso_serial#=78 2025-06-10 21:59:57.070035 :A2FD9CFF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16964 osp_idx=0 osp_ver=294675 osp_pg=0 (spawn #272471) 2025-06-10 21:59:57.070035 :A2FD9D00: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-10 21:59:57.070036 :A2FD9D01:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=31 time=2568540266 2025-06-10 21:59:57.070036 :A2FD9D02:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 21:59:57.070036 :A2FD9D03:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 21:59:57.070037 :A2FD9D04:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 21:59:57.070037 :A2FD9D05:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 21:59:57.070037 :A2FD9D06:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 21:59:57.070517 :A2FD9D07: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-10 21:59:57.071150 :A2FD9D1C: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-10 21:59:57.078742 :A2FD9D38:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-10 21:59:57.079155 :A2FD9D39: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-10 21:59:57.079190 :A2FD9D3C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-10 21:59:57.080296 :A2FD9D4E: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-10 21:59:57.080429 :A2FD9D57: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-10 22:00:02.038497 :A2FDA066:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 22:00:02.038497*:A2FDA086:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FDDC718 estart=1749564001 eid=16782093 ctx=0x000000077FD6A000 cwh=0x000000000FDD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-10 22:00:02.038497*:A2FDA087:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FDDC718 curCtx=0x000000000FDD8938 pubTabIdxCnt=0 2025-06-10 22:00:02.038497*:A2FDA088:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FDDC718 2025-06-10 22:00:02.038497*:A2FDA089:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FDDC718 2025-06-10 22:00:02.038497*:A2FDA093:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FDDC718 curCtx=0x000000000FDD8938 xsc->flg4=65792 flags=2 2025-06-10 22:00:02.038497*:A2FDA094:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FDDC718 2025-06-10 22:00:02.045501 :A2FDA0AA: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-10 22:00:02.045565 :A2FDA0AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-06-10 22:00:02.057223 :A2FDA230: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-10 22:00:02.062225 :A2FDA253: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-10 22:00:02.063103 :A2FDA266: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-10 22:00:02.068646 :A2FDA2C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-10 22:00:02.068792 :A2FDA2D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 22:00:02.069458 :A2FDA2FE: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-10 22:00:02.069674 :A2FDA306:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-06-10 22:00:02.070791 :A2FDA32A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-06-10 22:00:02.071222 :A2FDA341: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-10 22:00:02.071255 :A2FDA344:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-10 22:00:02.071295 :A2FDA34C: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-10 22:00:02.163248 :A2FDA3AD:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 22:00:04.007757 :A2FDA7B0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 22:00:04.007757*:A2FDA7B7:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FDDC718 estart=1749564003 eid=16782098 ctx=0x000000077FD6A000 cwh=0x000000000FDD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-10 22:00:04.007757*:A2FDA7B8:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FDDC718 curCtx=0x000000000FDD8938 pubTabIdxCnt=0 2025-06-10 22:00:04.007757*:A2FDA7B9:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FDDC718 2025-06-10 22:00:04.007757*:A2FDA7BA:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FDDC718 2025-06-10 22:00:04.007757*:A2FDA7BB:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FDDC718 curCtx=0x000000000FDD8938 xsc->flg4=65792 flags=2 2025-06-10 22:00:04.007757*:A2FDA7BC:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FDDC718 2025-06-10 22:00:04.010058 :A2FDA7BD: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-10 22:00:04.010102 :A2FDA7C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-06-10 22:00:04.019521 :A2FDA7C6: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-10 22:00:04.020200 :A2FDA7CE: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-10 22:00:04.021104 :A2FDA7D6: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-10 22:00:04.021310 :A2FDA7DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-06-10 22:00:04.022965 :A2FDA7DF: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-10 22:00:04.023014 :A2FDA7E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-10 22:00:04.023113 :A2FDA7EF: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: 16964, J000)