Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250618082723\orcl_j001_16916_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:36532M/63366M, Ph+PgF:42921M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 16916, image: ORACLE.EXE (J001) *** 2025-06-18 08:27:23.594 *** SESSION ID:(209.15331) 2025-06-18 08:27:23.594 *** 2025-06-18 08:27:23.594 Process diagnostic dump for ORACLE.EXE (J001), OS id=16916, pid: 26, proc_ser: 60, sid: 209, sess_ser: 15331 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16916 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.354886 sec, exc=0.354886 sec, total=0.354886 sec wait times: max=0.500000 sec, heur=5.363086 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.510124 sec, exc=0.510124 sec, total=0.510124 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=7 seq_num=8 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.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.000013 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.510084 sec, exc=0.510084 sec, total=0.510084 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500011 sec, exc=0.500011 sec, total=0.500011 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=0 seq_num=1 snap_id=1 wait times: snap=0.487901 sec, exc=0.487901 sec, total=0.487901 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 209 serial 15331 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [6 samples, 08:27:18 - 08:27:23] idle wait at each sample [session created at: 08:27:18] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-18 08:27:23.594 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 16916, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-18 08:23:43.610479 :A642650B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:23:43.610644 :A6426514:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:23:43.611429 :A6426515:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:23:46.687448 :A64265F4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:23:46.674 2025-06-18 08:23:46.687450 :A64265F5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14204 pso_num=26 pso_serial#=34 2025-06-18 08:23:46.687594 :A64265F6:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14204 (legacy spawn) 2025-06-18 08:23:46.694171 :A64265F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:23:46.694349 :A64265FC:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:23:46.695155 :A6426601:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:23:46.703809 :A6426604:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:23:46.690 2025-06-18 08:23:46.703811 :A6426605:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11524 pso_num=26 pso_serial#=35 2025-06-18 08:23:46.703954 :A6426606:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11524 (legacy spawn) 2025-06-18 08:23:46.710510 :A6426607:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:23:46.710674 :A6426610:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:23:46.711537 :A6426611:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:24:19.906459 :A64270CB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:24:19.903 2025-06-18 08:24:19.906461 :A64270CC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16320 pso_num=26 pso_serial#=36 2025-06-18 08:24:19.906605 :A64270CD:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16320 (legacy spawn) 2025-06-18 08:24:19.913043 :A64270CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:24:19.913212 :A64270D3:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:24:19.913994 :A64270D8:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:24:19.922665 :A64270DB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:24:19.919 2025-06-18 08:24:19.922667 :A64270DC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9744 pso_num=26 pso_serial#=37 2025-06-18 08:24:19.922806 :A64270DD:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9744 (legacy spawn) 2025-06-18 08:24:19.929303 :A64270DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:24:19.929477 :A64270E6:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:24:19.930286 :A64270E8:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:24:23.004872 :A64271D4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:24:22.991 2025-06-18 08:24:23.004874 :A64271D5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16908 pso_num=26 pso_serial#=38 2025-06-18 08:24:23.005012 :A64271D6:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16908 (legacy spawn) 2025-06-18 08:24:23.011425 :A64271D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:24:23.011603 :A64271DC:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:24:23.012371 :A64271E1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:24:23.020995 :A64271E4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:24:23.007 2025-06-18 08:24:23.020997 :A64271E5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11852 pso_num=26 pso_serial#=39 2025-06-18 08:24:23.021133 :A64271E6:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11852 (legacy spawn) 2025-06-18 08:24:23.027668 :A64271E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:24:23.027829 :A64271F0:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:24:23.028659 :A64271F1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:24:25.405519 :A6427288:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:24:25.392 2025-06-18 08:24:25.405521 :A6427289:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16132 pso_num=26 pso_serial#=40 2025-06-18 08:24:25.405657 :A642728A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16132 (legacy spawn) 2025-06-18 08:24:25.425590 :A642728B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:24:25.425765 :A6427290:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:24:25.426648 :A6427295:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:24:25.435431 :A6427298:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:24:25.423 2025-06-18 08:24:25.435433 :A6427299:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13924 pso_num=26 pso_serial#=41 2025-06-18 08:24:25.435573 :A642729A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13924 (legacy spawn) 2025-06-18 08:24:25.442082 :A642729B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:24:25.442263 :A64272A3:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:24:25.443061 :A64272A5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:24:36.524472 :A6427633:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:24:36.516 2025-06-18 08:24:36.524474 :A6427634:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15264 pso_num=26 pso_serial#=42 2025-06-18 08:24:36.524611 :A6427635:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15264 (legacy spawn) 2025-06-18 08:24:36.531080 :A6427636:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:24:36.531237 :A642763F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:24:36.532033 :A6427640:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:24:38.527813 :A64276C1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:24:38.520 2025-06-18 08:24:38.527815 :A64276C2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13808 pso_num=26 pso_serial#=43 2025-06-18 08:24:38.527948 :A64276C3:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13808 (legacy spawn) 2025-06-18 08:24:38.534390 :A64276C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:24:38.534564 :A64276C9:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:24:38.535392 :A64276CE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:25:21.461177 :A6428451:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:25:21.448 2025-06-18 08:25:21.461179 :A6428452:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15820 pso_num=26 pso_serial#=44 2025-06-18 08:25:21.461314 :A6428453:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15820 (legacy spawn) 2025-06-18 08:25:21.467843 :A6428454:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:25:21.467874 :A6428457:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:25:21.468014 :A642845D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:25:21.468878 :A642846B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:25:32.516419 :A64287C6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:25:32.502 2025-06-18 08:25:32.516421 :A64287C7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12488 pso_num=26 pso_serial#=45 2025-06-18 08:25:32.516553 :A64287C8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12488 (legacy spawn) 2025-06-18 08:25:32.523117 :A64287D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:25:32.523291 :A64287DA:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:25:32.524077 :A64287DF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:25:32.532775 :A64287E2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:25:32.518 2025-06-18 08:25:32.532776 :A64287E3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14528 pso_num=26 pso_serial#=46 2025-06-18 08:25:32.532909 :A64287E4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14528 (legacy spawn) 2025-06-18 08:25:32.539432 :A64287E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:25:32.539599 :A64287EE:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:25:32.540403 :A64287EF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:25:35.598365 :A64288D7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:25:35.585 2025-06-18 08:25:35.598367 :A64288D8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17148 pso_num=26 pso_serial#=47 2025-06-18 08:25:35.598555 :A64288D9:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17148 (legacy spawn) 2025-06-18 08:25:35.605061 :A64288DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:25:35.605238 :A64288DF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:25:35.606041 :A64288E4:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:25:35.614672 :A64288E7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:25:35.601 2025-06-18 08:25:35.614674 :A64288E8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8872 pso_num=26 pso_serial#=48 2025-06-18 08:25:35.614814 :A64288E9:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=8872 (legacy spawn) 2025-06-18 08:25:35.621291 :A64288EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:25:35.621449 :A64288F3:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:25:35.622249 :A64288F4:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:25:44.836809 :A6428BC8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:25:44.829 2025-06-18 08:25:44.836811 :A6428BC9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16212 pso_num=26 pso_serial#=49 2025-06-18 08:25:44.836966 :A6428BCA:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16212 (legacy spawn) 2025-06-18 08:25:44.843696 :A6428BCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:25:44.843886 :A6428BD0:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:25:44.844827 :A6428BD5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:25:52.547883 :A6428E22:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:25:52.540 2025-06-18 08:25:52.547886 :A6428E23:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12756 pso_num=26 pso_serial#=50 2025-06-18 08:25:52.548041 :A6428E24:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12756 (legacy spawn) 2025-06-18 08:25:52.554570 :A6428E25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:25:52.554744 :A6428E2A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:25:52.555569 :A6428E2F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:26:08.785330 :A6429319:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:26:08.770 2025-06-18 08:26:08.785332 :A642931A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12700 pso_num=26 pso_serial#=51 2025-06-18 08:26:08.785467 :A642931B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12700 (legacy spawn) 2025-06-18 08:26:08.792075 :A642931C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:26:08.792252 :A6429321:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:26:08.793046 :A6429326:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:26:08.801706 :A6429329:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:26:08.786 2025-06-18 08:26:08.801709 :A642932A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14604 pso_num=26 pso_serial#=52 2025-06-18 08:26:08.801860 :A642932B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14604 (legacy spawn) 2025-06-18 08:26:08.808300 :A642932C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:26:08.808460 :A6429335:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:26:08.809258 :A6429336:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:26:11.913333 :A6429417:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:26:11.900 2025-06-18 08:26:11.913334 :A6429418:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16960 pso_num=26 pso_serial#=53 2025-06-18 08:26:11.913480 :A6429419:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16960 (legacy spawn) 2025-06-18 08:26:11.919885 :A642941A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:26:11.920084 :A642941F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:26:11.920908 :A6429424:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:26:11.929644 :A6429427:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:26:11.916 2025-06-18 08:26:11.929646 :A6429428:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16336 pso_num=26 pso_serial#=54 2025-06-18 08:26:11.929783 :A6429429:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16336 (legacy spawn) 2025-06-18 08:26:11.936385 :A642942A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:26:11.936557 :A6429433:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:26:11.937361 :A6429434:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:26:18.979193 :A6429703:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:26:18.967 2025-06-18 08:26:18.979196 :A6429704:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16756 pso_num=26 pso_serial#=55 2025-06-18 08:26:18.979340 :A6429705:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16756 (legacy spawn) 2025-06-18 08:26:18.985882 :A6429706:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:26:18.986049 :A642970B:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:26:18.986862 :A6429710:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:26:23.634176 :A642984C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:26:23.626 2025-06-18 08:26:23.634178 :A642984D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14720 pso_num=26 pso_serial#=56 2025-06-18 08:26:23.634322 :A642984E:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14720 (legacy spawn) 2025-06-18 08:26:23.658636 :A642984F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:26:23.658808 :A6429854:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:26:23.659696 :A6429859:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:26:26.038232 :A642993D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:26:26.033 2025-06-18 08:26:26.038234 :A642993E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17052 pso_num=26 pso_serial#=57 2025-06-18 08:26:26.038377 :A642993F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17052 osp_idx=24 osp_ver=464922 osp_pg=0 (spawn #297270) 2025-06-18 08:26:26.038378 :A6429940:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 08:26:26.038378 :A6429941:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=118 time=3210929235 2025-06-18 08:26:26.038378 :A6429942:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:26:26.038379 :A6429943:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:26:26.038379 :A6429944:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:26:26.038379 :A6429945:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:26:26.038379 :A6429946:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:26:26.038887 :A6429947:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 08:26:26.038932 :A642994D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-06-18 08:26:26.058783 :A64299AA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:26:45.178263 :A6429F72:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:26:45.164 2025-06-18 08:26:45.178265 :A6429F73:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15468 pso_num=26 pso_serial#=58 2025-06-18 08:26:45.178398 :A6429F74:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15468 (legacy spawn) 2025-06-18 08:26:45.184756 :A6429F75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:26:45.184930 :A6429F7E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:26:45.185709 :A6429F7F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:26:45.194315 :A6429F82:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:26:45.180 2025-06-18 08:26:45.194317 :A6429F83:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9584 pso_num=26 pso_serial#=59 2025-06-18 08:26:45.194450 :A6429F84:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9584 (legacy spawn) 2025-06-18 08:26:45.200910 :A6429F85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:26:45.201075 :A6429F8E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-18 08:26:45.201904 :A6429F8F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-18 08:27:18.241492 :A642E08C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-18 08:27:18.230 2025-06-18 08:27:18.241494 :A642E08D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16916 pso_num=26 pso_serial#=60 2025-06-18 08:27:18.241623 :A642E08E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16916 osp_idx=24 osp_ver=464925 osp_pg=0 (spawn #297274) 2025-06-18 08:27:18.241623 :A642E08F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 08:27:18.241624 :A642E090:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=119 time=3210981422 2025-06-18 08:27:18.241624 :A642E091:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:27:18.241625 :A642E092:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:27:18.241625 :A642E093:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:27:18.241626 :A642E094:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:27:18.241626 :A642E095:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:27:18.242156 :A642E096:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] 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 26 (osid: 16916, J001)