Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250914140659\orcl_j002_14268_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:34782M/63366M, Ph+PgF:39428M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 14268, image: ORACLE.EXE (J002) *** 2025-09-14 14:06:59.159 *** SESSION ID:(225.57735) 2025-09-14 14:06:59.159 *** 2025-09-14 14:06:59.159 Process diagnostic dump for ORACLE.EXE (J002), OS id=14268, pid: 28, proc_ser: 158, sid: 225, sess_ser: 57735 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14268 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.238309 sec, exc=0.238309 sec, total=0.238309 sec wait times: max=0.500000 sec, heur=4.242258 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=7 seq_num=8 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.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510100 sec, exc=0.510100 sec, total=0.510100 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.493795 sec, exc=0.493795 sec, total=0.493795 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 225 serial 57735 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 14:06:54 - 14:06:59] idle wait at each sample [session created at: 14:06:54] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-14 14:06:59.159 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 14268, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-14 14:01:17.719779 :CD533572:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22732 (legacy spawn) 2025-09-14 14:01:17.726776 :CD533574:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:01:17.726944 :CD53357D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:01:17.727844 :CD53357E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:01:36.131453 :CD533B2C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:01:36.130 2025-09-14 14:01:36.131456 :CD533B2D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24052 pso_num=28 pso_serial#=131 2025-09-14 14:01:36.131632 :CD533B2E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24052 (legacy spawn) 2025-09-14 14:01:36.139170 :CD533B2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:01:36.139387 :CD533B34:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:01:36.140436 :CD533B39:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:02:10.245688 :CD5346E3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:02:10.236 2025-09-14 14:02:10.245690 :CD5346E4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21880 pso_num=28 pso_serial#=132 2025-09-14 14:02:10.245834 :CD5346E5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21880 (legacy spawn) 2025-09-14 14:02:10.252414 :CD5346E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:02:10.252578 :CD5346EB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:02:10.253400 :CD5346F0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:02:19.880230 :CD5349BC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:02:19.871 2025-09-14 14:02:19.880232 :CD5349BD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18848 pso_num=28 pso_serial#=133 2025-09-14 14:02:19.880374 :CD5349BE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18848 (legacy spawn) 2025-09-14 14:02:19.887116 :CD5349BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:02:19.887299 :CD5349C4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:02:19.888269 :CD5349C9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:02:25.191360 :CD534B8E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:02:25.176 2025-09-14 14:02:25.191362 :CD534B8F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20404 pso_num=28 pso_serial#=134 2025-09-14 14:02:25.191497 :CD534B90:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20404 (legacy spawn) 2025-09-14 14:02:25.198154 :CD534B91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:02:25.198329 :CD534B96:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:02:25.199237 :CD534B9B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:02:25.208305 :CD534B9E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:02:25.207 2025-09-14 14:02:25.208308 :CD534B9F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25428 pso_num=28 pso_serial#=135 2025-09-14 14:02:25.208453 :CD534BA0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25428 (legacy spawn) 2025-09-14 14:02:25.215036 :CD534BA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:02:25.215207 :CD534BAA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:02:25.216041 :CD534BAB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:02:28.402420 :CD534CA5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:02:28.389 2025-09-14 14:02:28.402423 :CD534CA6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23408 pso_num=28 pso_serial#=136 2025-09-14 14:02:28.402559 :CD534CA7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23408 (legacy spawn) 2025-09-14 14:02:28.424820 :CD534CA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:02:28.424993 :CD534CAD:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:02:28.425783 :CD534CB2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:02:28.434535 :CD534CB5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:02:28.420 2025-09-14 14:02:28.434537 :CD534CB6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22496 pso_num=28 pso_serial#=137 2025-09-14 14:02:28.434687 :CD534CB7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22496 (legacy spawn) 2025-09-14 14:02:28.441265 :CD534CB8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:02:28.441437 :CD534CC1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:02:28.442278 :CD534CC2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:03:19.633085 :CD535D24:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:03:19.624 2025-09-14 14:03:19.633087 :CD535D25:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22176 pso_num=28 pso_serial#=138 2025-09-14 14:03:19.633232 :CD535D26:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22176 (legacy spawn) 2025-09-14 14:03:19.656853 :CD535D27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:03:19.657025 :CD535D2C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:03:19.657878 :CD535D31:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:03:22.090754 :CD535E0F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:03:22.082 2025-09-14 14:03:22.090757 :CD535E10:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7196 pso_num=28 pso_serial#=139 2025-09-14 14:03:22.090930 :CD535E11:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7196 (legacy spawn) 2025-09-14 14:03:22.097695 :CD535E12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:03:22.097893 :CD535E17:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:03:22.098797 :CD535E1C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:03:53.203547 :CD536817:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:03:53.190 2025-09-14 14:03:53.203550 :CD536818:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12108 pso_num=28 pso_serial#=140 2025-09-14 14:03:53.203692 :CD536819:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12108 (legacy spawn) 2025-09-14 14:03:53.210342 :CD53681A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:03:53.210532 :CD536822:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:03:53.211353 :CD536824:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:03:53.434714 :CD53683A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:03:53.424 2025-09-14 14:03:53.434716 :CD53683B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25104 pso_num=28 pso_serial#=141 2025-09-14 14:03:53.434867 :CD53683C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25104 (legacy spawn) 2025-09-14 14:03:53.441719 :CD53683D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:03:53.441897 :CD536846:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:03:53.442725 :CD536847:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:04:14.693331 :CD536F47:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:04:14.693 2025-09-14 14:04:14.693334 :CD536F48:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25588 pso_num=28 pso_serial#=142 2025-09-14 14:04:14.693482 :CD536F49:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25588 (legacy spawn) 2025-09-14 14:04:14.700283 :CD536F4A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:04:14.700491 :CD536F4F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:04:14.701444 :CD536F54:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:04:14.711025 :CD536F57:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:04:14.709 2025-09-14 14:04:14.711027 :CD536F58:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25028 pso_num=28 pso_serial#=143 2025-09-14 14:04:14.711169 :CD536F59:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25028 (legacy spawn) 2025-09-14 14:04:14.717977 :CD536F5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:04:14.718161 :CD536F63:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:04:14.719048 :CD536F64:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:04:17.909342 :CD537056:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:04:17.901 2025-09-14 14:04:17.909345 :CD537057:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23104 pso_num=28 pso_serial#=144 2025-09-14 14:04:17.909490 :CD537058:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23104 (legacy spawn) 2025-09-14 14:04:17.916022 :CD537059:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:04:17.916211 :CD53705E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:04:17.917033 :CD537063:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:04:17.925801 :CD537066:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:04:17.917 2025-09-14 14:04:17.925803 :CD537067:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19064 pso_num=28 pso_serial#=145 2025-09-14 14:04:17.925940 :CD537068:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19064 (legacy spawn) 2025-09-14 14:04:17.932376 :CD537069:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:04:17.932550 :CD537072:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:04:17.933388 :CD537073:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:04:24.299806 :CD537250:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:04:24.291 2025-09-14 14:04:24.299808 :CD537251:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23516 pso_num=28 pso_serial#=146 2025-09-14 14:04:24.299961 :CD537252:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23516 (legacy spawn) 2025-09-14 14:04:24.307174 :CD537253:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:04:24.307383 :CD537258:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:04:24.308301 :CD53725D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:04:27.529372 :CD537375:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:04:27.520 2025-09-14 14:04:27.529374 :CD537376:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23376 pso_num=28 pso_serial#=147 2025-09-14 14:04:27.529518 :CD537377:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23376 (legacy spawn) 2025-09-14 14:04:27.536076 :CD537378:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:04:27.536251 :CD53737D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:04:27.537095 :CD537382:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:05:20.511887 :CD5385C0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:05:20.499 2025-09-14 14:05:20.511889 :CD5385C1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23332 pso_num=28 pso_serial#=148 2025-09-14 14:05:20.512033 :CD5385C2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23332 (legacy spawn) 2025-09-14 14:05:20.518659 :CD5385C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:05:20.518836 :CD5385CC:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:05:20.519630 :CD5385CD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:05:20.528423 :CD5385D0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:05:20.515 2025-09-14 14:05:20.528425 :CD5385D1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23480 pso_num=28 pso_serial#=149 2025-09-14 14:05:20.528566 :CD5385D2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23480 (legacy spawn) 2025-09-14 14:05:20.535090 :CD5385D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:05:20.535262 :CD5385DC:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:05:20.536080 :CD5385DD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:05:26.464812 :CD5387A2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:05:26.457 2025-09-14 14:05:26.464814 :CD5387A3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22836 pso_num=28 pso_serial#=150 2025-09-14 14:05:26.464949 :CD5387A4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22836 (legacy spawn) 2025-09-14 14:05:26.471482 :CD5387A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:05:26.471663 :CD5387AA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:05:26.472497 :CD5387AF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:05:27.606113 :CD53880D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:05:27.592 2025-09-14 14:05:27.606115 :CD53880E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23152 pso_num=28 pso_serial#=151 2025-09-14 14:05:27.606252 :CD53880F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23152 (legacy spawn) 2025-09-14 14:05:27.612719 :CD538810:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:05:27.612892 :CD538815:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:05:27.613694 :CD53881A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:05:27.622434 :CD53881D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:05:27.607 2025-09-14 14:05:27.622437 :CD53881E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24208 pso_num=28 pso_serial#=152 2025-09-14 14:05:27.622682 :CD53881F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24208 (legacy spawn) 2025-09-14 14:05:27.631195 :CD538820:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:05:27.631358 :CD538827:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:05:27.632093 :CD53882A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:05:30.822929 :CD53893A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:05:30.810 2025-09-14 14:05:30.822931 :CD53893B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24956 pso_num=28 pso_serial#=153 2025-09-14 14:05:30.823074 :CD53893C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24956 (legacy spawn) 2025-09-14 14:05:30.829631 :CD53893D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:05:30.829811 :CD538943:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:05:30.830631 :CD538947:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:05:30.848408 :CD53894A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:05:30.841 2025-09-14 14:05:30.848410 :CD53894B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23564 pso_num=28 pso_serial#=154 2025-09-14 14:05:30.848555 :CD53894C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23564 (legacy spawn) 2025-09-14 14:05:30.855017 :CD53894D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:05:30.855184 :CD538956:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:05:30.856049 :CD538957:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:05:35.748611 :CD538ADB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:05:35.740 2025-09-14 14:05:35.748612 :CD538ADC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23912 pso_num=28 pso_serial#=155 2025-09-14 14:05:35.748761 :CD538ADD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23912 (legacy spawn) 2025-09-14 14:05:35.755390 :CD538ADE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:05:35.755548 :CD538AE3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:05:35.756387 :CD538AE8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:06:10.019194 :CD539BFA:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:06:10.010 2025-09-14 14:06:10.019196 :CD539BFB:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23764 pso_num=28 pso_serial#=156 2025-09-14 14:06:10.019333 :CD539BFC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23764 (legacy spawn) 2025-09-14 14:06:10.042982 :CD539BFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:06:10.043174 :CD539C02:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:06:10.044088 :CD539C07:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:06:21.831341 :CD539F9F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:06:21.823 2025-09-14 14:06:21.831344 :CD539FA0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22636 pso_num=28 pso_serial#=157 2025-09-14 14:06:21.831504 :CD539FA1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22636 (legacy spawn) 2025-09-14 14:06:21.838707 :CD539FA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 14:06:21.838897 :CD539FA7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-14 14:06:21.839783 :CD539FAC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 14:06:54.920245 :CD53AEB7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 14:06:54.914 2025-09-14 14:06:54.920247 :CD53AEB8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14268 pso_num=28 pso_serial#=158 2025-09-14 14:06:54.920382 :CD53AEB9:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=14268 osp_idx=26 osp_ver=323546 osp_pg=0 (spawn #591422) 2025-09-14 14:06:54.920383 :CD53AEBA:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 14:06:54.920384 :CD53AEBB:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=171 time=2244466221 2025-09-14 14:06:54.920384 :CD53AEBC:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 14:06:54.920384 :CD53AEBD:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 14:06:54.920385 :CD53AEBE:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 14:06:54.920385 :CD53AEBF:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 14:06:54.920385 :CD53AEC0:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 14:06:54.920874 :CD53AECA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 14268, J002)