Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706060006\orcl_j002_17876_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706060006\orcl_j002_17876_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:37140M/63366M, Ph+PgF:43154M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 17876, image: ORACLE.EXE (J002) *** 2025-07-06 06:00:06.125 *** SESSION ID:(219.7829) 2025-07-06 06:00:06.125 *** 2025-07-06 06:00:06.125 Process diagnostic dump for ORACLE.EXE (J002), OS id=17876, pid: 27, proc_ser: 247, sid: 219, sess_ser: 7829 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17876 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12709, hash value=3644558267 Current Wait Stack: Not in wait; last wait ended 2.259101 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.259125 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.000146 sec, exc=0.000146 sec, total=0.000146 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.058066 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.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.039509 sec of elapsed time 2: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000155 sec, exc=0.000155 sec, total=0.000155 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.711622 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.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.025007 sec of elapsed time 4: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.013115 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.004982 sec, exc=0.004982 sec, total=0.004982 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049638 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.001743 sec, exc=0.001743 sec, total=0.001743 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007982 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.001696 sec, exc=0.001696 sec, total=0.001696 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.261069 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.000134 sec, exc=0.000134 sec, total=0.000134 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000310 sec of elapsed time 9: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.006599 sec, exc=0.006599 sec, total=0.006599 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007818 sec of elapsed time Sampled Session History of session 219 serial 7829 --------------------------------------------------- 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, 06:00:02 - 06:00:06] not in wait at each sample [1 sample, 06:00:01] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-06 06:00:06.125 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 17876, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-06 01:58:37.603387 :AE1B2B4D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 01:58:37.612352 :AE1B2B50:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 01:58:37.598 2025-07-06 01:58:37.612354 :AE1B2B51:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13548 pso_num=27 pso_serial#=237 2025-07-06 01:58:37.612488 :AE1B2B52:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13548 (legacy spawn) 2025-07-06 01:58:37.619199 :AE1B2B53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 01:58:37.619359 :AE1B2B5C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 01:58:37.620167 :AE1B2B5D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 01:59:26.522198 :AE1B3C0B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 01:59:26.512 2025-07-06 01:59:26.522201 :AE1B3C0C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16392 pso_num=27 pso_serial#=238 2025-07-06 01:59:26.522345 :AE1B3C0D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16392 (legacy spawn) 2025-07-06 01:59:26.529288 :AE1B3C19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 01:59:26.529456 :AE1B3C22:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 01:59:26.530256 :AE1B3C23:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 01:59:28.845831 :AE1B3CFD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 01:59:28.837 2025-07-06 01:59:28.845833 :AE1B3CFE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18376 pso_num=27 pso_serial#=239 2025-07-06 01:59:28.845980 :AE1B3CFF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18376 (legacy spawn) 2025-07-06 01:59:28.852567 :AE1B3D00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 01:59:28.852754 :AE1B3D05:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 01:59:28.853543 :AE1B3D0A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 01:59:33.430417 :AE1B3E89:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 01:59:33.425 2025-07-06 01:59:33.430419 :AE1B3E8A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4196 pso_num=27 pso_serial#=240 2025-07-06 01:59:33.430553 :AE1B3E8B:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=4196 osp_idx=25 osp_ver=134888 osp_pg=0 (spawn #356388) 2025-07-06 01:59:33.430553 :AE1B3E8C:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 01:59:33.430554 :AE1B3E8D:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=66 time=447949329 2025-07-06 01:59:33.430554 :AE1B3E8E:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 01:59:33.430555 :AE1B3E8F:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 01:59:33.430555 :AE1B3E90:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 01:59:33.430555 :AE1B3E91:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 01:59:33.430556 :AE1B3E92:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 01:59:33.431071 :AE1B3E93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 01:59:33.431116 :AE1B3E99:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 01:59:33.435356 :AE1B3EA8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-06 01:59:33.435405 :AE1B3EA9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-06 01:59:33.435671 :AE1B3EAA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-06 01:59:33.435688 :AE1B3EAB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-06 01:59:33.435913 :AE1B3EAC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-06 01:59:33.435927 :AE1B3EAD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-06 01:59:33.436137 :AE1B3EAE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-06 01:59:33.436149 :AE1B3EAF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-06 01:59:33.436357 :AE1B3EB0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-06 01:59:33.436369 :AE1B3EB1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-06 01:59:33.436567 :AE1B3EB2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-06 01:59:33.436580 :AE1B3EB3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-06 01:59:33.439166 :AE1B3EB4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 01:59:37.015874 :AE1B3FB9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 01:59:37.007 2025-07-06 01:59:37.015877 :AE1B3FBA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2768 pso_num=27 pso_serial#=241 2025-07-06 01:59:37.016018 :AE1B3FBB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2768 (legacy spawn) 2025-07-06 01:59:37.022588 :AE1B3FBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 01:59:37.022808 :AE1B3FE8:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 01:59:37.023684 :AE1B3FE9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 01:59:47.010672 :AE1B430B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 01:59:46.995 2025-07-06 01:59:47.010674 :AE1B430C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4876 pso_num=27 pso_serial#=242 2025-07-06 01:59:47.010815 :AE1B430D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4876 (legacy spawn) 2025-07-06 01:59:47.017579 :AE1B430E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 01:59:47.017773 :AE1B4313:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 01:59:47.018599 :AE1B4318:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 01:59:47.027782 :AE1B431B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 01:59:47.027 2025-07-06 01:59:47.027784 :AE1B431C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16880 pso_num=27 pso_serial#=243 2025-07-06 01:59:47.027932 :AE1B431D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16880 (legacy spawn) 2025-07-06 01:59:47.034730 :AE1B431E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 01:59:47.034910 :AE1B4327:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 01:59:47.035777 :AE1B4328:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 01:59:50.123024 :AE1B4413:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 01:59:50.109 2025-07-06 01:59:50.123025 :AE1B4414:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14600 pso_num=27 pso_serial#=244 2025-07-06 01:59:50.123163 :AE1B4415:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14600 (legacy spawn) 2025-07-06 01:59:50.129912 :AE1B4416:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 01:59:50.130091 :AE1B441B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 01:59:50.130901 :AE1B441F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 01:59:50.139742 :AE1B4422:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 01:59:50.125 2025-07-06 01:59:50.139744 :AE1B4423:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6552 pso_num=27 pso_serial#=245 2025-07-06 01:59:50.139889 :AE1B4424:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6552 (legacy spawn) 2025-07-06 01:59:50.146623 :AE1B4425:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 01:59:50.146791 :AE1B442E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 01:59:50.147619 :AE1B442F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 06:00:00.030806 :AE2E8570:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 06:00:00.022 2025-07-06 06:00:00.030808 :AE2E8571:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4688 pso_num=27 pso_serial#=246 2025-07-06 06:00:00.030948 :AE2E8572:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=4688 osp_idx=25 osp_ver=134894 osp_pg=0 (spawn #356943) 2025-07-06 06:00:00.030949 :AE2E8573:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 06:00:00.030949 :AE2E8574:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=67 time=462375923 2025-07-06 06:00:00.030950 :AE2E8575:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 06:00:00.030950 :AE2E8576:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 06:00:00.030951 :AE2E8577:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 06:00:00.030951 :AE2E8578:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 06:00:00.030951 :AE2E8579:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 06:00:00.031463 :AE2E857A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.031508 :AE2E8581:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 06:00:00.031585 :AE2E8595:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.035458 :AE2E85A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.036699 :AE2E85AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.036725 :AE2E85AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.036764 :AE2E85BA:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-06 06:00:00.038670 :AE2E85C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.038693 :AE2E85C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.038726 :AE2E85D3:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-06 06:00:00.039194 :AE2E85DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.041569 :AE2E85E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.046925 :AE2E85E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.053559 :AE2E85F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.054619 :AE2E8601:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.057229 :AE2E8609:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.058608 :AE2E8611:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.061633 :AE2E8619:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.065638 :AE2E8621:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.065996 :AE2E8630:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.066049 :AE2E8633:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-07-06 06:00:00.066063 :AE2E8634:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-07-06 06:00:00.068877 :AE2E863A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.069187 :AE2E8649:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.069236 :AE2E864C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-07-06 06:00:00.069251 :AE2E864D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-07-06 06:00:00.072060 :AE2E8653:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.072358 :AE2E8662:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.072409 :AE2E8665:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-07-06 06:00:00.072425 :AE2E8666:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-07-06 06:00:00.072608 :AE2E866C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.072620 :AE2E866F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-06 06:00:00.072646 :AE2E867A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-06 06:00:00.074170 :AE2E8685:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 06:00:02.037158 :AE2E878B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 06:00:02.031 2025-07-06 06:00:02.037159 :AE2E878C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17876 pso_num=27 pso_serial#=247 2025-07-06 06:00:02.037306 :AE2E878D:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17876 osp_idx=25 osp_ver=134895 osp_pg=0 (spawn #356944) 2025-07-06 06:00:02.037307 :AE2E878E:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 06:00:02.037308 :AE2E878F:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=68 time=462377923 2025-07-06 06:00:02.037309 :AE2E8790:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 06:00:02.037309 :AE2E8791:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 06:00:02.037310 :AE2E8792:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 06:00:02.037310 :AE2E8793:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 06:00:02.037311 :AE2E8794:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 06:00:02.037864 :AE2E879A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.043062 :AE2E87C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.043103 :AE2E87C7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-07-06 06:00:02.060736 :AE2E8857:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.060946 :AE2E885F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.061111 :AE2E8867:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.061266 :AE2E886F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.061428 :AE2E8877:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.061591 :AE2E887F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.061817 :AE2E8887:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.062047 :AE2E888F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.062202 :AE2E8897:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.062363 :AE2E889F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.062530 :AE2E88B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.062722 :AE2E88BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.062876 :AE2E88CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.063070 :AE2E88D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.063267 :AE2E88DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.063418 :AE2E88E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.063556 :AE2E88F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.063580 :AE2E88F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.063709 :AE2E88FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.063733 :AE2E88FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.064030 :AE2E8911:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.064183 :AE2E8919:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.064330 :AE2E8923:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:02.087459 :AE2E893C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:03.791264 :AE2E8DDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:03.811094 :AE2E8DE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=30 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-07-06 06:00:03.811224 :AE2E8DE4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:03.813265 :AE2E8DEC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:psdpgi.c:2235 2025-07-06 06:00:03.813465 :AE2E8DED:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:psdpgi.c:2235 2025-07-06 06:00:03.814500 :AE2E8DEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:03.814621 :AE2E8DF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:03.815511 :AE2E8E03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:03.816938 :AE2E8E0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:03.817215 :AE2E8E13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:03.875262*: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-06 06:00:03.875262*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-06 06:00:03.875262*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-06 06:00:03.875262*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-06 06:00:03.875262*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-06 06:00:03.875262*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-06 06:00:03.875262*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-06 06:00:03.922131*:AE2E8E1B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000004C1B0B90 estart=1751752802 eid=16778026 ctx=0x000000079F4EAD08 cwh=0x000000002B410158 cpu=15625 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-07-06 06:00:03.922131*:AE2E8E1C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000004C1B0B90 curCtx=0x0000000033F72DC8 pubTabIdxCnt=0 2025-07-06 06:00:03.922131*:AE2E8E1D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000004C1B0B90 2025-07-06 06:00:03.922131*:AE2E8E1E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000004C1B0B90 2025-07-06 06:00:03.922131*:AE2E8E27:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000004C1B0B90 curCtx=0x0000000033F72DC8 xsc->flg4=65792 flags=1 2025-07-06 06:00:03.922131*:AE2E8E28:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000004C1B0B90 2025-07-06 06:00:03.943991 :AE2E8E2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:05.843513 :AE2E8F65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 06:00:05.843528 :AE2E8F66:db_trace:ktur.c@3079:ktuabt(): [10444:27:219] ABORT TRANSACTION - xid: 0x0009.015.0001d13d ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 17876, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de