Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250914180732\orcl_j002_24788_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:34770M/63366M, Ph+PgF:39361M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 24788, image: ORACLE.EXE (J002) *** 2025-09-14 18:07:32.563 *** SESSION ID:(225.60481) 2025-09-14 18:07:32.563 *** 2025-09-14 18:07:32.563 Process diagnostic dump for ORACLE.EXE (J002), OS id=24788, pid: 28, proc_ser: 1, sid: 225, sess_ser: 60481 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 24788 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.162283 sec, exc=0.162283 sec, total=0.162283 sec wait times: max=0.500000 sec, heur=6.849189 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500010 sec, exc=0.500010 sec, total=0.500010 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.503338 sec, exc=0.503338 sec, total=0.503338 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499246 sec, exc=0.499246 sec, total=0.499246 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.188359 sec, exc=0.188359 sec, total=0.188359 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: 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.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.502098 sec, exc=0.502098 sec, total=0.502098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000015 sec of elapsed time Sampled Session History of session 225 serial 60481 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 18:07:25 - 18:07:32] idle wait at each sample [session created at: 18:07:25] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-14 18:07:32.563 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 24788, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-14 18:02:56.425685 :CD666375: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 18:02:56.426585 :CD66637A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:03:44.508682 :CD667333:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:03:44.504 2025-09-14 18:03:44.508684 :CD667334:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23408 pso_num=28 pso_serial#=229 2025-09-14 18:03:44.508831 :CD667335:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23408 (legacy spawn) 2025-09-14 18:03:44.515857 :CD667336: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 18:03:44.516036 :CD66733D: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 18:03:44.516941 :CD667340:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:03:44.543336 :CD667343:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:03:44.535 2025-09-14 18:03:44.543338 :CD667344:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25088 pso_num=28 pso_serial#=230 2025-09-14 18:03:44.543504 :CD667345:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25088 (legacy spawn) 2025-09-14 18:03:44.550526 :CD667346: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 18:03:44.550721 :CD66734C: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 18:03:44.551710 :CD667350:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:03:47.605388 :CD667442:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:03:47.592 2025-09-14 18:03:47.605390 :CD667443:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22308 pso_num=28 pso_serial#=231 2025-09-14 18:03:47.605530 :CD667444:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22308 (legacy spawn) 2025-09-14 18:03:47.612015 :CD667445: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 18:03:47.612184 :CD66744E: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 18:03:47.612982 :CD66744F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:03:47.621716 :CD667452:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:03:47.608 2025-09-14 18:03:47.621718 :CD667453:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23276 pso_num=28 pso_serial#=232 2025-09-14 18:03:47.621856 :CD667454:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23276 (legacy spawn) 2025-09-14 18:03:47.628306 :CD667455: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 18:03:47.628463 :CD66745E: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 18:03:47.629287 :CD66745F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:03:51.046493 :CD667563:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:03:51.038 2025-09-14 18:03:51.046495 :CD667564:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23592 pso_num=28 pso_serial#=233 2025-09-14 18:03:51.046633 :CD667565:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23592 (legacy spawn) 2025-09-14 18:03:51.053152 :CD667566: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 18:03:51.053339 :CD66756B: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 18:03:51.054141 :CD667570:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:03:51.984637 :CD6675A7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:03:51.976 2025-09-14 18:03:51.984640 :CD6675A8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23812 pso_num=28 pso_serial#=234 2025-09-14 18:03:51.984789 :CD6675A9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23812 (legacy spawn) 2025-09-14 18:03:51.991940 :CD6675AA: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 18:03:51.992147 :CD6675AF: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 18:03:51.993263 :CD6675B4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:20.786269 :CD667F13:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:20.771 2025-09-14 18:04:20.786271 :CD667F14:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24228 pso_num=28 pso_serial#=235 2025-09-14 18:04:20.786409 :CD667F15:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24228 (legacy spawn) 2025-09-14 18:04:20.792924 :CD667F16: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 18:04:20.792955 :CD667F19: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 18:04:20.793119 :CD667F20: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 18:04:20.793902 :CD667F2D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:20.802550 :CD667F30:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:20.787 2025-09-14 18:04:20.802552 :CD667F31:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22532 pso_num=28 pso_serial#=236 2025-09-14 18:04:20.802679 :CD667F32:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22532 (legacy spawn) 2025-09-14 18:04:20.809200 :CD667F33: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 18:04:20.809364 :CD667F3C: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 18:04:20.810144 :CD667F3D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:23.030243 :CD668011:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:23.021 2025-09-14 18:04:23.030245 :CD668012:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10320 pso_num=28 pso_serial#=237 2025-09-14 18:04:23.030389 :CD668013:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10320 (legacy spawn) 2025-09-14 18:04:23.037614 :CD668014: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 18:04:23.037804 :CD668019: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 18:04:23.038765 :CD66801E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:23.872606 :CD668034:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:23.859 2025-09-14 18:04:23.872608 :CD668035:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22228 pso_num=28 pso_serial#=238 2025-09-14 18:04:23.872795 :CD668036:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22228 (legacy spawn) 2025-09-14 18:04:23.879283 :CD668037: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 18:04:23.879460 :CD66803E: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 18:04:23.880289 :CD668041:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:23.888964 :CD668044:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:23.875 2025-09-14 18:04:23.888966 :CD668045:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25120 pso_num=28 pso_serial#=239 2025-09-14 18:04:23.889110 :CD668046:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25120 (legacy spawn) 2025-09-14 18:04:23.895635 :CD668047: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 18:04:23.895795 :CD668050: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 18:04:23.896641 :CD668051:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:25.270297 :CD668107:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:25.261 2025-09-14 18:04:25.270300 :CD668108:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24668 pso_num=28 pso_serial#=240 2025-09-14 18:04:25.270444 :CD668109:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24668 (legacy spawn) 2025-09-14 18:04:25.277280 :CD66810A: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 18:04:25.277448 :CD66810F: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 18:04:25.278378 :CD668114:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:54.128903 :CD668A51:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:54.115 2025-09-14 18:04:54.128905 :CD668A52:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25172 pso_num=28 pso_serial#=241 2025-09-14 18:04:54.129047 :CD668A53:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25172 (legacy spawn) 2025-09-14 18:04:54.148634 :CD668A54: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 18:04:54.148832 :CD668A59: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 18:04:54.149848 :CD668A5E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:57.093372 :CD668B48:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:57.078 2025-09-14 18:04:57.093374 :CD668B49:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14024 pso_num=28 pso_serial#=242 2025-09-14 18:04:57.093511 :CD668B4A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14024 (legacy spawn) 2025-09-14 18:04:57.100022 :CD668B4B: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 18:04:57.100199 :CD668B50: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 18:04:57.101000 :CD668B55:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:57.109840 :CD668B58:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:57.110 2025-09-14 18:04:57.109842 :CD668B59:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20512 pso_num=28 pso_serial#=243 2025-09-14 18:04:57.110024 :CD668B5A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20512 (legacy spawn) 2025-09-14 18:04:57.116545 :CD668B5B: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 18:04:57.116711 :CD668B64: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 18:04:57.117584 :CD668B65:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:04:59.255612 :CD668C39:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:04:59.247 2025-09-14 18:04:59.255614 :CD668C3A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16664 pso_num=28 pso_serial#=244 2025-09-14 18:04:59.255752 :CD668C3B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16664 (legacy spawn) 2025-09-14 18:04:59.262357 :CD668C3C: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 18:04:59.262520 :CD668C41: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 18:04:59.263319 :CD668C46:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:05:33.431143 :CD669D95:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:05:33.422 2025-09-14 18:05:33.431145 :CD669D96:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25384 pso_num=28 pso_serial#=245 2025-09-14 18:05:33.431288 :CD669D97:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25384 (legacy spawn) 2025-09-14 18:05:33.437810 :CD669D98: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 18:05:33.437989 :CD669D9D: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 18:05:33.438816 :CD669DA2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:05:33.514410 :CD669DA5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:05:33.500 2025-09-14 18:05:33.514412 :CD669DA6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24696 pso_num=28 pso_serial#=246 2025-09-14 18:05:33.514555 :CD669DA7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24696 (legacy spawn) 2025-09-14 18:05:33.521272 :CD669DA8: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 18:05:33.521450 :CD669DB0: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 18:05:33.522253 :CD669DB2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:05:33.530919 :CD669DB5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:05:33.516 2025-09-14 18:05:33.530921 :CD669DB6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22084 pso_num=28 pso_serial#=247 2025-09-14 18:05:33.531106 :CD669DB7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22084 (legacy spawn) 2025-09-14 18:05:33.537499 :CD669DB8: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 18:05:33.537674 :CD669DC0: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 18:05:33.538453 :CD669DC2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:05:36.781504 :CD669EB9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:05:36.779 2025-09-14 18:05:36.781506 :CD669EBA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21908 pso_num=28 pso_serial#=248 2025-09-14 18:05:36.781644 :CD669EBB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21908 (legacy spawn) 2025-09-14 18:05:36.788091 :CD669EBC: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 18:05:36.788272 :CD669EC5: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 18:05:36.789063 :CD669EC6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:05:36.797749 :CD669EC9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:05:36.795 2025-09-14 18:05:36.797751 :CD669ECA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23244 pso_num=28 pso_serial#=249 2025-09-14 18:05:36.797874 :CD669ECB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23244 (legacy spawn) 2025-09-14 18:05:36.804322 :CD669ECC: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 18:05:36.804520 :CD669ED5: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 18:05:36.805284 :CD669ED6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:05:56.263732 :CD66A538:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:05:56.255 2025-09-14 18:05:56.263734 :CD66A539:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22888 pso_num=28 pso_serial#=250 2025-09-14 18:05:56.263871 :CD66A53A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22888 (legacy spawn) 2025-09-14 18:05:56.289238 :CD66A53B: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 18:05:56.289413 :CD66A540: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 18:05:56.290227 :CD66A545:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:06:41.535902 :CD66B564:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:06:41.523 2025-09-14 18:06:41.535904 :CD66B565:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23320 pso_num=28 pso_serial#=251 2025-09-14 18:06:41.536051 :CD66B566:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23320 (legacy spawn) 2025-09-14 18:06:41.542611 :CD66B567: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 18:06:41.542787 :CD66B570: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 18:06:41.543592 :CD66B571:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:06:46.190914 :CD66B673:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:06:46.177 2025-09-14 18:06:46.190916 :CD66B674:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22704 pso_num=28 pso_serial#=252 2025-09-14 18:06:46.191049 :CD66B675:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22704 (legacy spawn) 2025-09-14 18:06:46.197631 :CD66B676: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 18:06:46.197798 :CD66B67B: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 18:06:46.198657 :CD66B680:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:06:46.207240 :CD66B683:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:06:46.192 2025-09-14 18:06:46.207242 :CD66B684:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22072 pso_num=28 pso_serial#=253 2025-09-14 18:06:46.207368 :CD66B685:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22072 (legacy spawn) 2025-09-14 18:06:46.213801 :CD66B686: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 18:06:46.213970 :CD66B68F: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 18:06:46.214748 :CD66B690:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:06:49.513429 :CD66B806:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:06:49.505 2025-09-14 18:06:49.513431 :CD66B807:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23584 pso_num=28 pso_serial#=254 2025-09-14 18:06:49.513582 :CD66B808:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23584 (legacy spawn) 2025-09-14 18:06:49.520487 :CD66B809: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 18:06:49.520707 :CD66B80E: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 18:06:49.521620 :CD66B813:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:06:49.530836 :CD66B816:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:06:49.521 2025-09-14 18:06:49.530838 :CD66B817:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24156 pso_num=28 pso_serial#=255 2025-09-14 18:06:49.530980 :CD66B818:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24156 (legacy spawn) 2025-09-14 18:06:49.537912 :CD66B819: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 18:06:49.538100 :CD66B822: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 18:06:49.538999 :CD66B823:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 18:07:25.719297 :CD66C623:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 18:07:25.714 2025-09-14 18:07:25.719299 :CD66C624:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24788 pso_num=28 pso_serial#=1 2025-09-14 18:07:25.719516 :CD66C625:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24788 osp_idx=26 osp_ver=324919 osp_pg=0 (spawn #591979) 2025-09-14 18:07:25.719517 :CD66C626: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 18:07:25.719518 :CD66C627:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=176 time=2258897018 2025-09-14 18:07:25.719518 :CD66C628:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 18:07:25.719519 :CD66C629:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 18:07:25.719519 :CD66C62A:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 18:07:25.719520 :CD66C62B:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 18:07:25.719520 :CD66C62C:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 18:07:25.720036 :CD66C636: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 2025-09-14 18:07:29.904157 :CD66C98B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 24788, J002)