Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525140425\orcl_j002_13348_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:37328M/63366M, Ph+PgF:44376M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 13348, image: ORACLE.EXE (J002) *** 2025-05-25 14:04:25.362 *** SESSION ID:(225.32329) 2025-05-25 14:04:25.362 *** 2025-05-25 14:04:25.362 Process diagnostic dump for ORACLE.EXE (J002), OS id=13348, pid: 28, proc_ser: 200, sid: 225, sess_ser: 32329 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13348 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.411552 sec, exc=0.411552 sec, total=0.411552 sec wait times: max=0.500000 sec, heur=4.407019 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.000012 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.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 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.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.500107 sec, exc=0.500107 sec, total=0.500107 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=4 seq_num=5 snap_id=1 wait times: snap=0.500153 sec, exc=0.500153 sec, total=0.500153 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000007 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.500104 sec, exc=0.500104 sec, total=0.500104 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.495044 sec, exc=0.495044 sec, total=0.495044 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 32329 --------------------------------------------------- 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:04:20 - 14:04:25] idle wait at each sample [session created at: 14:04:20] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 14:04:25.362 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 13348, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 14:00:57.454516 :9BD43C2A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:00:59.489676 :9BD43D09:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:00:59.478 2025-05-25 14:00:59.489678 :9BD43D0A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12004 pso_num=28 pso_serial#=176 2025-05-25 14:00:59.489816 :9BD43D0B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12004 (legacy spawn) 2025-05-25 14:00:59.496320 :9BD43D0C: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-05-25 14:00:59.496495 :9BD43D15: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-05-25 14:00:59.497289 :9BD43D16:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:01:00.585273 :9BD43D25:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:01:00.572 2025-05-25 14:01:00.585275 :9BD43D26:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14424 pso_num=28 pso_serial#=177 2025-05-25 14:01:00.585418 :9BD43D27:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14424 (legacy spawn) 2025-05-25 14:01:00.591841 :9BD43D28: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-05-25 14:01:00.592018 :9BD43D30: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-05-25 14:01:00.592834 :9BD43D32:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:01:00.612160 :9BD43D35:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:01:00.603 2025-05-25 14:01:00.612162 :9BD43D36:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12580 pso_num=28 pso_serial#=178 2025-05-25 14:01:00.612293 :9BD43D37:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12580 (legacy spawn) 2025-05-25 14:01:00.618727 :9BD43D38: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-05-25 14:01:00.618915 :9BD43D3D: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-05-25 14:01:00.619762 :9BD43D42:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:01:09.703579 :9BD4400F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:01:09.695 2025-05-25 14:01:09.703580 :9BD44010:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7888 pso_num=28 pso_serial#=179 2025-05-25 14:01:09.703715 :9BD44011:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7888 (legacy spawn) 2025-05-25 14:01:09.710224 :9BD44012: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-05-25 14:01:09.710396 :9BD44017: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-05-25 14:01:09.711189 :9BD4401C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:02:07.482663 :9BD45259:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:02:07.473 2025-05-25 14:02:07.482665 :9BD4525A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14432 pso_num=28 pso_serial#=180 2025-05-25 14:02:07.482807 :9BD4525B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14432 (legacy spawn) 2025-05-25 14:02:07.489310 :9BD4525C: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-05-25 14:02:07.489485 :9BD45261: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-05-25 14:02:07.490310 :9BD45266:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:02:10.036945 :9BD4534C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:02:10.023 2025-05-25 14:02:10.036947 :9BD4534D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11132 pso_num=28 pso_serial#=181 2025-05-25 14:02:10.037082 :9BD4534E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11132 (legacy spawn) 2025-05-25 14:02:10.058483 :9BD4534F: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-05-25 14:02:10.058653 :9BD45354: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-05-25 14:02:10.059421 :9BD45359:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:02:10.068103 :9BD4535C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:02:10.054 2025-05-25 14:02:10.068105 :9BD4535D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14668 pso_num=28 pso_serial#=182 2025-05-25 14:02:10.068281 :9BD4535E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14668 (legacy spawn) 2025-05-25 14:02:10.074795 :9BD4535F: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-05-25 14:02:10.074961 :9BD45368: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-05-25 14:02:10.075796 :9BD45369:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:02:11.874784 :9BD45429:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:02:11.866 2025-05-25 14:02:11.874786 :9BD4542A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15168 pso_num=28 pso_serial#=183 2025-05-25 14:02:11.874926 :9BD4542B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15168 (legacy spawn) 2025-05-25 14:02:11.881369 :9BD4542C: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-05-25 14:02:11.881542 :9BD45431: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-05-25 14:02:11.882373 :9BD45436:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:02:13.174076 :9BD45470:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:02:13.161 2025-05-25 14:02:13.174079 :9BD45471:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12960 pso_num=28 pso_serial#=184 2025-05-25 14:02:13.174217 :9BD45472:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12960 (legacy spawn) 2025-05-25 14:02:13.180701 :9BD45473: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-05-25 14:02:13.180876 :9BD4547C: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-05-25 14:02:13.181647 :9BD4547D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:02:13.190295 :9BD45480:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:02:13.176 2025-05-25 14:02:13.190296 :9BD45481:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14732 pso_num=28 pso_serial#=185 2025-05-25 14:02:13.190436 :9BD45482:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14732 (legacy spawn) 2025-05-25 14:02:13.557166 :9BD45483: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-05-25 14:02:13.557327 :9BD4548C: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-05-25 14:02:13.558148 :9BD4548D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:14.042293 :9BD4687E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:14.034 2025-05-25 14:03:14.042295 :9BD4687F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14892 pso_num=28 pso_serial#=186 2025-05-25 14:03:14.042433 :9BD46880:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14892 (legacy spawn) 2025-05-25 14:03:14.048948 :9BD46881: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-05-25 14:03:14.049119 :9BD4688A: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-05-25 14:03:14.049966 :9BD4688B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:15.682102 :9BD468BD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:15.675 2025-05-25 14:03:15.682104 :9BD468BE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6600 pso_num=28 pso_serial#=187 2025-05-25 14:03:15.682243 :9BD468BF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6600 (legacy spawn) 2025-05-25 14:03:15.688748 :9BD468C0: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-05-25 14:03:15.688920 :9BD468C5: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-05-25 14:03:15.689723 :9BD468CA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:22.827674 :9BD46B3E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:22.814 2025-05-25 14:03:22.827677 :9BD46B3F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14888 pso_num=28 pso_serial#=188 2025-05-25 14:03:22.827812 :9BD46B40:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14888 (legacy spawn) 2025-05-25 14:03:22.834185 :9BD46B4C: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-05-25 14:03:22.834360 :9BD46B51: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-05-25 14:03:22.835140 :9BD46B56:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:22.853347 :9BD46B65:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:22.845 2025-05-25 14:03:22.853349 :9BD46B66:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13864 pso_num=28 pso_serial#=189 2025-05-25 14:03:22.853485 :9BD46B67:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13864 (legacy spawn) 2025-05-25 14:03:22.859979 :9BD46B68: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-05-25 14:03:22.860217 :9BD46B71: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-05-25 14:03:22.861251 :9BD46B72:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:26.127355 :9BD46C6E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:26.114 2025-05-25 14:03:26.127357 :9BD46C6F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12756 pso_num=28 pso_serial#=190 2025-05-25 14:03:26.127490 :9BD46C70:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12756 (legacy spawn) 2025-05-25 14:03:26.134073 :9BD46C71: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-05-25 14:03:26.134245 :9BD46C7A: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-05-25 14:03:26.135055 :9BD46C7B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:26.143663 :9BD46C7E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:26.129 2025-05-25 14:03:26.143665 :9BD46C7F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13408 pso_num=28 pso_serial#=191 2025-05-25 14:03:26.143797 :9BD46C80:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13408 (legacy spawn) 2025-05-25 14:03:26.150245 :9BD46C81: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-05-25 14:03:26.150416 :9BD46C8A: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-05-25 14:03:26.151204 :9BD46C8B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:59.240335 :9BD47720:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:59.225 2025-05-25 14:03:59.240337 :9BD47721:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12492 pso_num=28 pso_serial#=192 2025-05-25 14:03:59.240482 :9BD47722:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12492 (legacy spawn) 2025-05-25 14:03:59.247009 :9BD47723: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-05-25 14:03:59.247177 :9BD47728: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-05-25 14:03:59.247981 :9BD4772D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:59.256782 :9BD47730:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:59.257 2025-05-25 14:03:59.256784 :9BD47731:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9112 pso_num=28 pso_serial#=193 2025-05-25 14:03:59.256917 :9BD47732:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9112 (legacy spawn) 2025-05-25 14:03:59.263319 :9BD47733: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-05-25 14:03:59.263484 :9BD4773C: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-05-25 14:03:59.264310 :9BD4773D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:59.847979 :9BD47763:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:59.840 2025-05-25 14:03:59.847981 :9BD47764:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10596 pso_num=28 pso_serial#=194 2025-05-25 14:03:59.848120 :9BD47765:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10596 (legacy spawn) 2025-05-25 14:03:59.854619 :9BD47766: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-05-25 14:03:59.854781 :9BD4776E: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-05-25 14:03:59.855648 :9BD47770:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:03:59.864289 :9BD47773:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:03:59.855 2025-05-25 14:03:59.864291 :9BD47774:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10856 pso_num=28 pso_serial#=195 2025-05-25 14:03:59.864430 :9BD47775:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10856 (legacy spawn) 2025-05-25 14:03:59.870929 :9BD47776: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-05-25 14:03:59.871074 :9BD4777F: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-05-25 14:03:59.871881 :9BD47780:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:04:02.347370 :9BD47855:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:04:02.334 2025-05-25 14:04:02.347372 :9BD47856:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14840 pso_num=28 pso_serial#=196 2025-05-25 14:04:02.347505 :9BD47857:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14840 (legacy spawn) 2025-05-25 14:04:02.354028 :9BD47858: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-05-25 14:04:02.354209 :9BD47861: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-05-25 14:04:02.355055 :9BD47862:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:04:02.363868 :9BD47865:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:04:02.350 2025-05-25 14:04:02.363870 :9BD47866:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15272 pso_num=28 pso_serial#=197 2025-05-25 14:04:02.364009 :9BD47867:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15272 (legacy spawn) 2025-05-25 14:04:02.370681 :9BD47868: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-05-25 14:04:02.370864 :9BD47870: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-05-25 14:04:02.371711 :9BD47872:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:04:16.225770 :9BD47C43:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:04:16.218 2025-05-25 14:04:16.225772 :9BD47C44:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12608 pso_num=28 pso_serial#=198 2025-05-25 14:04:16.225938 :9BD47C45:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12608 (legacy spawn) 2025-05-25 14:04:16.232368 :9BD47C46: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-05-25 14:04:16.232543 :9BD47C4B: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-05-25 14:04:16.233428 :9BD47C7E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:04:20.759582 :9BD47E2B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:04:20.754 2025-05-25 14:04:20.759584 :9BD47E2C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14192 pso_num=28 pso_serial#=199 2025-05-25 14:04:20.759713 :9BD47E2D:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=14192 osp_idx=26 osp_ver=147077 osp_pg=0 (spawn #218064) 2025-05-25 14:04:20.759714 :9BD47E2E:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 14:04:20.759714 :9BD47E2F:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=185 time=1157633016 2025-05-25 14:04:20.759714 :9BD47E30:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 14:04:20.759715 :9BD47E31:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 14:04:20.759715 :9BD47E32:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 14:04:20.759715 :9BD47E33:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 14:04:20.759715 :9BD47E34:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 14:04:20.760203 :9BD47E35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 14:04:20.760248 :9BD47E3B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-25 14:04:20.764398 :9BD47E52:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 14:04:20.764441 :9BD47E53:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 14:04:20.764695 :9BD47E54:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 14:04:20.764720 :9BD47E55:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 14:04:20.765021 :9BD47E56:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 14:04:20.765036 :9BD47E57:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 14:04:20.765250 :9BD47E58:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 14:04:20.765264 :9BD47E59:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 14:04:20.765484 :9BD47E5A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 14:04:20.765497 :9BD47E5B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 14:04:20.765708 :9BD47E5C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 14:04:20.765722 :9BD47E5D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 14:04:20.768057 :9BD47E82:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 14:04:20.962565 :9BD482A0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 14:04:20.957 2025-05-25 14:04:20.962566 :9BD482A1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13348 pso_num=28 pso_serial#=200 2025-05-25 14:04:20.962693 :9BD482A2:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13348 osp_idx=26 osp_ver=147078 osp_pg=0 (spawn #218067) 2025-05-25 14:04:20.962694 :9BD482A3:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 14:04:20.962694 :9BD482A4:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=186 time=1157633219 2025-05-25 14:04:20.962695 :9BD482A5:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 14:04:20.962695 :9BD482A6:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 14:04:20.962695 :9BD482A7:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 14:04:20.962696 :9BD482A8:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 14:04:20.962696 :9BD482A9:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 14:04:20.963176 :9BD482B3: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: 13348, J002)