Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250806220005\orcl_w000_14108_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:35683M/63366M, Ph+PgF:41214M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14108, image: ORACLE.EXE (W000) *** 2025-08-06 22:00:05.340 *** SESSION ID:(201.47595) 2025-08-06 22:00:05.340 *** 2025-08-06 22:00:05.340 Process diagnostic dump for ORACLE.EXE (W000), OS id=14108, pid: 25, proc_ser: 209, sid: 201, sess_ser: 47595 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=80 seq_num=81 snap_id=1 wait times: snap=3.713638 sec, exc=3.713638 sec, total=3.713638 sec wait times: max=5.000000 sec, heur=6 min 39 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.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=79 seq_num=80 snap_id=1 wait times: snap=5.009216 sec, exc=5.009216 sec, total=5.009216 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=78 seq_num=79 snap_id=1 wait times: snap=5.010140 sec, exc=5.010140 sec, total=5.010140 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=77 seq_num=78 snap_id=1 wait times: snap=5.009745 sec, exc=5.009745 sec, total=5.009745 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=76 seq_num=77 snap_id=1 wait times: snap=5.003230 sec, exc=5.003230 sec, total=5.003230 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=75 seq_num=76 snap_id=1 wait times: snap=5.000526 sec, exc=5.000526 sec, total=5.000526 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=74 seq_num=75 snap_id=1 wait times: snap=5.003000 sec, exc=5.003000 sec, total=5.003000 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=73 seq_num=74 snap_id=1 wait times: snap=5.003921 sec, exc=5.003921 sec, total=5.003921 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=72 seq_num=73 snap_id=1 wait times: snap=5.010175 sec, exc=5.010175 sec, total=5.010175 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=5.014747 sec, exc=5.014747 sec, total=5.014747 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=70 seq_num=71 snap_id=1 wait times: snap=5.007236 sec, exc=5.007236 sec, total=5.007236 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 201 serial 47595 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 21:58:05 - 22:00:05] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-06 22:00:05.340 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14108, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-06 21:39:19.424020 :BC47A5AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:39:19.424200 :BC47A5B6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:39:19.425017 :BC47A5B7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:39:21.171258 :BC47A622:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:39:21.156 2025-08-06 21:39:21.171260 :BC47A623:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20312 pso_num=25 pso_serial#=184 2025-08-06 21:39:21.171400 :BC47A624:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20312 (legacy spawn) 2025-08-06 21:39:21.177974 :BC47A625:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:39:21.178154 :BC47A62A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:39:21.178960 :BC47A62F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:39:21.187818 :BC47A632:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:39:21.188 2025-08-06 21:39:21.187820 :BC47A633:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8216 pso_num=25 pso_serial#=185 2025-08-06 21:39:21.187964 :BC47A634:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8216 (legacy spawn) 2025-08-06 21:39:21.194511 :BC47A635:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:39:21.194693 :BC47A63E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:39:21.195480 :BC47A63F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:39:24.284618 :BC47A737:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:39:24.271 2025-08-06 21:39:24.284620 :BC47A738:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16860 pso_num=25 pso_serial#=186 2025-08-06 21:39:24.284763 :BC47A739:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16860 (legacy spawn) 2025-08-06 21:39:24.291591 :BC47A73A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:39:24.291807 :BC47A73F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:39:24.292638 :BC47A744:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:39:24.301723 :BC47A747:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:39:24.287 2025-08-06 21:39:24.301726 :BC47A748:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9912 pso_num=25 pso_serial#=187 2025-08-06 21:39:24.301867 :BC47A749:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9912 (legacy spawn) 2025-08-06 21:39:24.308618 :BC47A74A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:39:24.308797 :BC47A753:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:39:24.309689 :BC47A754:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:40:00.469801 :BC47B30F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:40:00.457 2025-08-06 21:40:00.469803 :BC47B310:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21376 pso_num=25 pso_serial#=188 2025-08-06 21:40:00.469940 :BC47B311:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21376 (legacy spawn) 2025-08-06 21:40:00.476427 :BC47B312:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:40:00.476600 :BC47B31A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:40:00.477408 :BC47B31C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:40:00.486109 :BC47B31F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:40:00.472 2025-08-06 21:40:00.486111 :BC47B320:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7880 pso_num=25 pso_serial#=189 2025-08-06 21:40:00.486245 :BC47B321:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=7880 (legacy spawn) 2025-08-06 21:40:00.505300 :BC47B322:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:40:00.505464 :BC47B32B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:40:00.506349 :BC47B32C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:40:12.948159 :BC47B6E5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:40:12.940 2025-08-06 21:40:12.948161 :BC47B6E6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20976 pso_num=25 pso_serial#=190 2025-08-06 21:40:12.948302 :BC47B6E7:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20976 (legacy spawn) 2025-08-06 21:40:12.954821 :BC47B6E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:40:12.954996 :BC47B6EE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:40:12.955960 :BC47B6F2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:40:21.567993 :BC47B9B2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:40:21.555 2025-08-06 21:40:21.567996 :BC47B9B3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8004 pso_num=25 pso_serial#=191 2025-08-06 21:40:21.568136 :BC47B9B4:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8004 (legacy spawn) 2025-08-06 21:40:21.574615 :BC47B9B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:40:21.574797 :BC47B9BA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:40:21.575578 :BC47B9BF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:41:09.908619 :BC47CA6F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:41:09.894 2025-08-06 21:41:09.908621 :BC47CA70:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21200 pso_num=25 pso_serial#=192 2025-08-06 21:41:09.908771 :BC47CA71:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21200 (legacy spawn) 2025-08-06 21:41:09.915304 :BC47CA72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:41:09.915488 :BC47CA77:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:41:09.916287 :BC47CA7C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:41:09.932394 :BC47CA7F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:41:09.925 2025-08-06 21:41:09.932396 :BC47CA80:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14392 pso_num=25 pso_serial#=193 2025-08-06 21:41:09.932530 :BC47CA81:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14392 (legacy spawn) 2025-08-06 21:41:09.939049 :BC47CA82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:41:09.939215 :BC47CA8B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:41:09.940030 :BC47CA8C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:41:13.010814 :BC47CB75:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:41:12.998 2025-08-06 21:41:13.010816 :BC47CB76:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11292 pso_num=25 pso_serial#=194 2025-08-06 21:41:13.010953 :BC47CB77:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11292 (legacy spawn) 2025-08-06 21:41:13.017432 :BC47CB78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:41:13.017607 :BC47CB7D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:41:13.018377 :BC47CB90:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:41:13.027017 :BC47CB93:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:41:13.013 2025-08-06 21:41:13.027019 :BC47CB94:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18832 pso_num=25 pso_serial#=195 2025-08-06 21:41:13.027149 :BC47CB95:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=18832 (legacy spawn) 2025-08-06 21:41:13.033681 :BC47CB96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:41:13.033842 :BC47CB9F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:41:13.034704 :BC47CBA0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:41:21.057139 :BC47CE16:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:41:21.054 2025-08-06 21:41:21.057141 :BC47CE17:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16544 pso_num=25 pso_serial#=196 2025-08-06 21:41:21.057275 :BC47CE18:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16544 (legacy spawn) 2025-08-06 21:41:21.063807 :BC47CE19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:41:21.063984 :BC47CE1E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:41:21.064808 :BC47CE23:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:41:23.678979 :BC47CF04:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:41:23.671 2025-08-06 21:41:23.678981 :BC47CF05:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17200 pso_num=25 pso_serial#=197 2025-08-06 21:41:23.679113 :BC47CF06:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17200 (legacy spawn) 2025-08-06 21:41:23.685530 :BC47CF07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:41:23.685706 :BC47CF0C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:41:23.686543 :BC47CF11:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:42:22.396153 :BC47E211:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:42:22.387 2025-08-06 21:42:22.396155 :BC47E212:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18812 pso_num=25 pso_serial#=198 2025-08-06 21:42:22.396297 :BC47E213:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=18812 (legacy spawn) 2025-08-06 21:42:22.402745 :BC47E214:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:42:22.402921 :BC47E219:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:42:22.403757 :BC47E21E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:42:22.412366 :BC47E221:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:42:22.402 2025-08-06 21:42:22.412368 :BC47E222:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19488 pso_num=25 pso_serial#=199 2025-08-06 21:42:22.412502 :BC47E223:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19488 (legacy spawn) 2025-08-06 21:42:22.419059 :BC47E224:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:42:22.419220 :BC47E22D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:42:22.420037 :BC47E22E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:42:25.582340 :BC47E32A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:42:25.569 2025-08-06 21:42:25.582342 :BC47E32B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16632 pso_num=25 pso_serial#=200 2025-08-06 21:42:25.582479 :BC47E32C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16632 (legacy spawn) 2025-08-06 21:42:25.589039 :BC47E32D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:42:25.589206 :BC47E332:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:42:25.590035 :BC47E337:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:42:25.598775 :BC47E33A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:42:25.584 2025-08-06 21:42:25.598777 :BC47E33B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20952 pso_num=25 pso_serial#=201 2025-08-06 21:42:25.598912 :BC47E33C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20952 (legacy spawn) 2025-08-06 21:42:25.605390 :BC47E33D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:42:25.605563 :BC47E346:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:42:25.606390 :BC47E347:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:42:25.842944 :BC47E34A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:42:25.834 2025-08-06 21:42:25.842946 :BC47E34B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20764 pso_num=25 pso_serial#=202 2025-08-06 21:42:25.843088 :BC47E34C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20764 (legacy spawn) 2025-08-06 21:42:25.849554 :BC47E34D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:42:25.849734 :BC47E356:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:42:25.850571 :BC47E357:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:42:29.242247 :BC47E46A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:42:29.233 2025-08-06 21:42:29.242249 :BC47E46B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20188 pso_num=25 pso_serial#=203 2025-08-06 21:42:29.242392 :BC47E46C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20188 (legacy spawn) 2025-08-06 21:42:29.248915 :BC47E46D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:42:29.249081 :BC47E472:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:42:29.249879 :BC47E477:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:43:01.838696 :BC47EEE9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:43:01.837 2025-08-06 21:43:01.838698 :BC47EEEA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19548 pso_num=25 pso_serial#=204 2025-08-06 21:43:01.838890 :BC47EEEB:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19548 (legacy spawn) 2025-08-06 21:43:01.845558 :BC47EEEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:43:01.845743 :BC47EEF1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:43:01.846541 :BC47EEF6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:43:01.855409 :BC47EEF9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:43:01.852 2025-08-06 21:43:01.855411 :BC47EEFA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19228 pso_num=25 pso_serial#=205 2025-08-06 21:43:01.855554 :BC47EEFB:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19228 (legacy spawn) 2025-08-06 21:43:01.862048 :BC47EEFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:43:01.862221 :BC47EF04:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:43:01.863059 :BC47EF06:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:43:02.592256 :BC47EF65:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:43:02.587 2025-08-06 21:43:02.592258 :BC47EF66:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20880 pso_num=25 pso_serial#=206 2025-08-06 21:43:02.592398 :BC47EF67:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20880 osp_idx=23 osp_ver=229563 osp_pg=0 (spawn #462442) 2025-08-06 21:43:02.592398 :BC47EF68:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 21:43:02.592399 :BC47EF69:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=89 time=3197251892 2025-08-06 21:43:02.592399 :BC47EF6A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 21:43:02.592399 :BC47EF6B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 21:43:02.592400 :BC47EF6C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 21:43:02.592401 :BC47EF6D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 21:43:02.592401 :BC47EF6E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 21:43:02.592905 :BC47EF6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-06 21:43:02.592952 :BC47EF75:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:43:02.612180 :BC47EF86:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:43:03.424028 :BC47EF95:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:43:03.415 2025-08-06 21:43:03.424030 :BC47EF96:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19212 pso_num=25 pso_serial#=207 2025-08-06 21:43:03.424171 :BC47EF97:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19212 (legacy spawn) 2025-08-06 21:43:03.430592 :BC47EF98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 21:43:03.430776 :BC47EF9D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-06 21:43:03.431610 :BC47EFA2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:43:24.779851 :BC47F640:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:43:24.774 2025-08-06 21:43:24.779853 :BC47F641:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21064 pso_num=25 pso_serial#=208 2025-08-06 21:43:24.779995 :BC47F642:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21064 osp_idx=23 osp_ver=229565 osp_pg=0 (spawn #462443) 2025-08-06 21:43:24.779995 :BC47F643:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 21:43:24.779996 :BC47F644:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=90 time=3197274079 2025-08-06 21:43:24.779996 :BC47F645:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 21:43:24.779997 :BC47F646:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 21:43:24.779997 :BC47F647:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 21:43:24.779998 :BC47F648:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 21:43:24.779998 :BC47F649:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 21:43:24.780493 :BC47F64A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-06 21:43:24.780542 :BC47F651:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-06 21:53:25.627713 :BC48BB17:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 21:53:26.070236 :BC48BB53:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 21:53:26.064 2025-08-06 21:53:26.070238 :BC48BB54:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14108 pso_num=25 pso_serial#=209 2025-08-06 21:53:26.070381 :BC48BB55:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14108 osp_idx=23 osp_ver=229566 osp_pg=0 (spawn #462465) 2025-08-06 21:53:26.070382 :BC48BB56:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 21:53:26.070382 :BC48BB57:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=91 time=3197875376 2025-08-06 21:53:26.070382 :BC48BB58:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 21:53:26.070383 :BC48BB59:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 21:53:26.070383 :BC48BB5A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 21:53:26.070384 :BC48BB5B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 21:53:26.070384 :BC48BB5C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 21:53:26.070908 :BC48BB5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-06 21:53:26.070958 :BC48BB65:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 14108, W000)