Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250601180748\orcl_j000_12656_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250601180748\orcl_j000_12656_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:37148M/63366M, Ph+PgF:44006M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12656, image: ORACLE.EXE (J000) *** 2025-06-01 18:07:48.964 *** SESSION ID:(201.43063) 2025-06-01 18:07:48.964 *** 2025-06-01 18:07:48.964 Process diagnostic dump for ORACLE.EXE (J000), OS id=12656, pid: 25, proc_ser: 251, sid: 201, sess_ser: 43063 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12656 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12531, hash value=883929061 Current Wait Stack: Not in wait; last wait ended 0.743841 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.743867 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=155 seq_num=156 snap_id=1 wait times: snap=0.000222 sec, exc=0.000222 sec, total=0.000222 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 1: waited for 'log file sequential read' log#=0x2816, block#=0x0, blocks=0x0 wait_id=154 seq_num=155 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.054399 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2816, block#=0x0, blocks=0x0 wait_id=153 seq_num=154 snap_id=1 wait times: snap=0.000015 sec, exc=0.000015 sec, total=0.000015 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000010 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x13800, blocks=0x800 wait_id=152 seq_num=153 snap_id=1 wait times: snap=0.000307 sec, exc=0.000307 sec, total=0.000307 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x13000, blocks=0x800 wait_id=151 seq_num=152 snap_id=1 wait times: snap=0.000296 sec, exc=0.000296 sec, total=0.000296 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x12800, blocks=0x800 wait_id=150 seq_num=151 snap_id=1 wait times: snap=0.000291 sec, exc=0.000291 sec, total=0.000291 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0x12000, blocks=0x800 wait_id=149 seq_num=150 snap_id=1 wait times: snap=0.000318 sec, exc=0.000318 sec, total=0.000318 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000010 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x11800, blocks=0x800 wait_id=148 seq_num=149 snap_id=1 wait times: snap=0.000350 sec, exc=0.000350 sec, total=0.000350 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000096 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0x11000, blocks=0x800 wait_id=147 seq_num=148 snap_id=1 wait times: snap=0.002030 sec, exc=0.002030 sec, total=0.002030 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.553657 sec of elapsed time 9: waited for 'log file sequential read' log#=0x2816, block#=0x1000, blocks=0x800 wait_id=146 seq_num=147 snap_id=1 wait times: snap=0.000099 sec, exc=0.000099 sec, total=0.000099 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002343 sec of elapsed time Sampled Session History of session 201 serial 43063 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 18:07:42 - 18:07:48] not in wait at each sample [4 samples, 18:07:38 - 18:07:41] idle wait at each sample [session created at: 18:07:38] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-01 18:07:48.964 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12656, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-01 18:06:08.026826 :9EF95BEC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:06:15.689670 :9EF95E5F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:06:15.676 2025-06-01 18:06:15.689672 :9EF95E60:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14452 pso_num=25 pso_serial#=238 2025-06-01 18:06:15.689811 :9EF95E61:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14452 (legacy spawn) 2025-06-01 18:06:15.696355 :9EF95E62:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:15.696536 :9EF95E67:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:06:15.697324 :9EF95E6C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:06:19.891176 :9EF95F7E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:06:19.883 2025-06-01 18:06:19.891178 :9EF95F7F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9804 pso_num=25 pso_serial#=239 2025-06-01 18:06:19.891318 :9EF95F80:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9804 (legacy spawn) 2025-06-01 18:06:19.897796 :9EF95F81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:19.897980 :9EF95F89:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:06:19.898820 :9EF95F8B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:06:20.131556 :9EF95FB6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:06:20.117 2025-06-01 18:06:20.131558 :9EF95FB7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14720 pso_num=25 pso_serial#=240 2025-06-01 18:06:20.131697 :9EF95FB8:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14720 (legacy spawn) 2025-06-01 18:06:20.138197 :9EF95FB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:20.138387 :9EF95FBE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:06:20.139175 :9EF95FC3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:06:20.157126 :9EF95FEC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:06:20.148 2025-06-01 18:06:20.157128 :9EF95FED:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1112 pso_num=25 pso_serial#=241 2025-06-01 18:06:20.157261 :9EF95FEE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=1112 (legacy spawn) 2025-06-01 18:06:20.163656 :9EF95FEF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:20.163824 :9EF95FF7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:06:20.164666 :9EF95FF9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:06:23.237820 :9EF960E8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:06:23.225 2025-06-01 18:06:23.237822 :9EF960E9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15192 pso_num=25 pso_serial#=242 2025-06-01 18:06:23.237956 :9EF960EA:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=15192 (legacy spawn) 2025-06-01 18:06:23.244490 :9EF960EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:23.244671 :9EF960F4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:06:23.245469 :9EF960F5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:06:23.254049 :9EF960F8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:06:23.240 2025-06-01 18:06:23.254052 :9EF960F9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13532 pso_num=25 pso_serial#=243 2025-06-01 18:06:23.254185 :9EF960FA:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13532 (legacy spawn) 2025-06-01 18:06:23.260626 :9EF960FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:23.260798 :9EF96104:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:06:23.261622 :9EF96105:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:06:38.030111 :9EF96582:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:06:38.024 2025-06-01 18:06:38.030113 :9EF96583:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12400 pso_num=25 pso_serial#=244 2025-06-01 18:06:38.030248 :9EF96584:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12400 osp_idx=23 osp_ver=137036 osp_pg=0 (spawn #241948) 2025-06-01 18:06:38.030249 :9EF96585:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 18:06:38.030249 :9EF96586:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=1776956672 2025-06-01 18:06:38.030250 :9EF96587:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 18:06:38.030250 :9EF96588:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 18:06:38.030250 :9EF96589:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 18:06:38.030251 :9EF9658A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 18:06:38.030251 :9EF9658B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 18:06:38.030798 :9EF9658C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 18:06:38.031458 :9EF965A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:38.045656 :9EF965BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 18:06:38.045739 :9EF965BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 18:06:38.046564 :9EF965BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 18:06:38.046930 :9EF965C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:38.046970 :9EF965C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 18:06:38.054834 :9EF965C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:38.054898 :9EF965CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-01 18:06:38.054936 :9EF965CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-01 18:06:38.055408 :9EF965D3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-01 18:06:38.055784 :9EF965D4:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-01 18:06:38.055985 :9EF965D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 18:06:38.056279 :9EF965D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:38.056306 :9EF965D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 18:06:38.057918 :9EF965DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 18:06:38.058155 :9EF965E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:38.058188 :9EF965E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 18:06:38.059171 :9EF965E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:38.059287 :9EF965F2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:07:08.134560 :9EF96F8A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:07:17.901181 :9EF97278:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:07:17.888 2025-06-01 18:07:17.901183 :9EF97279:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10700 pso_num=25 pso_serial#=245 2025-06-01 18:07:17.901325 :9EF9727A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10700 (legacy spawn) 2025-06-01 18:07:17.907828 :9EF9727B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:17.908006 :9EF97280:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:07:17.908844 :9EF97285:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:07:28.184642 :9EF975B7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:07:28.175 2025-06-01 18:07:28.184645 :9EF975B8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12820 pso_num=25 pso_serial#=246 2025-06-01 18:07:28.184781 :9EF975B9:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12820 (legacy spawn) 2025-06-01 18:07:28.191265 :9EF975CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:28.191455 :9EF975D6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:07:28.192260 :9EF975D7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:07:32.734643 :9EF97729:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:07:32.720 2025-06-01 18:07:32.734645 :9EF9772A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7880 pso_num=25 pso_serial#=247 2025-06-01 18:07:32.734780 :9EF9772B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=7880 (legacy spawn) 2025-06-01 18:07:32.741171 :9EF9772C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:32.741350 :9EF97732:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:07:32.742179 :9EF97736:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:07:32.750783 :9EF97739:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:07:32.736 2025-06-01 18:07:32.750785 :9EF9773A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14636 pso_num=25 pso_serial#=248 2025-06-01 18:07:32.750916 :9EF9773B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14636 (legacy spawn) 2025-06-01 18:07:32.757344 :9EF9773C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:32.757507 :9EF97745:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:07:32.758298 :9EF97746:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:07:35.952914 :9EF9784D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:07:35.939 2025-06-01 18:07:35.952916 :9EF9784E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13500 pso_num=25 pso_serial#=249 2025-06-01 18:07:35.953056 :9EF9784F:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13500 (legacy spawn) 2025-06-01 18:07:35.959504 :9EF97850:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:35.959679 :9EF97855:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:07:35.960477 :9EF9785A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:07:35.969170 :9EF9785D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:07:35.955 2025-06-01 18:07:35.969172 :9EF9785E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14320 pso_num=25 pso_serial#=250 2025-06-01 18:07:35.969306 :9EF9785F:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14320 (legacy spawn) 2025-06-01 18:07:35.975734 :9EF97860:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:35.975918 :9EF97869:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:07:35.976705 :9EF9786A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 18:07:38.132365 :9EF97928:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 18:07:38.127 2025-06-01 18:07:38.132367 :9EF97929:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12656 pso_num=25 pso_serial#=251 2025-06-01 18:07:38.132501 :9EF9792A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12656 osp_idx=23 osp_ver=137043 osp_pg=0 (spawn #241950) 2025-06-01 18:07:38.132501 :9EF9792B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 18:07:38.132501 :9EF9792C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=1777016782 2025-06-01 18:07:38.132502 :9EF9792D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 18:07:38.132502 :9EF9792E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 18:07:38.132502 :9EF9792F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 18:07:38.132503 :9EF97930:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 18:07:38.132503 :9EF97931:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 18:07:38.132982 :9EF97932:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 18:07:38.133623 :9EF97947:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:38.141148 :9EF97963:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 18:07:38.141516 :9EF97964:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:38.141553 :9EF97967:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 18:07:38.142512 :9EF9796D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:38.142634 :9EF97976:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 18:07:42.125525 :9EF97C09:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 18:07:42.128292*:9EF97C49:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DDC718 estart=1748772461 eid=16781538 ctx=0x000000077FD6A000 cwh=0x0000000024DD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-01 18:07:42.128292*:9EF97C4A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 pubTabIdxCnt=0 2025-06-01 18:07:42.128292*:9EF97C4B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DDC718 2025-06-01 18:07:42.128292*:9EF97C4C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DDC718 2025-06-01 18:07:42.128292*:9EF97C4D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 xsc->flg4=65792 flags=2 2025-06-01 18:07:42.128292*:9EF97C4E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DDC718 2025-06-01 18:07:42.131895 :9EF97C4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.131937 :9EF97C52:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-01 18:07:42.148720 :9EF97C58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.148916 :9EF97C60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.149075 :9EF97C68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.149222 :9EF97C70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.149378 :9EF97C78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.149522 :9EF97C80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.149669 :9EF97C88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.149815 :9EF97C90:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.149957 :9EF97C98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.150097 :9EF97CA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.150241 :9EF97CA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.150380 :9EF97CB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.150517 :9EF97CB8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.150676 :9EF97CC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.150834 :9EF97CC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.151185 :9EF97CD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.151430 :9EF97CD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.169753 :9EF97CE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:43.884628 :9EF97E55:db_trace:ktu.c@4871:ktuaex1r(): [10442:25:201] Extension usn 2, ext 0, nex 2 2025-06-01 18:07:43.884638 :9EF97E56:db_trace:ktusm.c@2117:ktusmasp(): [10445:25:201] des1:tsn:1 rdba:0x00818fe6 where: kduwh01: kdusru 2025-06-01 18:07:43.894171 :9EF97E71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:43.916813 :9EF97EBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:43.919656 :9EF97ED5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 18:07:43.919940 :9EF97ED6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 18:07:43.920965 :9EF97ED7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:43.921071 :9EF97EDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:43.922065 :9EF97EEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:43.923869 :9EF97EF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:43.924204 :9EF97EFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:43.958532*:9EF97F04:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029030348 estart=1748772463 eid=16777761 ctx=0x000000079F4EAD08 cwh=0x0000000024DE0B10 cpu=0 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-06-01 18:07:43.958532*:9EF97F05:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029030348 curCtx=0x0000000028D4BC20 pubTabIdxCnt=0 2025-06-01 18:07:43.958532*:9EF97F06:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029030348 2025-06-01 18:07:43.958532*:9EF97F07:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029030348 2025-06-01 18:07:43.958532*:9EF97F08:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029030348 curCtx=0x0000000028D4BC20 xsc->flg4=65792 flags=1 2025-06-01 18:07:43.958532*:9EF97F09:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029030348 2025-06-01 18:07:43.974160*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-01 18:07:43.974160*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-01 18:07:43.974160*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-01 18:07:43.974160*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-01 18:07:43.974160*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-01 18:07:43.974160*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-01 18:07:43.974160*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-01 18:07:44.048745 :9EF97F0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:45.969179 :9EF97FDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:45.969194 :9EF97FDC:db_trace:ktur.c@3079:ktuabt(): [10444:25:201] ABORT TRANSACTION - xid: 0x0002.010.0000c1ee 2025-06-01 18:07:48.684710 :9EF98191:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:48.686307 :9EF98199:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:48.697531 :9EF981A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:07:48.722955 :9EF981A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 12656, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de