Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250822081034\orcl_m002_18712_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:35303M/63366M, Ph+PgF:40457M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 18712, image: ORACLE.EXE (M002) *** 2025-08-22 08:10:34.674 *** SESSION ID:(225.40239) 2025-08-22 08:10:34.674 *** 2025-08-22 08:10:34.674 Process diagnostic dump for ORACLE.EXE (M002), OS id=18712, pid: 28, proc_ser: 121, sid: 225, sess_ser: 40239 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x1baa, blocks=0x1 wait_id=1199 seq_num=1200 snap_id=1 wait times: snap=0.005812 sec, exc=0.005812 sec, total=0.005812 sec wait times: max=infinite, heur=0.005812 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000320 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x1d2a, blocks=0x1 wait_id=1198 seq_num=1199 snap_id=1 wait times: snap=0.003308 sec, exc=0.003308 sec, total=0.003308 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000337 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x1b2a, blocks=0x1 wait_id=1197 seq_num=1198 snap_id=1 wait times: snap=0.005926 sec, exc=0.005926 sec, total=0.005926 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000174 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x7ca, blocks=0x1 wait_id=1196 seq_num=1197 snap_id=1 wait times: snap=0.000128 sec, exc=0.000128 sec, total=0.000128 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000319 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x7d2, blocks=0x1 wait_id=1195 seq_num=1196 snap_id=1 wait times: snap=0.003879 sec, exc=0.003879 sec, total=0.003879 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000136 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x6b2, blocks=0x1 wait_id=1194 seq_num=1195 snap_id=1 wait times: snap=0.000117 sec, exc=0.000117 sec, total=0.000117 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000135 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x6a2, blocks=0x1 wait_id=1193 seq_num=1194 snap_id=1 wait times: snap=0.000116 sec, exc=0.000116 sec, total=0.000116 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000134 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x692, blocks=0x1 wait_id=1192 seq_num=1193 snap_id=1 wait times: snap=0.000123 sec, exc=0.000123 sec, total=0.000123 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000320 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x642, blocks=0x1 wait_id=1191 seq_num=1192 snap_id=1 wait times: snap=0.000132 sec, exc=0.000132 sec, total=0.000132 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000163 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x632, blocks=0x1 wait_id=1190 seq_num=1191 snap_id=1 wait times: snap=0.000102 sec, exc=0.000102 sec, total=0.000102 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000325 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x1baa, blocks=0x1 wait_id=1199 seq_num=1200 snap_id=1 wait times: snap=0.005924 sec, exc=0.005924 sec, total=0.005924 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000320 sec of elapsed time Sampled Session History of session 225 serial 40239 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 08:10:34] idle wait at each sample [session created at: 08:10:34] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-22 08:10:34.674 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 18712, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-22 01:56:50.603790 :C2EED4EF: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-08-22 01:56:50.604603 :C2EED4F0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:56:53.682063 :C2EED5D4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:56:53.669 2025-08-22 01:56:53.682065 :C2EED5D5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20888 pso_num=28 pso_serial#=98 2025-08-22 01:56:53.682207 :C2EED5D6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20888 (legacy spawn) 2025-08-22 01:56:53.688742 :C2EED5D7: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-08-22 01:56:53.688919 :C2EED5E0: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-08-22 01:56:53.689730 :C2EED5E1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:56:53.698451 :C2EED5E4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:56:53.684 2025-08-22 01:56:53.698453 :C2EED5E5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17352 pso_num=28 pso_serial#=99 2025-08-22 01:56:53.698588 :C2EED5E6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17352 (legacy spawn) 2025-08-22 01:56:53.705063 :C2EED5E7: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-08-22 01:56:53.705221 :C2EED5F0: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-08-22 01:56:53.706024 :C2EED5F1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:56:54.777988 :C2EED63C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:56:54.778 2025-08-22 01:56:54.777990 :C2EED63D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20996 pso_num=28 pso_serial#=100 2025-08-22 01:56:54.778132 :C2EED63E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20996 (legacy spawn) 2025-08-22 01:56:54.784655 :C2EED63F: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-08-22 01:56:54.784821 :C2EED644: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-08-22 01:56:54.785619 :C2EED649:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:57:47.259605 :C2EEE70F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:57:47.251 2025-08-22 01:57:47.259607 :C2EEE710:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22428 pso_num=28 pso_serial#=101 2025-08-22 01:57:47.259747 :C2EEE711:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22428 (legacy spawn) 2025-08-22 01:57:47.266257 :C2EEE712: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-08-22 01:57:47.266437 :C2EEE71B: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-08-22 01:57:47.267320 :C2EEE71C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:58:02.639333 :C2EEEBE0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:58:02.630 2025-08-22 01:58:02.639335 :C2EEEBE1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19800 pso_num=28 pso_serial#=102 2025-08-22 01:58:02.639471 :C2EEEBE2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19800 (legacy spawn) 2025-08-22 01:58:02.646245 :C2EEEBE3: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-08-22 01:58:02.646446 :C2EEEBEB: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-08-22 01:58:02.647353 :C2EEEBED:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:58:03.286733 :C2EEEC11:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:58:03.272 2025-08-22 01:58:03.286735 :C2EEEC12:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20404 pso_num=28 pso_serial#=103 2025-08-22 01:58:03.286873 :C2EEEC13:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20404 (legacy spawn) 2025-08-22 01:58:03.293934 :C2EEEC14: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-08-22 01:58:03.294104 :C2EEEC1D: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-08-22 01:58:03.295161 :C2EEEC1E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:58:03.304252 :C2EEEC21:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:58:03.303 2025-08-22 01:58:03.304254 :C2EEEC22:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16832 pso_num=28 pso_serial#=104 2025-08-22 01:58:03.304399 :C2EEEC23:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16832 (legacy spawn) 2025-08-22 01:58:03.311252 :C2EEEC24: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-08-22 01:58:03.311424 :C2EEEC2D: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-08-22 01:58:03.312276 :C2EEEC2E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:58:06.381067 :C2EEED1F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:58:06.381 2025-08-22 01:58:06.381069 :C2EEED20:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13880 pso_num=28 pso_serial#=105 2025-08-22 01:58:06.381210 :C2EEED21:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13880 (legacy spawn) 2025-08-22 01:58:06.395573 :C2EEED22: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-08-22 01:58:06.395750 :C2EEED27: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-08-22 01:58:06.396644 :C2EEED2C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:58:06.405671 :C2EEED2F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:58:06.394 2025-08-22 01:58:06.405673 :C2EEED30:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22096 pso_num=28 pso_serial#=106 2025-08-22 01:58:06.405823 :C2EEED31:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22096 (legacy spawn) 2025-08-22 01:58:06.412416 :C2EEED32: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-08-22 01:58:06.412585 :C2EEED3B: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-08-22 01:58:06.413401 :C2EEED3C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:58:42.702706 :C2EEF912:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:58:42.689 2025-08-22 01:58:42.702709 :C2EEF913:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19392 pso_num=28 pso_serial#=107 2025-08-22 01:58:42.702851 :C2EEF914:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19392 (legacy spawn) 2025-08-22 01:58:42.709382 :C2EEF915: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-08-22 01:58:42.709552 :C2EEF91E: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-08-22 01:58:42.710346 :C2EEF91F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:58:42.719054 :C2EEF922:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:58:42.705 2025-08-22 01:58:42.719055 :C2EEF923:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20324 pso_num=28 pso_serial#=108 2025-08-22 01:58:42.719194 :C2EEF924:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20324 (legacy spawn) 2025-08-22 01:58:42.725702 :C2EEF925: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-08-22 01:58:42.725870 :C2EEF92E: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-08-22 01:58:42.726654 :C2EEF92F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:58:49.402891 :C2EEFAFC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:58:49.395 2025-08-22 01:58:49.402893 :C2EEFAFD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21844 pso_num=28 pso_serial#=109 2025-08-22 01:58:49.403030 :C2EEFAFE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21844 (legacy spawn) 2025-08-22 01:58:49.409474 :C2EEFAFF: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-08-22 01:58:49.409654 :C2EEFB04: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-08-22 01:58:49.410477 :C2EEFB09:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:59:44.940472 :C2EF0D84:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:59:44.931 2025-08-22 01:59:44.940474 :C2EF0D85:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22344 pso_num=28 pso_serial#=110 2025-08-22 01:59:44.940613 :C2EF0D86:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22344 (legacy spawn) 2025-08-22 01:59:44.947214 :C2EF0D87: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-08-22 01:59:44.947375 :C2EF0D90: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-08-22 01:59:44.948185 :C2EF0D91:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:59:49.081620 :C2EF0EBC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:59:49.069 2025-08-22 01:59:49.081622 :C2EF0EBD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13240 pso_num=28 pso_serial#=111 2025-08-22 01:59:49.081761 :C2EF0EBE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13240 (legacy spawn) 2025-08-22 01:59:49.088264 :C2EF0EBF: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-08-22 01:59:49.088437 :C2EF0EC4: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-08-22 01:59:49.089247 :C2EF0EC9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:59:49.097815 :C2EF0ECC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:59:49.084 2025-08-22 01:59:49.097817 :C2EF0ECD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22280 pso_num=28 pso_serial#=112 2025-08-22 01:59:49.097948 :C2EF0ECE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22280 (legacy spawn) 2025-08-22 01:59:49.104456 :C2EF0ECF: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-08-22 01:59:49.104627 :C2EF0ED8: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-08-22 01:59:49.105465 :C2EF0ED9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:59:51.623927 :C2EF0F96:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:59:51.616 2025-08-22 01:59:51.623929 :C2EF0F97:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21992 pso_num=28 pso_serial#=113 2025-08-22 01:59:51.624075 :C2EF0F98:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21992 (legacy spawn) 2025-08-22 01:59:51.630640 :C2EF0F99: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-08-22 01:59:51.647405 :C2EF0F9E: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-08-22 01:59:51.648346 :C2EF0FA3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:59:52.298958 :C2EF0FE0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:59:52.288 2025-08-22 01:59:52.298961 :C2EF0FE1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21628 pso_num=28 pso_serial#=114 2025-08-22 01:59:52.299102 :C2EF0FE2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21628 (legacy spawn) 2025-08-22 01:59:52.305748 :C2EF0FE3: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-08-22 01:59:52.305926 :C2EF0FEC: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-08-22 01:59:52.306715 :C2EF0FED:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:59:52.315481 :C2EF0FF0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:59:52.303 2025-08-22 01:59:52.315483 :C2EF0FF1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21424 pso_num=28 pso_serial#=115 2025-08-22 01:59:52.315618 :C2EF0FF2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21424 (legacy spawn) 2025-08-22 01:59:52.322183 :C2EF0FF3: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-08-22 01:59:52.322346 :C2EF0FFC: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-08-22 01:59:52.323143 :C2EF0FFD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:59:55.395241 :C2EF10EF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:59:55.381 2025-08-22 01:59:55.395243 :C2EF10F0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22372 pso_num=28 pso_serial#=116 2025-08-22 01:59:55.395376 :C2EF10F1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22372 (legacy spawn) 2025-08-22 01:59:55.401900 :C2EF10F2: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-08-22 01:59:55.402075 :C2EF10F7: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-08-22 01:59:55.402881 :C2EF10FC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 01:59:55.411531 :C2EF10FF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 01:59:55.397 2025-08-22 01:59:55.411533 :C2EF1100:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21936 pso_num=28 pso_serial#=117 2025-08-22 01:59:55.411671 :C2EF1101:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21936 (legacy spawn) 2025-08-22 01:59:55.418169 :C2EF1102: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-08-22 01:59:55.418337 :C2EF110B: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-08-22 01:59:55.419158 :C2EF110C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 02:00:00.024930 :C2EF12F3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 02:00:00.015 2025-08-22 02:00:00.024932 :C2EF12F4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20856 pso_num=28 pso_serial#=118 2025-08-22 02:00:00.025082 :C2EF12F5:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=20856 osp_idx=26 osp_ver=249301 osp_pg=0 (spawn #513036) 2025-08-22 02:00:00.025082 :C2EF12F6:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-22 02:00:00.025083 :C2EF12F7:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=105 time=213702033 2025-08-22 02:00:00.025083 :C2EF12F8:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-22 02:00:00.025084 :C2EF12F9:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-22 02:00:00.025084 :C2EF12FA:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-22 02:00:00.025084 :C2EF12FB:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-22 02:00:00.025084 :C2EF12FC:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-22 02:00:00.025588 :C2EF12FD: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-08-22 02:00:00.025633 :C2EF1304: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-08-22 02:00:00.025710 :C2EF1318:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-22 02:00:00.029690 :C2EF1327: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-08-22 02:00:00.084932 :C2EF133F: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-08-22 02:00:00.086244 :C2EF1347: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-08-22 02:00:00.087706 :C2EF134F: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-08-22 02:00:00.088324 :C2EF1357: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-08-22 02:00:00.088343 :C2EF135A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-22 02:00:00.088382 :C2EF1365:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-22 02:00:00.089003 :C2EF1370: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-08-22 02:00:00.089016 :C2EF1373:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-22 02:00:00.089039 :C2EF137E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-22 02:00:00.089844 :C2EF1389: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-08-22 02:00:00.089858 :C2EF138C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-22 02:00:00.089898 :C2EF1398:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-22 02:00:00.091101 :C2EF13A2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 08:10:29.518679 :C30C2A92:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 08:10:29.509 2025-08-22 08:10:29.518681 :C30C2A93:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21676 pso_num=28 pso_serial#=119 2025-08-22 08:10:29.518823 :C30C2A94:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21676 (legacy spawn) 2025-08-22 08:10:29.525266 :C30C2A95: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-08-22 08:10:29.525546 :C30C2A9D: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-08-22 08:10:29.526359 :C30C2A9F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 08:10:32.082126 :C30C2F97:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 08:10:32.080 2025-08-22 08:10:32.082127 :C30C2F98:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22428 pso_num=28 pso_serial#=120 2025-08-22 08:10:32.082282 :C30C2FA1:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=22428 osp_idx=26 osp_ver=249303 osp_pg=0 (spawn #513894) 2025-08-22 08:10:32.082283 :C30C2FA2:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-22 08:10:32.082283 :C30C2FA3:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=106 time=235934080 2025-08-22 08:10:32.082284 :C30C2FA4:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-22 08:10:32.082284 :C30C2FA5:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-22 08:10:32.082284 :C30C2FA6:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-22 08:10:32.082285 :C30C2FA7:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-22 08:10:32.082285 :C30C2FA8:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-22 08:10:32.083787 :C30C2FE9: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-08-22 08:10:32.083826 :C30C2FEF: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-08-22 08:10:32.183411 :C30C32BD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-22 08:10:34.371103 :C30C33B6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-22 08:10:34.361 2025-08-22 08:10:34.371105 :C30C33B7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18712 pso_num=28 pso_serial#=121 2025-08-22 08:10:34.371233 :C30C33B8:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=18712 osp_idx=26 osp_ver=249304 osp_pg=0 (spawn #513896) 2025-08-22 08:10:34.371234 :C30C33B9:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-22 08:10:34.371234 :C30C33BA:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=107 time=235936377 2025-08-22 08:10:34.371235 :C30C33BB:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-22 08:10:34.371235 :C30C33BC:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-22 08:10:34.371236 :C30C33BD:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-22 08:10:34.371236 :C30C33BE:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-22 08:10:34.371236 :C30C33BF:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-22 08:10:34.371706 :C30C33C0: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-08-22 08:10:34.371750 :C30C33C6: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 18712, M002)