Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250825081341\orcl_m002_21564_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:35219M/63366M, Ph+PgF:40300M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 21564, image: ORACLE.EXE (M002) *** 2025-08-25 08:13:41.916 *** SESSION ID:(225.5589) 2025-08-25 08:13:41.916 *** 2025-08-25 08:13:41.916 Process diagnostic dump for ORACLE.EXE (M002), OS id=21564, pid: 28, proc_ser: 1, sid: 225, sess_ser: 5589 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.000114 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000139 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x410a, blocks=0x1 wait_id=1481 seq_num=1482 snap_id=1 wait times: snap=0.000134 sec, exc=0.000134 sec, total=0.000134 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000360 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x40ea, blocks=0x1 wait_id=1480 seq_num=1481 snap_id=1 wait times: snap=0.005270 sec, exc=0.005270 sec, total=0.005270 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000159 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x40ca, blocks=0x1 wait_id=1479 seq_num=1480 snap_id=1 wait times: snap=0.000133 sec, exc=0.000133 sec, total=0.000133 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000224 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x40aa, blocks=0x1 wait_id=1478 seq_num=1479 snap_id=1 wait times: snap=0.001896 sec, exc=0.001896 sec, total=0.001896 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000138 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x4072, blocks=0x1 wait_id=1477 seq_num=1478 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.000140 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x4052, blocks=0x1 wait_id=1476 seq_num=1477 snap_id=1 wait times: snap=0.000083 sec, exc=0.000083 sec, total=0.000083 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000189 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x4032, blocks=0x1 wait_id=1475 seq_num=1476 snap_id=1 wait times: snap=0.001332 sec, exc=0.001332 sec, total=0.001332 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000131 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x4022, blocks=0x1 wait_id=1474 seq_num=1475 snap_id=1 wait times: snap=0.000094 sec, exc=0.000094 sec, total=0.000094 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000133 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x4002, blocks=0x1 wait_id=1473 seq_num=1474 snap_id=1 wait times: snap=0.000098 sec, exc=0.000098 sec, total=0.000098 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000145 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x3fea, blocks=0x1 wait_id=1472 seq_num=1473 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000152 sec of elapsed time Sampled Session History of session 225 serial 5589 --------------------------------------------------- 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:13:41] waited for 'ADR block file read', seq_num: 458 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:13:40] idle wait at each sample [session created at: 08:13:41] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:13:41 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-25 08:13:41.916 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 21564, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-25 05:48:16.872478 :C454ABB3: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-25 05:48:16.872655 :C454ABB8: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-25 05:48:16.873444 :C454ABBD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:48:16.882319 :C454ABC0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:48:16.868 2025-08-25 05:48:16.882321 :C454ABC1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22328 pso_num=28 pso_serial#=230 2025-08-25 05:48:16.882460 :C454ABC2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22328 (legacy spawn) 2025-08-25 05:48:16.888923 :C454ABC3: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-25 05:48:16.889088 :C454ABCC: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-25 05:48:16.889902 :C454ABCD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:48:23.085332 :C454ADBA:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:48:23.076 2025-08-25 05:48:23.085334 :C454ADBB:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20960 pso_num=28 pso_serial#=231 2025-08-25 05:48:23.085476 :C454ADBC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20960 (legacy spawn) 2025-08-25 05:48:23.091953 :C454ADBD: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-25 05:48:23.092127 :C454ADC6: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-25 05:48:23.092929 :C454ADC7:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:48:34.431144 :C454B112:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:48:34.422 2025-08-25 05:48:34.431146 :C454B113:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16832 pso_num=28 pso_serial#=232 2025-08-25 05:48:34.431328 :C454B114:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16832 (legacy spawn) 2025-08-25 05:48:34.455125 :C454B115: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-25 05:48:34.455321 :C454B11A: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-25 05:48:34.456299 :C454B11F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:49:26.153134 :C454C239:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:49:26.150 2025-08-25 05:49:26.153136 :C454C23A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19672 pso_num=28 pso_serial#=233 2025-08-25 05:49:26.153274 :C454C23B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19672 (legacy spawn) 2025-08-25 05:49:26.159691 :C454C23C: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-25 05:49:26.159875 :C454C241: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-25 05:49:26.160668 :C454C246:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:49:26.188604 :C454C25C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:49:26.181 2025-08-25 05:49:26.188606 :C454C25D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22628 pso_num=28 pso_serial#=234 2025-08-25 05:49:26.188738 :C454C25E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22628 (legacy spawn) 2025-08-25 05:49:26.195239 :C454C25F: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-25 05:49:26.195403 :C454C268: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-25 05:49:26.196196 :C454C269:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:49:29.246377 :C454C35D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:49:29.233 2025-08-25 05:49:29.246379 :C454C35E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=1388 pso_num=28 pso_serial#=235 2025-08-25 05:49:29.246523 :C454C35F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=1388 (legacy spawn) 2025-08-25 05:49:29.253199 :C454C360: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-25 05:49:29.253370 :C454C369: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-25 05:49:29.254180 :C454C36A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:49:29.262979 :C454C36D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:49:29.248 2025-08-25 05:49:29.262981 :C454C36E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23284 pso_num=28 pso_serial#=236 2025-08-25 05:49:29.263116 :C454C36F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23284 (legacy spawn) 2025-08-25 05:49:29.269647 :C454C370: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-25 05:49:29.269816 :C454C379: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-25 05:49:29.270654 :C454C37A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:49:31.535398 :C454C42C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:49:31.521 2025-08-25 05:49:31.535400 :C454C42D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22920 pso_num=28 pso_serial#=237 2025-08-25 05:49:31.535537 :C454C42E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22920 (legacy spawn) 2025-08-25 05:49:31.542881 :C454C42F: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-25 05:49:31.543098 :C454C434: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-25 05:49:31.544161 :C454C439:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:50:38.853878 :C454D975:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:50:38.850 2025-08-25 05:50:38.853881 :C454D976:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19816 pso_num=28 pso_serial#=238 2025-08-25 05:50:38.854020 :C454D977:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19816 (legacy spawn) 2025-08-25 05:50:38.861131 :C454D978: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-25 05:50:38.861299 :C454D981: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-25 05:50:38.862188 :C454D982:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:50:38.864372 :C454D985:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:50:38.850 2025-08-25 05:50:38.864374 :C454D986:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21068 pso_num=28 pso_serial#=239 2025-08-25 05:50:38.864510 :C454D987:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21068 (legacy spawn) 2025-08-25 05:50:38.885972 :C454D988: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-25 05:50:38.886178 :C454D98D: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-25 05:50:38.887097 :C454D992:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:50:38.890883 :C454D995:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:50:38.881 2025-08-25 05:50:38.890885 :C454D996:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23128 pso_num=28 pso_serial#=240 2025-08-25 05:50:38.891022 :C454D997:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23128 (legacy spawn) 2025-08-25 05:50:38.897918 :C454D998: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-25 05:50:38.898100 :C454D9A1: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-25 05:50:38.899087 :C454D9A2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:50:39.499836 :C454D9CB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:50:39.490 2025-08-25 05:50:39.499838 :C454D9CC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21576 pso_num=28 pso_serial#=241 2025-08-25 05:50:39.499970 :C454D9CD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21576 (legacy spawn) 2025-08-25 05:50:39.506628 :C454D9CE: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-25 05:50:39.506805 :C454D9D7: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-25 05:50:39.507668 :C454D9D8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:50:41.956249 :C454DA91:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:50:41.943 2025-08-25 05:50:41.956251 :C454DA92:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21324 pso_num=28 pso_serial#=242 2025-08-25 05:50:41.956392 :C454DA93:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21324 (legacy spawn) 2025-08-25 05:50:41.962953 :C454DAA1: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-25 05:50:41.963118 :C454DAAA: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-25 05:50:41.963919 :C454DAAB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:50:41.972626 :C454DAAE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:50:41.959 2025-08-25 05:50:41.972629 :C454DAAF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8240 pso_num=28 pso_serial#=243 2025-08-25 05:50:41.972776 :C454DAB0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8240 (legacy spawn) 2025-08-25 05:50:41.979344 :C454DAB1: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-25 05:50:41.979506 :C454DABA: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-25 05:50:41.990621 :C454DABB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:51:13.902567 :C454E4B9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:51:13.887 2025-08-25 05:51:13.902569 :C454E4BA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=4428 pso_num=28 pso_serial#=244 2025-08-25 05:51:13.902706 :C454E4BB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=4428 (legacy spawn) 2025-08-25 05:51:13.909845 :C454E4BC: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-25 05:51:13.910024 :C454E4C1: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-25 05:51:13.910954 :C454E4C6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:51:15.059472 :C454E53E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:51:15.044 2025-08-25 05:51:15.059474 :C454E53F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19064 pso_num=28 pso_serial#=245 2025-08-25 05:51:15.059640 :C454E540:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19064 (legacy spawn) 2025-08-25 05:51:15.066490 :C454E541: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-25 05:51:15.066672 :C454E546: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-25 05:51:15.067537 :C454E54B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:51:15.076816 :C454E54E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:51:15.075 2025-08-25 05:51:15.076818 :C454E54F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16724 pso_num=28 pso_serial#=246 2025-08-25 05:51:15.076968 :C454E550:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16724 (legacy spawn) 2025-08-25 05:51:15.083749 :C454E551: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-25 05:51:15.083921 :C454E55A: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-25 05:51:15.084865 :C454E55B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:51:18.464674 :C454E664:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:51:18.452 2025-08-25 05:51:18.464675 :C454E665:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17932 pso_num=28 pso_serial#=247 2025-08-25 05:51:18.464821 :C454E666:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17932 (legacy spawn) 2025-08-25 05:51:18.471319 :C454E667: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-25 05:51:18.471481 :C454E670: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-25 05:51:18.472261 :C454E671:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:51:18.480989 :C454E674:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:51:18.467 2025-08-25 05:51:18.480991 :C454E675:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22676 pso_num=28 pso_serial#=248 2025-08-25 05:51:18.481127 :C454E676:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22676 (legacy spawn) 2025-08-25 05:51:18.487759 :C454E677: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-25 05:51:18.487923 :C454E680: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-25 05:51:18.488745 :C454E681:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:51:41.034554 :C454ED0B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:51:41.026 2025-08-25 05:51:41.034556 :C454ED0C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22296 pso_num=28 pso_serial#=249 2025-08-25 05:51:41.034734 :C454ED0D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22296 (legacy spawn) 2025-08-25 05:51:41.041324 :C454ED0E: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-25 05:51:41.041673 :C454ED13: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-25 05:51:41.042502 :C454ED18:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:52:12.124033 :C454F769:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:52:12.110 2025-08-25 05:52:12.124034 :C454F76A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14268 pso_num=28 pso_serial#=250 2025-08-25 05:52:12.124184 :C454F76B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14268 (legacy spawn) 2025-08-25 05:52:12.131446 :C454F778: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-25 05:52:12.131635 :C454F780: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-25 05:52:12.132530 :C454F782:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:52:13.133465 :C454F8B0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:52:13.126 2025-08-25 05:52:13.133467 :C454F8B1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21984 pso_num=28 pso_serial#=251 2025-08-25 05:52:13.133607 :C454F8B2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21984 (legacy spawn) 2025-08-25 05:52:13.140072 :C454F8B3: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-25 05:52:13.140243 :C454F8BB: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-25 05:52:13.141053 :C454F8BD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 05:52:22.799037 :C454FB95:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 05:52:22.790 2025-08-25 05:52:22.799039 :C454FB96:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23204 pso_num=28 pso_serial#=252 2025-08-25 05:52:22.799183 :C454FB97:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23204 (legacy spawn) 2025-08-25 05:52:22.805660 :C454FB98: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-25 05:52:22.805828 :C454FB9D: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-25 05:52:22.806629 :C454FBA2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 08:13:37.848057 :C45FFF6A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 08:13:37.834 2025-08-25 08:13:37.848059 :C45FFF6B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23416 pso_num=28 pso_serial#=253 2025-08-25 08:13:37.848201 :C45FFF6C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23416 (legacy spawn) 2025-08-25 08:13:37.854817 :C45FFF75: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-25 08:13:37.855003 :C45FFF7E: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-25 08:13:37.855798 :C45FFF7F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 08:13:37.864782 :C45FFF82:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 08:13:37.849 2025-08-25 08:13:37.864784 :C45FFF83:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21892 pso_num=28 pso_serial#=254 2025-08-25 08:13:37.864922 :C45FFF84:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21892 (legacy spawn) 2025-08-25 08:13:37.871416 :C45FFF85: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-25 08:13:37.871751 :C45FFF8E: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-25 08:13:37.872560 :C45FFF8F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 08:13:39.185347 :C460017E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 08:13:39.172 2025-08-25 08:13:39.185349 :C460017F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22084 pso_num=28 pso_serial#=255 2025-08-25 08:13:39.185510 :C4600180:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=22084 osp_idx=26 osp_ver=264738 osp_pg=0 (spawn #523912) 2025-08-25 08:13:39.185511 :C4600181: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-25 08:13:39.185511 :C4600182:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=167 time=495273908 2025-08-25 08:13:39.185512 :C4600183:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 08:13:39.185513 :C4600184:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 08:13:39.185513 :C4600185:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 08:13:39.185513 :C4600186:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 08:13:39.185514 :C4600187:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 08:13:39.186378 :C4600188: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-25 08:13:39.186422 :C460018E: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-25 08:13:39.292883 :C46001C0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-25 08:13:41.383361 :C46002A4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-25 08:13:41.369 2025-08-25 08:13:41.383363 :C46002A5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21564 pso_num=28 pso_serial#=1 2025-08-25 08:13:41.383494 :C46002A6:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21564 osp_idx=26 osp_ver=264739 osp_pg=0 (spawn #523914) 2025-08-25 08:13:41.383495 :C46002A7: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-25 08:13:41.383496 :C46002A8:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=168 time=495276112 2025-08-25 08:13:41.383496 :C46002A9:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 08:13:41.383497 :C46002AA:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 08:13:41.383498 :C46002AB:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 08:13:41.383498 :C46002AC:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 08:13:41.383498 :C46002AD:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 08:13:41.384002 :C46002AE: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-25 08:13:41.384043 :C46002B4: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: 21564, M002)