Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250507082248\orcl_m002_7316_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:37438M/63366M, Ph+PgF:44872M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 7316, image: ORACLE.EXE (M002) *** 2025-05-07 08:22:49.032 *** SESSION ID:(225.53269) 2025-05-07 08:22:49.032 *** 2025-05-07 08:22:49.032 Process diagnostic dump for ORACLE.EXE (M002), OS id=7316, pid: 28, proc_ser: 7, sid: 225, sess_ser: 53269 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.000063 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000089 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x333a, blocks=0x1 wait_id=1510 seq_num=1511 snap_id=1 wait times: snap=0.000717 sec, exc=0.000717 sec, total=0.000717 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#=0x327a, blocks=0x1 wait_id=1509 seq_num=1510 snap_id=1 wait times: snap=0.003678 sec, exc=0.003678 sec, total=0.003678 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000289 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x31ba, blocks=0x1 wait_id=1508 seq_num=1509 snap_id=1 wait times: snap=0.001074 sec, exc=0.001074 sec, total=0.001074 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000324 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x30fa, blocks=0x1 wait_id=1507 seq_num=1508 snap_id=1 wait times: snap=0.002711 sec, exc=0.002711 sec, total=0.002711 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000379 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x2aea, blocks=0x1 wait_id=1506 seq_num=1507 snap_id=1 wait times: snap=0.003525 sec, exc=0.003525 sec, total=0.003525 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000171 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x267a, blocks=0x1 wait_id=1505 seq_num=1506 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.000157 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x2672, blocks=0x1 wait_id=1504 seq_num=1505 snap_id=1 wait times: snap=0.000077 sec, exc=0.000077 sec, total=0.000077 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000340 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x265a, blocks=0x1 wait_id=1503 seq_num=1504 snap_id=1 wait times: snap=0.001877 sec, exc=0.001877 sec, total=0.001877 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000171 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x263a, blocks=0x1 wait_id=1502 seq_num=1503 snap_id=1 wait times: snap=0.000562 sec, exc=0.000562 sec, total=0.000562 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000174 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x261a, blocks=0x1 wait_id=1501 seq_num=1502 snap_id=1 wait times: snap=0.000890 sec, exc=0.000890 sec, total=0.000890 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000141 sec of elapsed time Sampled Session History of session 225 serial 53269 --------------------------------------------------- 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:22:49] idle wait at each sample [session created at: 08:22:48] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-07 08:22:49.032 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 7316, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-07 05:00:56.240142 :93C11A70: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-05-07 05:00:56.241800 :93C11A78:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-07 05:09:56.066520 :93C1D0DD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-07 05:09:56.054 2025-05-07 05:09:56.066522 :93C1D0DE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11280 pso_num=28 pso_serial#=255 2025-05-07 05:09:56.066653 :93C1D0DF:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=11280 osp_idx=26 osp_ver=92054 osp_pg=0 (spawn #156524) 2025-05-07 05:09:56.066653 :93C1D0E0:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 05:09:56.066653 :93C1D0E1:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=236 time=3865373218 2025-05-07 05:09:56.066654 :93C1D0E2:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 05:09:56.066654 :93C1D0E3:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 05:09:56.066655 :93C1D0E4:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 05:09:56.066655 :93C1D0E5:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 05:09:56.066655 :93C1D0E6:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 05:09:56.067171 :93C1D0E7: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-05-07 05:09:56.067211 :93C1D0ED: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-05-07 05:09:56.071210 :93C1D115:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:09:56.071249 :93C1D116:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:09:56.071501 :93C1D117:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:09:56.071517 :93C1D118:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:09:56.071727 :93C1D119:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:09:56.071740 :93C1D11A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:09:56.071944 :93C1D11B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:09:56.071957 :93C1D11C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:09:56.072160 :93C1D11D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:09:56.072173 :93C1D11E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:09:56.072371 :93C1D11F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:09:56.072384 :93C1D120:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:09:56.074715 :93C1D121:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-07 05:39:59.856051 :93C42262:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-07 05:39:59.843 2025-05-07 05:39:59.856053 :93C42263:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11596 pso_num=28 pso_serial#=1 2025-05-07 05:39:59.856204 :93C42264:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=11596 osp_idx=26 osp_ver=92055 osp_pg=0 (spawn #156592) 2025-05-07 05:39:59.856204 :93C42265:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 05:39:59.856205 :93C42266:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=237 time=3867177015 2025-05-07 05:39:59.856205 :93C42267:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 05:39:59.856206 :93C42268:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 05:39:59.856206 :93C42269:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 05:39:59.856206 :93C4226A:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 05:39:59.856207 :93C4226B:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 05:39:59.856730 :93C4226C: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-05-07 05:39:59.856772 :93C42272: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-05-07 05:39:59.860850 :93C4228B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:39:59.860899 :93C4228C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:39:59.861159 :93C4228D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:39:59.861176 :93C4228E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:39:59.861385 :93C4228F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:39:59.861398 :93C42290:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:39:59.861604 :93C42291:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:39:59.861617 :93C42292:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:39:59.861820 :93C42293:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:39:59.861832 :93C42294:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:39:59.862038 :93C42295:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 05:39:59.862050 :93C42296:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 05:39:59.864384 :93C42297:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-07 06:00:02.597981 :93C5AEEC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-07 06:00:02.587 2025-05-07 06:00:02.597982 :93C5AEED:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8612 pso_num=28 pso_serial#=2 2025-05-07 06:00:02.598110 :93C5AEEF:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=8612 osp_idx=26 osp_ver=92056 osp_pg=0 (spawn #156639) 2025-05-07 06:00:02.598111 :93C5AEF0:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 06:00:02.598111 :93C5AEF1:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=238 time=3868379765 2025-05-07 06:00:02.598112 :93C5AEF2:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 06:00:02.598112 :93C5AEF3:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 06:00:02.598112 :93C5AEF4:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 06:00:02.598113 :93C5AEF5:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 06:00:02.598113 :93C5AEF6:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 06:00:02.598610 :93C5AEFC: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-05-07 06:00:02.598660 :93C5AF03: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-05-07 06:00:02.604056 :93C5AF1C: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-05-07 06:00:02.627497 :93C5AF49: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-05-07 06:00:02.642140 :93C5AF51: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-05-07 06:00:02.666577 :93C5AF59: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-05-07 06:00:02.670556 :93C5AF61: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-05-07 06:00:02.674166 :93C5AF69: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-05-07 06:00:02.678312 :93C5AF71: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-05-07 06:00:02.679406 :93C5AF79: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-05-07 06:00:02.698438 :93C5AF81:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 8, ext 1, nex 2 2025-05-07 06:00:02.698446 :93C5AF82:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00811cf9 where: kdiwh22: kdifind 2025-05-07 06:00:02.708741 :93C5AF83: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-05-07 06:00:02.708756 :93C5AF84:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0008.01b.0000c8d1 2025-05-07 06:00:02.709000 :93C5AF8C:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 8, ext 2, nex 3 2025-05-07 06:00:02.709007 :93C5AF8D:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00811bf4 where: ktspbwh1: ktspfsrch 2025-05-07 06:00:02.723013 :93C5AF8E: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-05-07 06:00:02.723025 :93C5AF8F:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0008.020.0000c8d5 2025-05-07 06:00:02.723205 :93C5AF92:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 8, ext 3, nex 4 2025-05-07 06:00:02.723210 :93C5AF93:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00811dda where: ktspbwh1: ktspfsrch 2025-05-07 06:00:02.734152 :93C5AF99:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 8, ext 4, nex 5 2025-05-07 06:00:02.734160 :93C5AF9A:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00811bf9 where: kdiwh22: kdifind 2025-05-07 06:00:03.308415 :93C5AFC3: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-05-07 06:00:03.308429 :93C5AFC4:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0008.00c.0000c8d3 2025-05-07 06:00:03.308717 :93C5AFCC:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 8, ext 5, nex 6 2025-05-07 06:00:03.308723 :93C5AFCD:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00811e1c where: ktspbwh1: ktspfsrch 2025-05-07 06:00:03.618184*:93C5B008:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029122520 estart=1746568802 eid=16778296 ctx=0x000000077BA4B1C8 cwh=0x00000000290E0AF8 cpu=312500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-07 06:00:03.618184*:93C5B009:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029122520 curCtx=0x0000000029121258 pubTabIdxCnt=0 2025-05-07 06:00:03.618184*:93C5B00A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029122520 2025-05-07 06:00:03.618184*:93C5B00B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029122520 2025-05-07 06:00:03.618184*:93C5B00C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000290F2D18 estart=1746568802 eid=16778290 ctx=0x0000000787AD5BF0 cwh=0x00000000290E0410 cpu=312500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-07 06:00:03.618184*:93C5B00D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000290F2D18 curCtx=0x00000000291234C0 pubTabIdxCnt=0 2025-05-07 06:00:03.618184*:93C5B00E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000290F2D18 2025-05-07 06:00:03.618184*:93C5B00F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000290F2D18 2025-05-07 06:00:03.852558*:93C5B011:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029122520 curCtx=0x0000000029121258 xsc->flg4=65792 flags=1 2025-05-07 06:00:03.852558*:93C5B012:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029122520 2025-05-07 06:00:03.866256 :93C5B013: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-05-07 06:00:03.866268 :93C5B014:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0008.021.0000c8d1 2025-05-07 06:00:03.866533 :93C5B01C:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 8, ext 6, nex 7 2025-05-07 06:00:03.866539 :93C5B01D:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00801335 where: kdiwh133: kdisdelete 2025-05-07 06:00:03.866539*:93C5B01E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000290F2D18 curCtx=0x00000000291234C0 xsc->flg4=65792 flags=2 2025-05-07 06:00:03.866539*:93C5B01F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000290F2D18 2025-05-07 06:00:03.926622 :93C5B020: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-05-07 06:00:03.951358 :93C5B028: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-05-07 06:00:03.951639 :93C5B030: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-05-07 06:00:03.951963 :93C5B038: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-05-07 06:00:03.951986 :93C5B03B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-07 06:00:03.952040 :93C5B047: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-05-07 06:00:03.954944 :93C5B055:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-07 06:10:03.982493 :93C67D28:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-07 06:10:03.970 2025-05-07 06:10:03.982494 :93C67D29:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12204 pso_num=28 pso_serial#=3 2025-05-07 06:10:03.982628 :93C67D2A:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12204 osp_idx=26 osp_ver=92057 osp_pg=0 (spawn #156666) 2025-05-07 06:10:03.982629 :93C67D2B:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 06:10:03.982630 :93C67D2C:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=239 time=3868981140 2025-05-07 06:10:03.982630 :93C67D2D:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 06:10:03.982630 :93C67D2E:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 06:10:03.982631 :93C67D2F:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 06:10:03.982631 :93C67D30:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 06:10:03.982632 :93C67D31:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 06:10:03.983129 :93C67D32: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-05-07 06:10:03.983172 :93C67D38: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-05-07 06:10:03.987152 :93C67D61:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 06:10:03.987195 :93C67D62:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 06:10:03.987435 :93C67D63:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 06:10:03.987449 :93C67D64:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 06:10:03.987653 :93C67D65:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 06:10:03.987666 :93C67D66:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 06:10:03.987872 :93C67D67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 06:10:03.987885 :93C67D68:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 06:10:03.988084 :93C67D69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 06:10:03.988096 :93C67D6A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 06:10:03.988300 :93C67D6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-07 06:10:03.988313 :93C67D6C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-07 06:10:03.990774 :93C67D6D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-07 08:22:46.324322 :93D10109:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-07 08:22:46.309 2025-05-07 08:22:46.324324 :93D1010A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9584 pso_num=28 pso_serial#=4 2025-05-07 08:22:46.324474 :93D1010B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9584 (legacy spawn) 2025-05-07 08:22:46.331079 :93D10116: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-05-07 08:22:46.331178 :93D1011F: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-05-07 08:22:46.331975 :93D10120:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-07 08:22:46.340737 :93D10123:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-07 08:22:46.340 2025-05-07 08:22:46.340739 :93D10124:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9984 pso_num=28 pso_serial#=5 2025-05-07 08:22:46.340876 :93D10125:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9984 (legacy spawn) 2025-05-07 08:22:46.347261 :93D10126: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-05-07 08:22:46.347569 :93D1012E: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-05-07 08:22:46.348321 :93D10130:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-07 08:22:46.450473 :93D1015C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-07 08:22:46.450 2025-05-07 08:22:46.450475 :93D1015D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12240 pso_num=28 pso_serial#=6 2025-05-07 08:22:46.450624 :93D1015E:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12240 osp_idx=26 osp_ver=92060 osp_pg=0 (spawn #156977) 2025-05-07 08:22:46.450625 :93D1015F:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:22:46.450625 :93D10160:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=240 time=3876943609 2025-05-07 08:22:46.450626 :93D10161:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:22:46.450628 :93D10162:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:22:46.450628 :93D10163:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:22:46.450628 :93D10164:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:22:46.450629 :93D10165:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:22:46.451795 :93D10166: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-05-07 08:22:46.451844 :93D1016C: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-05-07 08:22:46.548851 :93D1019E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-07 08:22:48.608511 :93D102C7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-07 08:22:48.606 2025-05-07 08:22:48.608513 :93D102C8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7316 pso_num=28 pso_serial#=7 2025-05-07 08:22:48.608651 :93D102C9:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=7316 osp_idx=26 osp_ver=92061 osp_pg=0 (spawn #156979) 2025-05-07 08:22:48.608652 :93D102CA:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:22:48.608652 :93D102CB:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=241 time=3876945765 2025-05-07 08:22:48.608653 :93D102CC:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:22:48.608653 :93D102CD:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:22:48.608653 :93D102CE:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:22:48.608654 :93D102CF:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:22:48.608654 :93D102D0:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:22:48.609154 :93D102D1: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-05-07 08:22:48.609196 :93D102D7: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: 7316, M002)