Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250515080242\orcl_m002_10896_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250515080242\orcl_m002_10896_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:37388M/63366M, Ph+PgF:44630M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 10896, image: ORACLE.EXE (M002) *** 2025-05-15 08:02:42.858 *** SESSION ID:(225.31293) 2025-05-15 08:02:42.858 *** 2025-05-15 08:02:42.858 Process diagnostic dump for ORACLE.EXE (M002), OS id=10896, pid: 28, proc_ser: 91, sid: 225, sess_ser: 31293 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x4472, blocks=0x1 wait_id=1577 seq_num=1578 snap_id=1 wait times: snap=0.001208 sec, exc=0.001208 sec, total=0.001208 sec wait times: max=infinite, heur=0.001208 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.000162 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x4912, blocks=0x1 wait_id=1576 seq_num=1577 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.000320 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x48e2, blocks=0x1 wait_id=1575 seq_num=1576 snap_id=1 wait times: snap=0.003218 sec, exc=0.003218 sec, total=0.003218 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000220 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x48c2, blocks=0x1 wait_id=1574 seq_num=1575 snap_id=1 wait times: snap=0.000449 sec, exc=0.000449 sec, total=0.000449 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000335 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x4872, blocks=0x1 wait_id=1573 seq_num=1574 snap_id=1 wait times: snap=0.005015 sec, exc=0.005015 sec, total=0.005015 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000145 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x454a, blocks=0x1 wait_id=1572 seq_num=1573 snap_id=1 wait times: snap=0.000069 sec, exc=0.000069 sec, total=0.000069 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000258 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x485a, blocks=0x1 wait_id=1571 seq_num=1572 snap_id=1 wait times: snap=0.002009 sec, exc=0.002009 sec, total=0.002009 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000129 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x444a, blocks=0x1 wait_id=1570 seq_num=1571 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000130 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x450a, blocks=0x1 wait_id=1569 seq_num=1570 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000136 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x44f2, blocks=0x1 wait_id=1568 seq_num=1569 snap_id=1 wait times: snap=0.000047 sec, exc=0.000047 sec, total=0.000047 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000136 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x453a, blocks=0x1 wait_id=1567 seq_num=1568 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000138 sec of elapsed time Sampled Session History of session 225 serial 31293 --------------------------------------------------- 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:02:42] waited for 'db file sequential read', seq_num: 1561 p1: 'file#'=0x2 p2: 'block#'=0x42e2 p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 08:02:41] idle wait at each sample [session created at: 08:02:42] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 08:02:42 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-15 08:02:42.858 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 10896, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-15 06:09:54.094972 :9746DC50:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:09:54.094986 :9746DC51:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:09:54.095190 :9746DC52:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:09:54.095202 :9746DC53:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:09:54.095412 :9746DC54:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:09:54.095425 :9746DC55:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:09:54.095629 :9746DC56:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:09:54.095641 :9746DC57:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:09:54.097895 :9746DC58:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-15 06:39:58.491669 :97493671:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-15 06:39:58.479 2025-05-15 06:39:58.491672 :97493672:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11860 pso_num=28 pso_serial#=86 2025-05-15 06:39:58.491813 :97493673:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=11860 osp_idx=26 osp_ver=113815 osp_pg=0 (spawn #183697) 2025-05-15 06:39:58.491814 :97493674: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-15 06:39:58.491814 :97493675:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=158 time=267008360 2025-05-15 06:39:58.491815 :97493676:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 06:39:58.491815 :97493677:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 06:39:58.491816 :97493678:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 06:39:58.491816 :97493679:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 06:39:58.491816 :9749367A:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 06:39:58.492328 :9749367B: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-15 06:39:58.492367 :97493681: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-15 06:39:58.496355 :9749369A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:39:58.496407 :9749369B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:39:58.496669 :9749369C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:39:58.496686 :9749369D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:39:58.496906 :9749369E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:39:58.496920 :9749369F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:39:58.497127 :974936A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:39:58.497139 :974936A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:39:58.497349 :974936A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:39:58.497362 :974936A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:39:58.497561 :974936A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 06:39:58.497574 :974936A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 06:39:58.499960 :974936A6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-15 07:00:01.187958 :974AC769:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-15 07:00:01.177 2025-05-15 07:00:01.187959 :974AC76A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12188 pso_num=28 pso_serial#=87 2025-05-15 07:00:01.188081 :974AC76C:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12188 osp_idx=26 osp_ver=113816 osp_pg=0 (spawn #183743) 2025-05-15 07:00:01.188082 :974AC76D: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-15 07:00:01.188083 :974AC76E:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=159 time=268211047 2025-05-15 07:00:01.188083 :974AC76F:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 07:00:01.188083 :974AC770:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 07:00:01.188083 :974AC771:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 07:00:01.188084 :974AC772:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 07:00:01.188084 :974AC773:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 07:00:01.188601 :974AC778: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-15 07:00:01.188637 :974AC77E: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-15 07:00:01.194065 :974AC797: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-15 07:00:01.216812 :974AC7C4: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-15 07:00:01.231754 :974AC7CC: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-15 07:00:01.258503 :974AC7D4: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-15 07:00:01.262103 :974AC7DC: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-15 07:00:01.265789 :974AC7E4: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-15 07:00:01.271155 :974AC7EC: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-15 07:00:01.272163 :974AC7F4: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-15 07:00:02.411317*:974AC872:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029120920 estart=1747263601 eid=16778490 ctx=0x000000077BA4B1C8 cwh=0x00000000290E0AF8 cpu=500000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-15 07:00:02.411317*:974AC873:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029120920 curCtx=0x0000000026E8F998 pubTabIdxCnt=0 2025-05-15 07:00:02.411317*:974AC874:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029120920 2025-05-15 07:00:02.411317*:974AC875:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029120920 2025-05-15 07:00:02.411317*:974AC876:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000290F4DE8 estart=1747263601 eid=16778483 ctx=0x0000000787AD5BF0 cwh=0x00000000290E0410 cpu=500000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-15 07:00:02.411317*:974AC877:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000290F4DE8 curCtx=0x00000000291218C0 pubTabIdxCnt=0 2025-05-15 07:00:02.411317*:974AC878:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000290F4DE8 2025-05-15 07:00:02.411317*:974AC879:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000290F4DE8 2025-05-15 07:00:02.473812*:974AC87A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029120920 curCtx=0x0000000026E8F998 xsc->flg4=65792 flags=1 2025-05-15 07:00:02.473812*:974AC87B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029120920 2025-05-15 07:00:02.473812*:974AC87C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000290F4DE8 curCtx=0x00000000291218C0 xsc->flg4=65792 flags=2 2025-05-15 07:00:02.473812*:974AC87D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000290F4DE8 2025-05-15 07:00:02.538682 :974AC87E: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-15 07:00:02.564324 :974AC886: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-15 07:00:02.564647 :974AC88E: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-15 07:00:02.564971 :974AC896: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-15 07:00:02.564987 :974AC899: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-15 07:00:02.565055 :974AC8A9: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-15 07:00:02.567896 :974AC8B3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-15 07:10:02.567638 :974B9A60:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-15 07:10:02.555 2025-05-15 07:10:02.567640 :974B9A61:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9376 pso_num=28 pso_serial#=88 2025-05-15 07:10:02.567774 :974B9A62:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=9376 osp_idx=26 osp_ver=113817 osp_pg=0 (spawn #183768) 2025-05-15 07:10:02.567774 :974B9A63: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-15 07:10:02.567775 :974B9A64:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=160 time=268812422 2025-05-15 07:10:02.567775 :974B9A65:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 07:10:02.567775 :974B9A66:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 07:10:02.567776 :974B9A67:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 07:10:02.567776 :974B9A68:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 07:10:02.567776 :974B9A69:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 07:10:02.568263 :974B9A6A: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-15 07:10:02.568304 :974B9A70: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-15 07:10:02.572386 :974B9A89:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:10:02.572431 :974B9A8A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:10:02.572691 :974B9A8B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:10:02.572706 :974B9A8C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:10:02.572916 :974B9A8D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:10:02.572929 :974B9A8E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:10:02.573132 :974B9A8F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:10:02.573146 :974B9A90:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:10:02.573347 :974B9A91:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:10:02.573359 :974B9A92:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:10:02.573556 :974B9A93:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:10:02.573568 :974B9A94:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:10:02.575884 :974B9A95:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-15 07:40:06.471500 :974DF460:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-15 07:40:06.459 2025-05-15 07:40:06.471503 :974DF461:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7792 pso_num=28 pso_serial#=89 2025-05-15 07:40:06.471643 :974DF462:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=7792 osp_idx=26 osp_ver=113818 osp_pg=0 (spawn #183836) 2025-05-15 07:40:06.471644 :974DF463: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-15 07:40:06.471644 :974DF464:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=161 time=270616329 2025-05-15 07:40:06.471645 :974DF465:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 07:40:06.471645 :974DF466:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 07:40:06.471645 :974DF467:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 07:40:06.471646 :974DF468:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 07:40:06.471646 :974DF469:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 07:40:06.472146 :974DF46A: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-15 07:40:06.472185 :974DF470: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-15 07:40:06.476238 :974DF489:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:40:06.476282 :974DF48A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:40:06.476536 :974DF48B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:40:06.476551 :974DF48C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:40:06.476759 :974DF48D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:40:06.476772 :974DF48E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:40:06.476982 :974DF48F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:40:06.476995 :974DF490:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:40:06.477198 :974DF491:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:40:06.477211 :974DF492:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:40:06.477417 :974DF493:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-15 07:40:06.477430 :974DF494:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-15 07:40:06.479791 :974DF495:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-15 08:00:09.050231 :974F84DB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-15 08:00:09.039 2025-05-15 08:00:09.050232 :974F84DC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12672 pso_num=28 pso_serial#=90 2025-05-15 08:00:09.050363 :974F84DD:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12672 osp_idx=26 osp_ver=113819 osp_pg=0 (spawn #183882) 2025-05-15 08:00:09.050364 :974F84DE: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-15 08:00:09.050364 :974F84DF:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=162 time=271818907 2025-05-15 08:00:09.050364 :974F84E0:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 08:00:09.050364 :974F84E1:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 08:00:09.050365 :974F84E2:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 08:00:09.050365 :974F84E3:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 08:00:09.050365 :974F84E4:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 08:00:09.050876 :974F84EA: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-15 08:00:09.050913 :974F84F1: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-15 08:00:09.056100 :974F850A: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-15 08:00:09.079433 :974F8537: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-15 08:00:09.094123 :974F853F: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-15 08:00:09.120399 :974F8547: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-15 08:00:09.124117 :974F854F: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-15 08:00:09.127885 :974F8557: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-15 08:00:09.132003 :974F855F: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-15 08:00:09.133067 :974F8567: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-15 08:00:10.027823*:974F85B0:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000291308E0 estart=1747267208 eid=16778491 ctx=0x000000077BA4B1C8 cwh=0x00000000290E0AF8 cpu=265625 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-15 08:00:10.027823*:974F85B1:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000291308E0 curCtx=0x0000000026E7F998 pubTabIdxCnt=0 2025-05-15 08:00:10.027823*:974F85B2:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000291308E0 2025-05-15 08:00:10.027823*:974F85B3:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000291308E0 2025-05-15 08:00:10.027823*:974F85B4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000290F2D18 estart=1747267208 eid=16778484 ctx=0x0000000787AD5BF0 cwh=0x00000000290E0410 cpu=265625 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-15 08:00:10.027823*:974F85B5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000290F2D18 curCtx=0x0000000029131880 pubTabIdxCnt=0 2025-05-15 08:00:10.027823*:974F85B6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000290F2D18 2025-05-15 08:00:10.027823*:974F85B7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000290F2D18 2025-05-15 08:00:10.324816*:974F85D0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000291308E0 curCtx=0x0000000026E7F998 xsc->flg4=65792 flags=1 2025-05-15 08:00:10.324816*:974F85D1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000291308E0 2025-05-15 08:00:10.324816*:974F85D2:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000290F2D18 curCtx=0x0000000029131880 xsc->flg4=65792 flags=2 2025-05-15 08:00:10.324816*:974F85D3:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000290F2D18 2025-05-15 08:00:10.410480 :974F85E0: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-15 08:00:10.435839 :974F85E3: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-15 08:00:10.436490 :974F85E4: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-15 08:00:10.477542 :974F8621: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-15 08:00:10.481036 :974F8628:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-15 08:02:42.286226 :974FF6D4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-15 08:02:42.279 2025-05-15 08:02:42.286228 :974FF6D5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10896 pso_num=28 pso_serial#=91 2025-05-15 08:02:42.286355 :974FF6D6:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10896 osp_idx=26 osp_ver=113820 osp_pg=0 (spawn #183894) 2025-05-15 08:02:42.286355 :974FF6D7: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-15 08:02:42.286356 :974FF6D8:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=163 time=271972157 2025-05-15 08:02:42.286356 :974FF6D9:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 08:02:42.286356 :974FF6DA:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 08:02:42.286357 :974FF6DB:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 08:02:42.286357 :974FF6DC:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 08:02:42.286358 :974FF6DD:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 08:02:42.286929 :974FF6DE: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-15 08:02:42.286971 :974FF6E4: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: 10896, M002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de