Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250625220006\orcl_j003_17556_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250625220006\orcl_j003_17556_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:36352M/63366M, Ph+PgF:42527M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 17556, image: ORACLE.EXE (J003) *** 2025-06-25 22:00:06.881 *** SESSION ID:(233.26951) 2025-06-25 22:00:06.881 *** 2025-06-25 22:00:06.881 Process diagnostic dump for ORACLE.EXE (J003), OS id=17556, pid: 29, proc_ser: 174, sid: 233, sess_ser: 26951 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17556 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12652, hash value=1364747797 Current Wait Stack: Not in wait; last wait ended 3.031170 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 3.031195 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000175 sec, exc=0.000175 sec, total=0.000175 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.067343 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000009 sec, exc=0.000009 sec, total=0.000009 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.765408 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.028316 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000008 sec, exc=0.000008 sec, total=0.000008 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.013662 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000110 sec, exc=0.000110 sec, total=0.000110 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049541 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.006905 sec, exc=0.006905 sec, total=0.006905 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007959 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.005538 sec, exc=0.005538 sec, total=0.005538 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.264362 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.000135 sec, exc=0.000135 sec, total=0.000135 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000316 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.003814 sec, exc=0.003814 sec, total=0.003814 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008162 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000052 sec of elapsed time Sampled Session History of session 233 serial 26951 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:00:02 - 22:00:06] not in wait at each sample [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-25 22:00:06.881 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 17556, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-25 01:00:24.713593 :A9389231:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16144 pso_num=29 pso_serial#=163 2025-06-25 01:00:24.713730 :A9389232:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16144 (legacy spawn) 2025-06-25 01:00:24.720177 :A9389233: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-06-25 01:00:24.720340 :A938923C: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-06-25 01:00:24.721151 :A938923D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 01:00:27.998735 :A9389333:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 01:00:27.985 2025-06-25 01:00:27.998737 :A9389334:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17968 pso_num=29 pso_serial#=164 2025-06-25 01:00:27.998871 :A9389335:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17968 (legacy spawn) 2025-06-25 01:00:28.005363 :A9389336: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-06-25 01:00:28.005533 :A938933B: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-06-25 01:00:28.006308 :A9389340:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 01:00:28.014855 :A9389343:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 01:00:28.001 2025-06-25 01:00:28.014856 :A9389344:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15712 pso_num=29 pso_serial#=165 2025-06-25 01:00:28.014986 :A9389345:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15712 (legacy spawn) 2025-06-25 01:00:28.021446 :A9389346: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-06-25 01:00:28.021611 :A938934B: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-06-25 01:00:28.022390 :A9389350:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 01:00:35.798844 :A93896A3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 01:00:35.791 2025-06-25 01:00:35.798847 :A93896A4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14672 pso_num=29 pso_serial#=166 2025-06-25 01:00:35.798985 :A93896A5:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14672 osp_idx=27 osp_ver=13425 osp_pg=0 (spawn #319584) 2025-06-25 01:00:35.798987 :A93896A6:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-25 01:00:35.798988 :A93896A7:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=162 time=3788978985 2025-06-25 01:00:35.798988 :A93896A8:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-25 01:00:35.798988 :A93896A9:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-25 01:00:35.798989 :A93896AA:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-25 01:00:35.798989 :A93896AB:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-25 01:00:35.798989 :A93896AC:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-25 01:00:35.799484 :A93896AD: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-06-25 01:00:35.799530 :A93896B4: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-06-25 01:00:35.806813 :A93896C1: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-06-25 01:00:35.807874 :A93896C9: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-06-25 01:00:35.808415 :A93896D1: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-06-25 01:00:35.808860 :A93896D9: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-06-25 01:00:35.809387 :A93896E1: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-06-25 01:00:35.811832 :A93896E9: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-06-25 01:00:35.812961 :A93896F1: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-06-25 01:00:35.861376 :A93896F9: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-06-25 01:00:35.862968 :A9389701: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-06-25 01:00:35.863819 :A9389709: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-06-25 01:00:35.864052 :A9389711: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-06-25 01:00:35.864324 :A9389719: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-06-25 01:00:35.866050 :A9389721:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 01:02:53.604369 :A938C53C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 01:02:53.591 2025-06-25 01:02:53.604371 :A938C53D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18028 pso_num=29 pso_serial#=167 2025-06-25 01:02:53.604510 :A938C53E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18028 (legacy spawn) 2025-06-25 01:02:53.610996 :A938C53F: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-06-25 01:02:53.632559 :A938C54E: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-06-25 01:02:53.633312 :A938C555:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 08:01:38.250919 :A959A9BE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 08:01:38.245 2025-06-25 08:01:38.250921 :A959A9BF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10428 pso_num=29 pso_serial#=168 2025-06-25 08:01:38.254013 :A959A9C0:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=10428 osp_idx=27 osp_ver=13427 osp_pg=0 (spawn #320556) 2025-06-25 08:01:38.254015 :A959A9C1:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-25 08:01:38.254015 :A959A9C2:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=163 time=3814241422 2025-06-25 08:01:38.254016 :A959A9C3:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-25 08:01:38.254017 :A959A9C4:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-25 08:01:38.254017 :A959A9C5:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-25 08:01:38.254017 :A959A9C6:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-25 08:01:38.254018 :A959A9C7:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-25 08:01:38.257818 :A959A9C8: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-06-25 08:01:38.257858 :A959A9CE: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-06-25 08:01:38.258310 :A959A9CF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 08:01:40.481797 :A959AABB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 08:01:40.482 2025-06-25 08:01:40.481799 :A959AABC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12252 pso_num=29 pso_serial#=169 2025-06-25 08:01:40.481951 :A959AABD:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12252 osp_idx=27 osp_ver=13428 osp_pg=0 (spawn #320558) 2025-06-25 08:01:40.481952 :A959AABE:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-25 08:01:40.481952 :A959AABF:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=164 time=3814243657 2025-06-25 08:01:40.481953 :A959AAC0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-25 08:01:40.481953 :A959AAC1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-25 08:01:40.481954 :A959AAC2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-25 08:01:40.481954 :A959AAC3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-25 08:01:40.481955 :A959AAC4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-25 08:01:40.482510 :A959AAC5: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-06-25 08:01:40.482551 :A959AACB: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-06-25 08:01:40.482902 :A959AACC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 08:01:41.506039 :A959AD45:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 08:01:41.498 2025-06-25 08:01:41.506041 :A959AD46:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7016 pso_num=29 pso_serial#=170 2025-06-25 08:01:41.506179 :A959AD47:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=7016 (legacy spawn) 2025-06-25 08:01:41.512800 :A959AD48: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-06-25 08:01:41.513171 :A959AD51: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-06-25 08:01:41.514034 :A959AD52:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 08:01:41.522662 :A959AD55:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 08:01:41.513 2025-06-25 08:01:41.522664 :A959AD56:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17680 pso_num=29 pso_serial#=171 2025-06-25 08:01:41.522799 :A959AD57:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17680 (legacy spawn) 2025-06-25 08:01:41.529322 :A959AD58: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-06-25 08:01:41.529640 :A959AD61: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-06-25 08:01:41.530529 :A959AD62:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 16:00:27.111722 :A97F46A9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 16:00:27.103 2025-06-25 16:00:27.111724 :A97F46AA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16076 pso_num=29 pso_serial#=172 2025-06-25 16:00:27.111850 :A97F46AB:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16076 (legacy spawn) 2025-06-25 16:00:27.118200 :A97F46AC: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-06-25 16:00:27.118536 :A97F46B5: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-06-25 16:00:27.119351 :A97F46B6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 16:00:28.314566 :A97F4740:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 16:00:28.306 2025-06-25 16:00:28.314569 :A97F4741:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9160 pso_num=29 pso_serial#=173 2025-06-25 16:00:28.314704 :A97F4742:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=9160 osp_idx=27 osp_ver=13432 osp_pg=0 (spawn #321659) 2025-06-25 16:00:28.314704 :A97F4743:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-25 16:00:28.314704 :A97F4744:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=165 time=3842971500 2025-06-25 16:00:28.314705 :A97F4745:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-25 16:00:28.314705 :A97F4746:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-25 16:00:28.314705 :A97F4747:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-25 16:00:28.314705 :A97F4748:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-25 16:00:28.314706 :A97F4749:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-25 16:00:28.315205 :A97F474A: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-06-25 16:00:28.315248 :A97F4750: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-06-25 16:00:28.322923 :A97F475E: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-06-25 16:00:28.324063 :A97F4766: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-06-25 16:00:28.324581 :A97F476E: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-06-25 16:00:28.325042 :A97F4776: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-06-25 16:00:28.325476 :A97F477E: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-06-25 16:00:28.327963 :A97F4786: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-06-25 16:00:28.329082 :A97F478E: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-06-25 16:00:28.379762 :A97F4796: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-06-25 16:00:28.380670 :A97F479E: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-06-25 16:00:28.380909 :A97F47A6: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-06-25 16:00:28.381161 :A97F47AE: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-06-25 16:00:28.383019 :A97F47B6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-25 22:00:02.033442 :A99B9490:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-25 22:00:02.021 2025-06-25 22:00:02.033443 :A99B9491:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17556 pso_num=29 pso_serial#=174 2025-06-25 22:00:02.033584 :A99B9496:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17556 osp_idx=27 osp_ver=13433 osp_pg=0 (spawn #322491) 2025-06-25 22:00:02.033585 :A99B9497:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-25 22:00:02.033586 :A99B9498:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=166 time=3864545219 2025-06-25 22:00:02.033586 :A99B9499:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-25 22:00:02.033587 :A99B949A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-25 22:00:02.033588 :A99B949B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-25 22:00:02.033588 :A99B949C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-25 22:00:02.033588 :A99B949D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-25 22:00:02.034208 :A99B94A3: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-06-25 22:00:02.039110 :A99B94BA: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-06-25 22:00:02.039149 :A99B94BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-06-25 22:00:02.056590 :A99B9689: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-06-25 22:00:02.056786 :A99B9691: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-06-25 22:00:02.056953 :A99B9699: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-06-25 22:00:02.057109 :A99B96A1: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-06-25 22:00:02.057270 :A99B96A9: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-06-25 22:00:02.057422 :A99B96B1: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-06-25 22:00:02.057585 :A99B96B9: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-06-25 22:00:02.057752 :A99B96C1: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-06-25 22:00:02.057901 :A99B96C9: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-06-25 22:00:02.058048 :A99B96D1: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-06-25 22:00:02.058196 :A99B96D9: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-06-25 22:00:02.058357 :A99B96E1: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-06-25 22:00:02.058502 :A99B96E9: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-06-25 22:00:02.058644 :A99B96F1: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-06-25 22:00:02.058810 :A99B96F9: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-06-25 22:00:02.058968 :A99B9701: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-06-25 22:00:02.059118 :A99B9709: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-06-25 22:00:02.059266 :A99B9711: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-06-25 22:00:02.059621 :A99B9719: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-06-25 22:00:02.059787 :A99B9721: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-06-25 22:00:02.059937 :A99B9729: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-06-25 22:00:02.060188 :A99B9731: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-06-25 22:00:02.060423 :A99B9739: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-06-25 22:00:02.060751 :A99B9741: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-06-25 22:00:02.061414 :A99B9749: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-06-25 22:00:02.061638 :A99B9751: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-06-25 22:00:02.084538 :A99B9759: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-06-25 22:00:03.303060*:A99B998D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B443AD0 estart=1750860003 eid=19761682 ctx=0x0000000777968158 cwh=0x00000000292A0AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-25 22:00:03.303060*:A99B998E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B443AD0 curCtx=0x000000004903C4B8 pubTabIdxCnt=0 2025-06-25 22:00:03.303060*:A99B998F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B443AD0 2025-06-25 22:00:03.303060*:A99B9990:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B443AD0 2025-06-25 22:00:03.303060*:A99B9991:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B47AAF8 estart=1750860001 eid=16777413 ctx=0x000000077B54EAC8 cwh=0x00000000292A0608 cpu=1015625 buffg=57112 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-25 22:00:03.303060*:A99B9992:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B47AAF8 curCtx=0x0000000048E51240 pubTabIdxCnt=0 2025-06-25 22:00:03.303060*:A99B9993:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B47AAF8 2025-06-25 22:00:03.303060*:A99B9994:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B47AAF8 2025-06-25 22:00:03.303060*:A99B9995:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B443AD0 curCtx=0x000000004903C4B8 xsc->flg4=65792 flags=1 2025-06-25 22:00:03.303060*:A99B9996:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B443AD0 2025-06-25 22:00:03.756190*:A99B9A28:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B47AAF8 curCtx=0x0000000048E51240 xsc->flg4=65792 flags=2 2025-06-25 22:00:03.756190*:A99B9A29:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B47AAF8 2025-06-25 22:00:03.770795 :A99B9A2A: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-06-25 22:00:03.793411 :A99B9A62: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-06-25 22:00:03.795648 :A99B9A6A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-06-25 22:00:03.795878 :A99B9A6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-06-25 22:00:03.796946 :A99B9A6C: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-06-25 22:00:03.797060 :A99B9A6F: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-06-25 22:00:03.798020 :A99B9A81: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-06-25 22:00:03.799725 :A99B9A89: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-06-25 22:00:03.800097 :A99B9A91: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-06-25 22:00:03.865573*: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-25 22:00:03.865573*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-25 22:00:03.865573*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-25 22:00:03.896821*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-25 22:00:03.896821*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-25 22:00:03.896821*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-25 22:00:03.896821*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-25 22:00:03.968720 :A99B9B0A: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-06-25 22:00:05.906488 :A99B9D04: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-06-25 22:00:05.906503 :A99B9D05:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0007.007.0000c5ab ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 17556, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de