Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250917082641\orcl_m002_24424_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:35971M/63366M, Ph+PgF:40274M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 24424, image: ORACLE.EXE (M002) *** 2025-09-17 08:26:41.513 *** SESSION ID:(225.6079) 2025-09-17 08:26:41.513 *** 2025-09-17 08:26:41.513 Process diagnostic dump for ORACLE.EXE (M002), OS id=24424, pid: 28, proc_ser: 209, sid: 225, sess_ser: 6079 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=340 seq_num=341 snap_id=1 wait times: snap=0.000764 sec, exc=0.000764 sec, total=0.000764 sec wait times: max=infinite, heur=0.208965 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.000018 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=339 seq_num=340 snap_id=1 wait times: snap=0.005380 sec, exc=0.005380 sec, total=0.005380 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=338 seq_num=339 snap_id=1 wait times: snap=0.000073 sec, exc=0.000073 sec, total=0.000073 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000016 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=337 seq_num=338 snap_id=1 wait times: snap=0.006220 sec, exc=0.006220 sec, total=0.006220 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000019 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=336 seq_num=337 snap_id=1 wait times: snap=0.005663 sec, exc=0.005663 sec, total=0.005663 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000017 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=335 seq_num=336 snap_id=1 wait times: snap=0.004242 sec, exc=0.004242 sec, total=0.004242 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000010 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=334 seq_num=335 snap_id=1 wait times: snap=0.000792 sec, exc=0.000792 sec, total=0.000792 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000019 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=333 seq_num=334 snap_id=1 wait times: snap=0.005839 sec, exc=0.005839 sec, total=0.005839 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000018 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=332 seq_num=333 snap_id=1 wait times: snap=0.004669 sec, exc=0.004669 sec, total=0.004669 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000023 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=331 seq_num=332 snap_id=1 wait times: snap=0.008776 sec, exc=0.008776 sec, total=0.008776 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000016 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=330 seq_num=331 snap_id=1 wait times: snap=0.001932 sec, exc=0.001932 sec, total=0.001932 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000017 sec of elapsed time Sampled Session History of session 225 serial 6079 --------------------------------------------------- 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:26:41] waited for 'ADR block file read', seq_num: 258 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:26:40] idle wait at each sample [session created at: 08:26:41] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:26:41 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-17 08:26:41.513 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 24424, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-17 07:00:21.308230 :CE87DE14:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 07:00:21.308230 :CE87DE15:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 07:00:21.308798 :CE87DE16: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-09-17 07:00:21.308848 :CE87DE1C: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-09-17 07:00:21.317406 :CE87DE2E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 07:00:22.492986 :CE87DE8D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 07:00:22.478 2025-09-17 07:00:22.492988 :CE87DE8E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24956 pso_num=28 pso_serial#=200 2025-09-17 07:00:22.493121 :CE87DE8F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24956 (legacy spawn) 2025-09-17 07:00:22.499683 :CE87DE90: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-09-17 07:00:22.500036 :CE87DE99: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-09-17 07:00:22.500821 :CE87DE9A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 07:00:22.518167 :CE87DE9D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 07:00:22.510 2025-09-17 07:00:22.518169 :CE87DE9E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25060 pso_num=28 pso_serial#=201 2025-09-17 07:00:22.518304 :CE87DE9F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25060 (legacy spawn) 2025-09-17 07:00:22.525261 :CE87DEA0: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-09-17 07:00:22.525428 :CE87DEA9: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-09-17 07:00:22.526467 :CE87DEAA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 07:00:22.672114 :CE87DEF8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 07:00:22.666 2025-09-17 07:00:22.672116 :CE87DEF9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24856 pso_num=28 pso_serial#=202 2025-09-17 07:00:22.672243 :CE87DEFA:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24856 osp_idx=26 osp_ver=330475 osp_pg=0 (spawn #600436) 2025-09-17 07:00:22.672243 :CE87DEFB:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 07:00:22.672244 :CE87DEFC:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=139 time=2478073955 2025-09-17 07:00:22.672244 :CE87DEFD:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 07:00:22.672244 :CE87DEFE:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 07:00:22.672244 :CE87DEFF:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 07:00:22.672245 :CE87DF00:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 07:00:22.672245 :CE87DF01:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 07:00:22.672771 :CE87DF02: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-09-17 07:00:22.672819 :CE87DF08: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-09-17 07:00:22.680134 :CE87DF16: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-09-17 07:00:22.681215 :CE87DF1E: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-09-17 07:00:22.681800 :CE87DF26: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-09-17 07:00:22.682252 :CE87DF2E: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-09-17 07:00:22.682683 :CE87DF36: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-09-17 07:00:22.685437 :CE87DF3E: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-09-17 07:00:22.686700 :CE87DF46: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-09-17 07:00:22.738295 :CE87DF76: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-09-17 07:00:22.739163 :CE87DF7E: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-09-17 07:00:22.739405 :CE87DF86: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-09-17 07:00:22.739647 :CE87DF8E: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-09-17 07:00:22.741533 :CE87DF96:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 07:05:21.951084 :CE88426C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 07:05:21.937 2025-09-17 07:05:21.951087 :CE88426D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25284 pso_num=28 pso_serial#=203 2025-09-17 07:05:21.951253 :CE88426E:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=25284 osp_idx=26 osp_ver=330476 osp_pg=0 (spawn #600448) 2025-09-17 07:05:21.951254 :CE88426F:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 07:05:21.951255 :CE884270:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=140 time=2478373252 2025-09-17 07:05:21.951255 :CE884271:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 07:05:21.951256 :CE884272:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 07:05:21.951257 :CE884273:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 07:05:21.951257 :CE884274:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 07:05:21.951258 :CE884275:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 07:05:21.951990 :CE884276: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-09-17 07:05:21.952030 :CE88427C: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-09-17 07:05:21.956296 :CE88429D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:05:21.956345 :CE88429E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:05:21.956618 :CE88429F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:05:21.956634 :CE8842A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:05:21.956856 :CE8842A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:05:21.956869 :CE8842A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:05:21.957077 :CE8842A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:05:21.957091 :CE8842A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:05:21.957302 :CE8842A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:05:21.957316 :CE8842A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:05:21.957527 :CE8842A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:05:21.957540 :CE8842A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:05:21.961058 :CE8842A9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 07:35:25.618007 :CE8A94FF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 07:35:25.604 2025-09-17 07:35:25.618009 :CE8A9500:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23652 pso_num=28 pso_serial#=204 2025-09-17 07:35:25.618143 :CE8A9501:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=23652 osp_idx=26 osp_ver=330477 osp_pg=0 (spawn #600515) 2025-09-17 07:35:25.618144 :CE8A9502:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 07:35:25.618144 :CE8A9503:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=141 time=2480176908 2025-09-17 07:35:25.618144 :CE8A9504:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 07:35:25.618145 :CE8A9505:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 07:35:25.618145 :CE8A9506:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 07:35:25.618146 :CE8A9507:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 07:35:25.618146 :CE8A9508:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 07:35:25.618866 :CE8A9509: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-09-17 07:35:25.618908 :CE8A950F: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-09-17 07:35:25.623264 :CE8A9528:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:35:25.623319 :CE8A9529:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:35:25.623582 :CE8A952A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:35:25.623598 :CE8A952B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:35:25.623818 :CE8A952C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:35:25.623832 :CE8A952D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:35:25.624042 :CE8A952E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:35:25.624054 :CE8A952F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:35:25.624283 :CE8A9530:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:35:25.624295 :CE8A9531:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:35:25.624507 :CE8A9532:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 07:35:25.624520 :CE8A9533:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 07:35:25.628202 :CE8A9534:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 08:00:28.752584 :CE8C7E29:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 08:00:28.741 2025-09-17 08:00:28.752585 :CE8C7E2A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21936 pso_num=28 pso_serial#=205 2025-09-17 08:00:28.752717 :CE8C7E2B:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21936 osp_idx=26 osp_ver=330478 osp_pg=0 (spawn #600573) 2025-09-17 08:00:28.752718 :CE8C7E2C:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 08:00:28.752718 :CE8C7E2D:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=142 time=2481680049 2025-09-17 08:00:28.752718 :CE8C7E2E:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:00:28.752719 :CE8C7E2F:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:00:28.752719 :CE8C7E30:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:00:28.752720 :CE8C7E31:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:00:28.752720 :CE8C7E32:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:00:28.753277 :CE8C7E33: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-09-17 08:00:28.753338 :CE8C7E3A: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-09-17 08:00:28.761907 :CE8C7E4B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 08:00:30.164974 :CE8C7EE1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 08:00:30.163 2025-09-17 08:00:30.164976 :CE8C7EE2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25532 pso_num=28 pso_serial#=206 2025-09-17 08:00:30.165109 :CE8C7EE3:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=25532 osp_idx=26 osp_ver=330479 osp_pg=0 (spawn #600574) 2025-09-17 08:00:30.165110 :CE8C7EE4:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 08:00:30.165110 :CE8C7EE5:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=143 time=2481681455 2025-09-17 08:00:30.165110 :CE8C7EE6:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:00:30.165111 :CE8C7EE7:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:00:30.165112 :CE8C7EE8:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:00:30.165112 :CE8C7EE9:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:00:30.165112 :CE8C7EEA:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:00:30.165739 :CE8C7EEB: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-09-17 08:00:30.165783 :CE8C7EF1: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-09-17 08:00:30.173826 :CE8C7EFF: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-09-17 08:00:30.174919 :CE8C7F07: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-09-17 08:00:30.175484 :CE8C7F0F: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-09-17 08:00:30.175927 :CE8C7F17: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-09-17 08:00:30.176350 :CE8C7F1F: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-09-17 08:00:30.178937 :CE8C7F27: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-09-17 08:00:30.180013 :CE8C7F2F: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-09-17 08:00:30.231794 :CE8C7F37: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-09-17 08:00:30.232737 :CE8C7F3F: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-09-17 08:00:30.232984 :CE8C7F47: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-09-17 08:00:30.233202 :CE8C7F4F: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-09-17 08:00:30.235086 :CE8C7F57:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 08:05:29.328041 :CE8CE10A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 08:05:29.314 2025-09-17 08:05:29.328043 :CE8CE10B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24264 pso_num=28 pso_serial#=207 2025-09-17 08:05:29.328237 :CE8CE10C:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24264 osp_idx=26 osp_ver=330480 osp_pg=0 (spawn #600586) 2025-09-17 08:05:29.328238 :CE8CE10D:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 08:05:29.328238 :CE8CE10E:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=144 time=2481980627 2025-09-17 08:05:29.328240 :CE8CE10F:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:05:29.328241 :CE8CE110:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:05:29.328241 :CE8CE111:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:05:29.328242 :CE8CE112:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:05:29.328242 :CE8CE113:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:05:29.328891 :CE8CE114: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-09-17 08:05:29.328929 :CE8CE11A: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-09-17 08:05:29.333154 :CE8CE143:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 08:05:29.333193 :CE8CE144:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 08:05:29.333459 :CE8CE145:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 08:05:29.333475 :CE8CE146:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 08:05:29.333692 :CE8CE147:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 08:05:29.333705 :CE8CE148:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 08:05:29.333918 :CE8CE149:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 08:05:29.333930 :CE8CE14A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 08:05:29.334133 :CE8CE14B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 08:05:29.334145 :CE8CE14C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 08:05:29.334360 :CE8CE14D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-17 08:05:29.334373 :CE8CE14E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-17 08:05:29.337816 :CE8CE14F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 08:26:38.800968 :CE8EBF0C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 08:26:38.794 2025-09-17 08:26:38.800970 :CE8EBF0D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24100 pso_num=28 pso_serial#=208 2025-09-17 08:26:38.801452 :CE8EBF0E:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24100 osp_idx=26 osp_ver=330481 osp_pg=0 (spawn #600635) 2025-09-17 08:26:38.801453 :CE8EBF0F:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 08:26:38.801453 :CE8EBF10:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=145 time=2483250080 2025-09-17 08:26:38.801454 :CE8EBF11:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:26:38.801454 :CE8EBF12:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:26:38.801455 :CE8EBF13:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:26:38.801455 :CE8EBF14:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:26:38.801455 :CE8EBF15:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:26:38.802861 :CE8EBF16: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-09-17 08:26:38.802909 :CE8EBF1C: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-09-17 08:26:38.956837 :CE8EBF4E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-17 08:26:41.168931 :CE8EC076:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-17 08:26:41.169 2025-09-17 08:26:41.168932 :CE8EC077:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24424 pso_num=28 pso_serial#=209 2025-09-17 08:26:41.169075 :CE8EC078:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24424 osp_idx=26 osp_ver=330482 osp_pg=0 (spawn #600637) 2025-09-17 08:26:41.169077 :CE8EC079:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 08:26:41.169078 :CE8EC07A:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=146 time=2483252455 2025-09-17 08:26:41.169078 :CE8EC07B:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:26:41.169079 :CE8EC07C:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:26:41.169079 :CE8EC07D:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:26:41.169080 :CE8EC07E:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:26:41.169080 :CE8EC07F:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:26:41.169786 :CE8EC080: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-09-17 08:26:41.169830 :CE8EC086: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: 24424, M002)