Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250815220521\orcl_m002_21320_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:35490M/63366M, Ph+PgF:40809M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 21320, image: ORACLE.EXE (M002) *** 2025-08-15 22:05:21.193 *** SESSION ID:(233.31995) 2025-08-15 22:05:21.193 *** 2025-08-15 22:05:21.193 Process diagnostic dump for ORACLE.EXE (M002), OS id=21320, pid: 29, proc_ser: 123, sid: 233, sess_ser: 31995 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x88ea, blocks=0x1 wait_id=1788 seq_num=1789 snap_id=1 wait times: snap=0.004932 sec, exc=0.004932 sec, total=0.004932 sec wait times: max=infinite, heur=0.004932 sec wait counts: calls=0 os=0 in_wait=0 iflags=0x15a0 Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000033 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x88ea, blocks=0x1 wait_id=1788 seq_num=1789 snap_id=1 wait times: snap=0.004966 sec, exc=0.004966 sec, total=0.004966 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000157 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x88fa, blocks=0x1 wait_id=1787 seq_num=1788 snap_id=1 wait times: snap=0.000177 sec, exc=0.000177 sec, total=0.000177 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000479 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0xe992, blocks=0x1 wait_id=1786 seq_num=1787 snap_id=1 wait times: snap=0.003277 sec, exc=0.003277 sec, total=0.003277 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000161 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x7c5a, blocks=0x1 wait_id=1785 seq_num=1786 snap_id=1 wait times: snap=0.000129 sec, exc=0.000129 sec, total=0.000129 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000345 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x889a, blocks=0x1 wait_id=1784 seq_num=1785 snap_id=1 wait times: snap=0.003756 sec, exc=0.003756 sec, total=0.003756 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000179 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x88ca, blocks=0x1 wait_id=1783 seq_num=1784 snap_id=1 wait times: snap=0.001095 sec, exc=0.001095 sec, total=0.001095 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000170 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x7c3a, blocks=0x1 wait_id=1782 seq_num=1783 snap_id=1 wait times: snap=0.000146 sec, exc=0.000146 sec, total=0.000146 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000348 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x883a, blocks=0x1 wait_id=1781 seq_num=1782 snap_id=1 wait times: snap=0.005577 sec, exc=0.005577 sec, total=0.005577 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000354 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x8d4a, blocks=0x1 wait_id=1780 seq_num=1781 snap_id=1 wait times: snap=0.006628 sec, exc=0.006628 sec, total=0.006628 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000384 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0xe972, blocks=0x1 wait_id=1779 seq_num=1780 snap_id=1 wait times: snap=0.004997 sec, exc=0.004997 sec, total=0.004997 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000356 sec of elapsed time Sampled Session History of session 233 serial 31995 --------------------------------------------------- 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, 22:05:20] not in wait at each sample [1 sample, 22:05:19] idle wait at each sample [session created at: 22:05:20] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-15 22:05:21.193 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 21320, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-15 16:00:22.189610 :C01C89C4:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 16:00:22.189611 :C01C89C5:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 16:00:22.190136 :C01C89C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.190182 :C01C89CC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 16:00:22.198182 :C01C89DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.199306 :C01C89E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.199894 :C01C89EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.200350 :C01C89F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.200782 :C01C89FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.203458 :C01C8A02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.204609 :C01C8A0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.256375 :C01C8A12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.257250 :C01C8A1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.257502 :C01C8A22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.257728 :C01C8A2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 16:00:22.259678 :C01C8A32:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:00:02.068368 :C0387E85:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:00:02.062 2025-08-15 22:00:02.068369 :C0387E86:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18460 pso_num=29 pso_serial#=113 2025-08-15 22:00:02.068489 :C0387E8C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=18460 osp_idx=27 osp_ver=15922 osp_pg=0 (spawn #492473) 2025-08-15 22:00:02.068489 :C0387E8D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 22:00:02.068490 :C0387E8E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=249 time=3975871376 2025-08-15 22:00:02.068490 :C0387E8F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 22:00:02.068491 :C0387E90:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 22:00:02.068491 :C0387E91:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 22:00:02.068491 :C0387E92:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 22:00:02.068492 :C0387E93:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 22:00:02.068986 :C0387E94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.074136 :C0387EB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.074177 :C0387EB8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-15 22:00:02.091984 :C03880D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.092265 :C03880DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.092479 :C03880E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.092695 :C03880EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.092884 :C03880F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.093064 :C03880FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.093229 :C0388104:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.093386 :C038810C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.093602 :C0388114:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.093781 :C038811C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.093933 :C0388124:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.094093 :C038812C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.094251 :C0388134:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.094405 :C038813C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.094557 :C0388144:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.094716 :C038814C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.094867 :C0388154:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.095017 :C038815C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.095636 :C0388164:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.096003 :C038816C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.096253 :C0388174:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.096411 :C038817C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.096646 :C0388184:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.096828 :C038818C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.096987 :C0388194:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:02.124108 :C038819C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:03.415416*:C03883D1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000325D3AD0 estart=1755266402 eid=21380752 ctx=0x0000000777968158 cwh=0x00000000571B0158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-15 22:00:03.415416*:C03883D2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000325D3AD0 curCtx=0x00000000570B1BF8 pubTabIdxCnt=0 2025-08-15 22:00:03.415416*:C03883D3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000325D3AD0 2025-08-15 22:00:03.415416*:C03883D4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000325D3AD0 2025-08-15 22:00:03.415416*:C03883D5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003264B6F0 estart=1755266401 eid=16777520 ctx=0x000000077B54EAC8 cwh=0x0000000032640068 cpu=1078125 buffg=61762 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-15 22:00:03.415416*:C03883D6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003264B6F0 curCtx=0x00000000570A7FD8 pubTabIdxCnt=0 2025-08-15 22:00:03.415416*:C03883D7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003264B6F0 2025-08-15 22:00:03.415416*:C03883D8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003264B6F0 2025-08-15 22:00:03.415416*:C03883D9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000325D3AD0 curCtx=0x00000000570B1BF8 xsc->flg4=65792 flags=1 2025-08-15 22:00:03.415416*:C03883DA:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000325D3AD0 2025-08-15 22:00:03.774279 :C0388417:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 6, ext 1, nex 2 2025-08-15 22:00:03.774287 :C0388418:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0080035f where: kdiwh22: kdifind 2025-08-15 22:00:03.774787*:C0388419:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003264B6F0 curCtx=0x00000000570A7FD8 xsc->flg4=65792 flags=2 2025-08-15 22:00:03.774787*:C038841A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003264B6F0 2025-08-15 22:00:03.780045 :C038841B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:03.800411 :C0388453:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:03.802246 :C038845B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-15 22:00:03.802435 :C038845C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-15 22:00:03.803294 :C038845D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:03.803382 :C0388460:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:03.804197 :C0388472:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:03.805716 :C038847A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:03.806003 :C0388482:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-15 22:00:03.937676 :C0388523:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:05.835850 :C0388872:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:05.835861 :C0388873:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0008.007.0000d1b6 2025-08-15 22:00:08.321018 :C0388A0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:08.322407 :C0388A15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:08.333087 :C0388A1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:08.361103 :C0388A25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.564340 :C0388BCF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-08-15 22:00:09.581826 :C0388BD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.583427 :C0388BD8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:00:09.891224 :C0388C13:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:00:09.876 2025-08-15 22:00:09.891226 :C0388C14:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12612 pso_num=29 pso_serial#=114 2025-08-15 22:00:09.891355 :C0388C15:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12612 osp_idx=27 osp_ver=15923 osp_pg=0 (spawn #492477) 2025-08-15 22:00:09.891356 :C0388C16:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 22:00:09.891357 :C0388C17:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=250 time=3975879189 2025-08-15 22:00:09.891357 :C0388C18:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 22:00:09.891357 :C0388C19:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 22:00:09.891358 :C0388C1A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 22:00:09.891358 :C0388C1B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 22:00:09.891358 :C0388C1C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 22:00:09.892744 :C0388C1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.892782 :C0388C23:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 22:00:09.900453 :C0388C31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.901540 :C0388C39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.902030 :C0388C41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.902468 :C0388C49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.902950 :C0388C51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.905489 :C0388C59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.906573 :C0388C61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.964042 :C0388C6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.964942 :C0388C77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.965174 :C0388C7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.965384 :C0388C87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:09.967680 :C0388C8F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:00:10.119940 :C0388CDA:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:00:10.111 2025-08-15 22:00:10.119942 :C0388CDB:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21304 pso_num=29 pso_serial#=115 2025-08-15 22:00:10.120075 :C0388CDC:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21304 (legacy spawn) 2025-08-15 22:00:10.126671 :C0388CE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:10.126807 :C0388CEA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 22:00:10.127666 :C0388CEB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:00:14.484753 :C0388EA3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:00:14.476 2025-08-15 22:00:14.484755 :C0388EA4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20948 pso_num=29 pso_serial#=116 2025-08-15 22:00:14.484894 :C0388EA5:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20948 (legacy spawn) 2025-08-15 22:00:14.491330 :C0388EA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:14.491507 :C0388EAB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 22:00:14.492399 :C0388EBC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:00:14.501111 :C0388EBF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:00:14.492 2025-08-15 22:00:14.501113 :C0388EC0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20512 pso_num=29 pso_serial#=117 2025-08-15 22:00:14.501253 :C0388EC1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20512 (legacy spawn) 2025-08-15 22:00:14.507729 :C0388ECA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:14.507938 :C0388ED0:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 22:00:14.508801 :C0388ED4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:00:17.692894 :C0388FD7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:00:17.680 2025-08-15 22:00:17.692896 :C0388FD8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14304 pso_num=29 pso_serial#=118 2025-08-15 22:00:17.693039 :C0388FD9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14304 (legacy spawn) 2025-08-15 22:00:17.699569 :C0388FDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:17.699749 :C0388FE3:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 22:00:17.700550 :C0388FE4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:00:17.709401 :C0388FE7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:00:17.695 2025-08-15 22:00:17.709403 :C0388FE8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19536 pso_num=29 pso_serial#=119 2025-08-15 22:00:17.709546 :C0388FE9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19536 (legacy spawn) 2025-08-15 22:00:17.715973 :C0388FEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:17.716131 :C0388FF3:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 22:00:17.716919 :C0388FF4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:00:31.143277 :C03893E4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:00:31.130 2025-08-15 22:00:31.143279 :C03893E5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19432 pso_num=29 pso_serial#=120 2025-08-15 22:00:31.143414 :C03893E6:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19432 (legacy spawn) 2025-08-15 22:00:31.149841 :C03893E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:00:31.150005 :C03893EC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 22:00:31.150794 :C03893F1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:05:17.125170 :C03933F8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:05:17.118 2025-08-15 22:05:17.125173 :C03933F9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19120 pso_num=29 pso_serial#=121 2025-08-15 22:05:17.125305 :C03933FA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19120 (legacy spawn) 2025-08-15 22:05:17.131725 :C03933FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 22:05:17.132275 :C0393404:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 22:05:17.133104 :C0393405:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:05:18.903574 :C03936ED:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:05:18.899 2025-08-15 22:05:18.903576 :C03936EE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20336 pso_num=29 pso_serial#=122 2025-08-15 22:05:18.903736 :C03936EF:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20336 osp_idx=27 osp_ver=15931 osp_pg=0 (spawn #492491) 2025-08-15 22:05:18.903737 :C03936F0:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 22:05:18.903737 :C03936F1:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=251 time=3976188189 2025-08-15 22:05:18.903737 :C03936F2:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 22:05:18.903738 :C03936F3:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 22:05:18.903738 :C03936F4:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 22:05:18.903738 :C03936F5:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 22:05:18.903739 :C03936F6:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 22:05:18.904979 :C03936F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-15 22:05:18.905024 :C03936FD:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-15 22:05:19.012266 :C039372F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:05:20.207025 :C03937BD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:05:20.196 2025-08-15 22:05:20.207027 :C03937BE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21320 pso_num=29 pso_serial#=123 2025-08-15 22:05:20.207163 :C03937BF:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21320 osp_idx=27 osp_ver=15932 osp_pg=0 (spawn #492493) 2025-08-15 22:05:20.207164 :C03937C0:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 22:05:20.207164 :C03937C1:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=252 time=3976189501 2025-08-15 22:05:20.207165 :C03937C2:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 22:05:20.207165 :C03937C3:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 22:05:20.207165 :C03937C4:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 22:05:20.207165 :C03937C5:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 22:05:20.207166 :C03937C6:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 22:05:20.207664 :C03937C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-15 22:05:20.207706 :C03937CD:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 29 (osid: 21320, M002)