Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250713081300\orcl_m002_16852_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:36371M/63366M, Ph+PgF:42385M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 16852, image: ORACLE.EXE (M002) *** 2025-07-13 08:13:00.242 *** SESSION ID:(233.28815) 2025-07-13 08:13:00.242 *** 2025-07-13 08:13:00.242 Process diagnostic dump for ORACLE.EXE (M002), OS id=16852, pid: 29, proc_ser: 77, sid: 233, sess_ser: 28815 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x8e32, blocks=0x1 wait_id=1888 seq_num=1889 snap_id=1 wait times: snap=0.004388 sec, exc=0.004388 sec, total=0.004388 sec wait times: max=infinite, heur=0.004388 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.000150 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x8e22, blocks=0x1 wait_id=1887 seq_num=1888 snap_id=1 wait times: snap=0.000059 sec, exc=0.000059 sec, total=0.000059 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000226 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x7ec2, blocks=0x1 wait_id=1886 seq_num=1887 snap_id=1 wait times: snap=0.001165 sec, exc=0.001165 sec, total=0.001165 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000320 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x7e92, blocks=0x1 wait_id=1885 seq_num=1886 snap_id=1 wait times: snap=0.003031 sec, exc=0.003031 sec, total=0.003031 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000176 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x89e2, blocks=0x1 wait_id=1884 seq_num=1885 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000149 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x89d2, blocks=0x1 wait_id=1883 seq_num=1884 snap_id=1 wait times: snap=0.000053 sec, exc=0.000053 sec, total=0.000053 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000148 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x89ba, blocks=0x1 wait_id=1882 seq_num=1883 snap_id=1 wait times: snap=0.000068 sec, exc=0.000068 sec, total=0.000068 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000402 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0xee82, blocks=0x1 wait_id=1881 seq_num=1882 snap_id=1 wait times: snap=0.003637 sec, exc=0.003637 sec, total=0.003637 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000136 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0xee72, blocks=0x1 wait_id=1880 seq_num=1881 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000137 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0xee62, blocks=0x1 wait_id=1879 seq_num=1880 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.000133 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0xee52, blocks=0x1 wait_id=1878 seq_num=1879 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000140 sec of elapsed time Sampled Session History of session 233 serial 28815 --------------------------------------------------- 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:12:59] waited for 'ADR block file read', seq_num: 977 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:12:58] idle wait at each sample [session created at: 08:12:59] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:12:59 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-13 08:13:00.242 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 16852, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-13 06:00:01.900693 :B14F7C2B: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-07-13 06:00:01.900846 :B14F7C33: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-07-13 06:00:01.901002 :B14F7C3B: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-07-13 06:00:01.901161 :B14F7C43: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-07-13 06:00:01.901315 :B14F7C4B: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-07-13 06:00:01.901461 :B14F7C53: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-07-13 06:00:01.901616 :B14F7C5B: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-07-13 06:00:01.901765 :B14F7C63: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-07-13 06:00:01.901909 :B14F7C6B: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-07-13 06:00:01.902060 :B14F7C73: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-07-13 06:00:01.902223 :B14F7C7B: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-07-13 06:00:01.921377 :B14F7C83: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-07-13 06:00:02.946982*:B14F7DF8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031233AD0 estart=1752357602 eid=20334081 ctx=0x0000000777968158 cwh=0x00000000312609C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-13 06:00:02.946982*:B14F7DFA:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031233AD0 curCtx=0x000000004DF07918 pubTabIdxCnt=0 2025-07-13 06:00:02.946982*:B14F7DFC:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031233AD0 2025-07-13 06:00:02.946982*:B14F7DFE:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031233AD0 2025-07-13 06:00:02.946982*:B14F7DFF:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000312AB6F0 estart=1752357601 eid=16777451 ctx=0x000000077B54EAC8 cwh=0x0000000031260518 cpu=843750 buffg=47994 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-13 06:00:02.946982*:B14F7E01:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000312AB6F0 curCtx=0x000000004DDE87D0 pubTabIdxCnt=0 2025-07-13 06:00:02.946982*:B14F7E02:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000312AB6F0 2025-07-13 06:00:02.946982*:B14F7E04:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000312AB6F0 2025-07-13 06:00:02.946982*:B14F7E07:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031233AD0 curCtx=0x000000004DF07918 xsc->flg4=65792 flags=1 2025-07-13 06:00:02.946982*:B14F7E08:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031233AD0 2025-07-13 06:00:03.566488*:B14F7FC0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000312AB6F0 curCtx=0x000000004DDE87D0 xsc->flg4=65792 flags=2 2025-07-13 06:00:03.566488*:B14F7FC1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000312AB6F0 2025-07-13 06:00:03.582125 :B14F7FC2: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-07-13 06:00:03.604914 :B14F7FE9: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-07-13 06:00:03.607278 :B14F8005:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-13 06:00:03.607517 :B14F800B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-13 06:00:03.608601 :B14F8025: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-07-13 06:00:03.608727 :B14F802D: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-07-13 06:00:03.609752 :B14F8035: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-07-13 06:00:03.611625 :B14F803D: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-07-13 06:00:03.611961 :B14F8045: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-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-13 06:00:03.745599 :B14F8063: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-07-13 06:00:05.609015 :B14F814E: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-07-13 06:00:05.609027 :B14F814F:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.00b.0001d292 2025-07-13 06:00:07.931584 :B14F82E6: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-07-13 06:00:07.933021 :B14F82EE: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-07-13 06:00:07.943572 :B14F82F6: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-07-13 06:00:07.969971 :B14F82FE: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-07-13 06:00:09.112977 :B14F83BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-07-13 06:00:09.123341 :B14F83C0: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-07-13 06:00:09.124980 :B14F83C8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 06:00:20.186379 :B14F88FF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 06:00:20.173 2025-07-13 06:00:20.186381 :B14F8900:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2512 pso_num=29 pso_serial#=64 2025-07-13 06:00:20.186519 :B14F8901:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=2512 (legacy spawn) 2025-07-13 06:00:20.206147 :B14F8902: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-07-13 06:00:20.206318 :B14F8908: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-07-13 06:00:20.207185 :B14F890C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 06:00:20.215874 :B14F890F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 06:00:20.204 2025-07-13 06:00:20.215876 :B14F8910:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9364 pso_num=29 pso_serial#=65 2025-07-13 06:00:20.216014 :B14F8911:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9364 (legacy spawn) 2025-07-13 06:00:20.222602 :B14F8912: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-07-13 06:00:20.222635 :B14F8915: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-07-13 06:00:20.222788 :B14F891C: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-07-13 06:00:20.223617 :B14F8929:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 06:00:21.228720 :B14F8938:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 06:00:21.220 2025-07-13 06:00:21.228721 :B14F8939:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18140 pso_num=29 pso_serial#=66 2025-07-13 06:00:21.228856 :B14F893A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18140 (legacy spawn) 2025-07-13 06:00:21.235745 :B14F893B: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-07-13 06:00:21.235921 :B14F8940: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-07-13 06:00:21.237083 :B14F8945:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 06:00:21.484443 :B14F897D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 06:00:21.470 2025-07-13 06:00:21.484445 :B14F897E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=1020 pso_num=29 pso_serial#=67 2025-07-13 06:00:21.484584 :B14F897F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=1020 (legacy spawn) 2025-07-13 06:00:21.491093 :B14F8980: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-07-13 06:00:21.491262 :B14F8989: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-07-13 06:00:21.492070 :B14F898A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 06:00:21.500802 :B14F898D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 06:00:21.486 2025-07-13 06:00:21.500804 :B14F898E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15432 pso_num=29 pso_serial#=68 2025-07-13 06:00:21.500943 :B14F898F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15432 (legacy spawn) 2025-07-13 06:00:21.507492 :B14F8990: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-07-13 06:00:21.507657 :B14F8997: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-07-13 06:00:21.508455 :B14F899A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 06:00:23.965143 :B14F8A61:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 06:00:23.958 2025-07-13 06:00:23.965145 :B14F8A62:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13716 pso_num=29 pso_serial#=69 2025-07-13 06:00:23.965286 :B14F8A63:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13716 (legacy spawn) 2025-07-13 06:00:23.971719 :B14F8A64: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-07-13 06:00:23.971906 :B14F8A69: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-07-13 06:00:23.972733 :B14F8A6E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 06:00:24.689505 :B14F8AA5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 06:00:24.677 2025-07-13 06:00:24.689507 :B14F8AA6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10356 pso_num=29 pso_serial#=70 2025-07-13 06:00:24.689654 :B14F8AA7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10356 (legacy spawn) 2025-07-13 06:00:24.696203 :B14F8AA8: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-07-13 06:00:24.696376 :B14F8AAD: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-07-13 06:00:24.697177 :B14F8AB2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 06:00:24.705897 :B14F8AB5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 06:00:24.692 2025-07-13 06:00:24.705899 :B14F8AB6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7880 pso_num=29 pso_serial#=71 2025-07-13 06:00:24.706032 :B14F8AB7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=7880 (legacy spawn) 2025-07-13 06:00:24.714272 :B14F8AB8: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-07-13 06:00:24.714444 :B14F8AC1: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-07-13 06:00:24.715162 :B14F8AC2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 07:00:05.483854 :B1543D27:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 07:00:05.475 2025-07-13 07:00:05.483859 :B1543D28:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17060 pso_num=29 pso_serial#=72 2025-07-13 07:00:05.483989 :B1543D29:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17060 osp_idx=27 osp_ver=14351 osp_pg=0 (spawn #380415) 2025-07-13 07:00:05.483990 :B1543D2A:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 07:00:05.483991 :B1543D2B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=159 time=1070781376 2025-07-13 07:00:05.483991 :B1543D2C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 07:00:05.483992 :B1543D2D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 07:00:05.483992 :B1543D2E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 07:00:05.483992 :B1543D2F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 07:00:05.483993 :B1543D30:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 07:00:05.484500 :B1543D31: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-07-13 07:00:05.484545 :B1543D37: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-07-13 07:00:05.492581 :B1543D45: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-07-13 07:00:05.493703 :B1543D4D: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-07-13 07:00:05.494369 :B1543D55: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-07-13 07:00:05.494844 :B1543D5D: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-07-13 07:00:05.495284 :B1543D65: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-07-13 07:00:05.498060 :B1543D6D: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-07-13 07:00:05.499250 :B1543D75: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-07-13 07:00:05.551102 :B1543D7D: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-07-13 07:00:05.552023 :B1543D85: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-07-13 07:00:05.552300 :B1543D8D: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-07-13 07:00:05.552555 :B1543D95: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-07-13 07:00:05.554556 :B1543D9D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 08:00:13.681515 :B158E506:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 08:00:13.675 2025-07-13 08:00:13.681517 :B158E507:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9144 pso_num=29 pso_serial#=73 2025-07-13 08:00:13.681647 :B158E508:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=9144 osp_idx=27 osp_ver=14352 osp_pg=0 (spawn #380553) 2025-07-13 08:00:13.681648 :B158E509:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 08:00:13.681648 :B158E50A:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=160 time=1074389564 2025-07-13 08:00:13.681649 :B158E50B:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 08:00:13.681649 :B158E50C:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 08:00:13.681650 :B158E50D:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 08:00:13.681650 :B158E50E:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 08:00:13.681650 :B158E50F:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 08:00:13.682136 :B158E510: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-07-13 08:00:13.682179 :B158E516: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-07-13 08:00:13.689415 :B158E524: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-07-13 08:00:13.690461 :B158E52C: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-07-13 08:00:13.691009 :B158E534: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-07-13 08:00:13.691561 :B158E53C: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-07-13 08:00:13.692010 :B158E544: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-07-13 08:00:13.694428 :B158E54C: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-07-13 08:00:13.695565 :B158E554: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-07-13 08:00:13.746398 :B158E55C: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-07-13 08:00:13.747292 :B158E564: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-07-13 08:00:13.747538 :B158E56C: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-07-13 08:00:13.747753 :B158E574: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-07-13 08:00:13.749561 :B158E57C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 08:12:56.901033 :B15A2150:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 08:12:56.898 2025-07-13 08:12:56.901035 :B15A2151:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12772 pso_num=29 pso_serial#=74 2025-07-13 08:12:56.901206 :B15A2152:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12772 osp_idx=27 osp_ver=14353 osp_pg=0 (spawn #380586) 2025-07-13 08:12:56.901207 :B15A2153:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 08:12:56.901207 :B15A2154:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=161 time=1075152783 2025-07-13 08:12:56.901208 :B15A2155:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 08:12:56.901208 :B15A2156:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 08:12:56.901208 :B15A2157:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 08:12:56.901209 :B15A2158:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 08:12:56.901209 :B15A2159:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 08:12:56.902023 :B15A215A: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-07-13 08:12:56.902069 :B15A2160: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-07-13 08:12:57.020988 :B15A2192:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 08:12:58.011749 :B15A21A1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 08:12:58.008 2025-07-13 08:12:58.011750 :B15A21A2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=1740 pso_num=29 pso_serial#=75 2025-07-13 08:12:58.011885 :B15A21A3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=1740 (legacy spawn) 2025-07-13 08:12:58.018360 :B15A21A4: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-07-13 08:12:58.018541 :B15A21A9: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-07-13 08:12:58.019317 :B15A21AE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 08:12:58.028094 :B15A21B1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 08:12:58.023 2025-07-13 08:12:58.028096 :B15A21B2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7880 pso_num=29 pso_serial#=76 2025-07-13 08:12:58.028228 :B15A21B3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=7880 (legacy spawn) 2025-07-13 08:12:58.034671 :B15A21B4: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-07-13 08:12:58.034829 :B15A21BD: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-07-13 08:12:58.035630 :B15A21BE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 08:12:59.169227 :B15A22A9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 08:12:59.164 2025-07-13 08:12:59.169230 :B15A22AA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16852 pso_num=29 pso_serial#=77 2025-07-13 08:12:59.169364 :B15A22AB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16852 osp_idx=27 osp_ver=14356 osp_pg=0 (spawn #380588) 2025-07-13 08:12:59.169365 :B15A22AC:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 08:12:59.169365 :B15A22AD:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=162 time=1075155064 2025-07-13 08:12:59.169366 :B15A22AE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 08:12:59.169366 :B15A22AF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 08:12:59.169366 :B15A22B0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 08:12:59.169368 :B15A22B1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 08:12:59.169368 :B15A22B2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 08:12:59.169889 :B15A22B3: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-07-13 08:12:59.169931 :B15A22B9: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: 16852, M002)