Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250524060008\orcl_j003_12076_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:37295M/63366M, Ph+PgF:44315M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 12076, image: ORACLE.EXE (J003) *** 2025-05-24 06:00:08.833 *** SESSION ID:(233.23097) 2025-05-24 06:00:08.833 *** 2025-05-24 06:00:08.833 Process diagnostic dump for ORACLE.EXE (J003), OS id=12076, pid: 29, proc_ser: 44, sid: 233, sess_ser: 23097 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12076 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.019145 sec, exc=0.019145 sec, total=0.019145 sec wait times: max=0.500000 sec, heur=5.035054 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.503202 sec, exc=0.503202 sec, total=0.503202 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500125 sec, exc=0.500125 sec, total=0.500125 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.514541 sec, exc=0.514541 sec, total=0.514541 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.497949 sec, exc=0.497949 sec, total=0.497949 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 233 serial 23097 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 06:00:03 - 06:00:08] idle wait at each sample [session created at: 06:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-24 06:00:08.833 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 12076, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-24 00:04:40.143606 :9B22CDDC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 00:04:40.144413 :9B22CDDD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 00:04:41.400458 :9B22CE37:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 00:04:41.387 2025-05-24 00:04:41.400461 :9B22CE38:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12004 pso_num=29 pso_serial#=32 2025-05-24 00:04:41.400604 :9B22CE39:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12004 (legacy spawn) 2025-05-24 00:04:41.407576 :9B22CE3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 00:04:41.407763 :9B22CE3F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 00:04:41.408654 :9B22CE44:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 00:05:15.080821 :9B22DEAD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 00:05:15.067 2025-05-24 00:05:15.080823 :9B22DEAE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13424 pso_num=29 pso_serial#=33 2025-05-24 00:05:15.080957 :9B22DEAF:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13424 (legacy spawn) 2025-05-24 00:05:15.087440 :9B22DEB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 00:05:15.087641 :9B22DEB9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 00:05:15.088454 :9B22DEBA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 00:05:16.720652 :9B22DEE0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 00:05:16.708 2025-05-24 00:05:16.720654 :9B22DEE1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9028 pso_num=29 pso_serial#=34 2025-05-24 00:05:16.720786 :9B22DEE2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9028 (legacy spawn) 2025-05-24 00:05:16.727494 :9B22DEE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 00:05:16.727684 :9B22DEE8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 00:05:16.728520 :9B22DEED:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 00:05:16.737266 :9B22DEF0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 00:05:16.723 2025-05-24 00:05:16.737268 :9B22DEF1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14224 pso_num=29 pso_serial#=35 2025-05-24 00:05:16.737409 :9B22DEF2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14224 (legacy spawn) 2025-05-24 00:05:16.744005 :9B22DEF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 00:05:16.744169 :9B22DEFC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 00:05:16.744962 :9B22DEFD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 00:05:35.723245 :9B22E620:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 00:05:35.715 2025-05-24 00:05:35.723247 :9B22E621:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10280 pso_num=29 pso_serial#=36 2025-05-24 00:05:35.723388 :9B22E622:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10280 (legacy spawn) 2025-05-24 00:05:35.729928 :9B22E623:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 00:05:35.730105 :9B22E628:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 00:05:35.730946 :9B22E62D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 01:00:17.830435 :9B270C20:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 01:00:17.824 2025-05-24 01:00:17.830437 :9B270C21:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7776 pso_num=29 pso_serial#=37 2025-05-24 01:00:17.830573 :9B270C22:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=7776 osp_idx=27 osp_ver=11511 osp_pg=0 (spawn #212919) 2025-05-24 01:00:17.830574 :9B270C23:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 01:00:17.830575 :9B270C24:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=241 time=1024190079 2025-05-24 01:00:17.830575 :9B270C25:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 01:00:17.830576 :9B270C26:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 01:00:17.830576 :9B270C27:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 01:00:17.830576 :9B270C28:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 01:00:17.830577 :9B270C29:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 01:00:17.831069 :9B270C2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 01:00:17.831113 :9B270C30:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 01:00:17.835579 :9B270C43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:17.858024 :9B270C4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:17.873107 :9B270C53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:17.899098 :9B270C5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:17.902905 :9B270C63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:17.906583 :9B270C6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:17.911970 :9B270C73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:17.913029 :9B270C7B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:19.160392 :9B270D21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:19.188233 :9B270D35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:19.188595 :9B270D3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:19.188883 :9B270D45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:19.188901 :9B270D48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:19.188964 :9B270D54:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 01:00:19.192462 :9B270D62:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 01:00:21.536139 :9B270F19:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 01:00:21.521 2025-05-24 01:00:21.536141 :9B270F1A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13768 pso_num=29 pso_serial#=38 2025-05-24 01:00:21.536282 :9B270F1B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13768 (legacy spawn) 2025-05-24 01:00:21.542845 :9B270F1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:21.543036 :9B270F21:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 01:00:21.543846 :9B270F26:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 01:00:21.552649 :9B270F29:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 01:00:21.547 2025-05-24 01:00:21.552651 :9B270F2A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12412 pso_num=29 pso_serial#=39 2025-05-24 01:00:21.552788 :9B270F2B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12412 (legacy spawn) 2025-05-24 01:00:21.559410 :9B270F2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:21.559579 :9B270F35:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 01:00:21.560413 :9B270F36:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 01:00:24.725131 :9B27105D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 01:00:24.712 2025-05-24 01:00:24.725133 :9B27105E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13012 pso_num=29 pso_serial#=40 2025-05-24 01:00:24.725274 :9B27105F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13012 (legacy spawn) 2025-05-24 01:00:24.731678 :9B271089:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:24.731867 :9B27108E:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 01:00:24.732657 :9B271093:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 01:00:24.803435 :9B271096:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 01:00:24.790 2025-05-24 01:00:24.803437 :9B271097:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13672 pso_num=29 pso_serial#=41 2025-05-24 01:00:24.803580 :9B271098:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13672 (legacy spawn) 2025-05-24 01:00:24.810289 :9B271099:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:24.810463 :9B2710A1:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 01:00:24.811188 :9B2710A3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 01:00:24.822356 :9B2710A6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 01:00:24.822 2025-05-24 01:00:24.822357 :9B2710A7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13552 pso_num=29 pso_serial#=42 2025-05-24 01:00:24.822445 :9B2710A8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13552 (legacy spawn) 2025-05-24 01:00:24.828384 :9B2710A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:24.828553 :9B2710B1:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 01:00:24.829336 :9B2710B3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 01:00:32.187845 :9B2712AE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 01:00:32.180 2025-05-24 01:00:32.187847 :9B2712AF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14156 pso_num=29 pso_serial#=43 2025-05-24 01:00:32.187980 :9B2712B0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14156 (legacy spawn) 2025-05-24 01:00:32.194518 :9B2712B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 01:00:32.194696 :9B2712BA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-24 01:00:32.195540 :9B2712BB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-24 06:00:02.050756 :9B3E2E83:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-24 06:00:02.039 2025-05-24 06:00:02.050758 :9B3E2E84:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12076 pso_num=29 pso_serial#=44 2025-05-24 06:00:02.050890 :9B3E2E89:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12076 osp_idx=27 osp_ver=11518 osp_pg=0 (spawn #213611) 2025-05-24 06:00:02.050891 :9B3E2E8A:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 06:00:02.050892 :9B3E2E8B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=242 time=1042174297 2025-05-24 06:00:02.050892 :9B3E2E8C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 06:00:02.050892 :9B3E2E8D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 06:00:02.050893 :9B3E2E8E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 06:00:02.050893 :9B3E2E8F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 06:00:02.050893 :9B3E2E90:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 06:00:02.051407 :9B3E2E97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.055728 :9B3E300A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 06:00:02.056333 :9B3E302C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.056359 :9B3E302F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-05-24 06:00:02.060747 :9B3E3054:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.196235 :9B3E31EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.332010 :9B3E31FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.469915 :9B3E321D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.602418 :9B3E33FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.603345 :9B3E3404:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.605084 :9B3E340C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.606465 :9B3E3414:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.608730 :9B3E341C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.610971 :9B3E3424:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.612368 :9B3E342C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.627566*:9B3E3458:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029AC06A8 estart=1748037603 eid=16783640 ctx=0x000000077F821100 cwh=0x0000000031FB0BB0 cpu=0 buffg=3 interb=0 r=0 rb=0 w=0 wb=0 2025-05-24 06:00:03.627566*:9B3E3459:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029AC06A8 curCtx=0x0000000031FB9E80 pubTabIdxCnt=0 2025-05-24 06:00:03.627566*:9B3E345A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029AC06A8 2025-05-24 06:00:03.627566*:9B3E345B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029AC06A8 2025-05-24 06:00:03.627566*:9B3E345C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029AC06A8 curCtx=0x0000000031FB9E80 xsc->flg4=65792 flags=2 2025-05-24 06:00:03.627566*:9B3E345D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029AC06A8 2025-05-24 06:00:03.627662 :9B3E345E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.628376 :9B3E3476:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.628897 :9B3E347E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.629397 :9B3E3486:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.631914 :9B3E348E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.632906 :9B3E3496:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.633657 :9B3E3497:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.634397 :9B3E3498:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.634791 :9B3E3499:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.634800 :9B3E349A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.635310 :9B3E349B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.635773 :9B3E349C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.636121 :9B3E349D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.637191 :9B3E349E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.665482 :9B3E349F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.665528 :9B3E34A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.666341 :9B3E34A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.667151 :9B3E34B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.667588 :9B3E34B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.688487 :9B3E34B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.688537 :9B3E34BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.689317 :9B3E34C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.700387 :9B3E34CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.700887 :9B3E34CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.722230 :9B3E34D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.722277 :9B3E34DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.723170 :9B3E34E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.724186 :9B3E34F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.724952 :9B3E34F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.744069 :9B3E34FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.744130 :9B3E34FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.744930 :9B3E350C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.745866 :9B3E3514:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.746296 :9B3E3515:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.768883 :9B3E3559:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.768922 :9B3E355C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.769732 :9B3E3562:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.770567 :9B3E356A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.771105 :9B3E356B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.772198 :9B3E3573:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 8, ext 1, nex 3 2025-05-24 06:00:03.772206 :9B3E3574:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:3 rdba:0x00400a81 where: kddwh01: kdddel 2025-05-24 06:00:03.792483 :9B3E3575:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.792531 :9B3E3578:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.793372 :9B3E357E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.793809 :9B3E3586:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.794147 :9B3E358E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.794342 :9B3E3596:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.794487 :9B3E359E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.794615 :9B3E35A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-05-24 06:00:03.798258 :9B3E35A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.798330 :9B3E35AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.798399 :9B3E35B7:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 29 (osid: 12076, J003)