Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504180206\orcl_j000_11916_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504180206\orcl_j000_11916_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:37574M/63366M, Ph+PgF:45103M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 11916, image: ORACLE.EXE (J000) *** 2025-05-04 18:02:06.099 *** SESSION ID:(193.57731) 2025-05-04 18:02:06.099 *** 2025-05-04 18:02:06.099 Process diagnostic dump for ORACLE.EXE (J000), OS id=11916, pid: 24, proc_ser: 27, sid: 193, sess_ser: 57731 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11916 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12383, hash value=3722798525 Current Wait Stack: Not in wait; last wait ended 2.355660 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.355685 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.000165 sec, exc=0.000165 sec, total=0.000165 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.053370 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.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.757956 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.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.024873 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.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.012889 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.000111 sec, exc=0.000111 sec, total=0.000111 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049530 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.003075 sec, exc=0.003075 sec, total=0.003075 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007831 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.000125 sec, exc=0.000125 sec, total=0.000125 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.261437 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.005746 sec, exc=0.005746 sec, total=0.005746 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000179 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.000112 sec, exc=0.000112 sec, total=0.000112 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008272 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.000060 sec of elapsed time Sampled Session History of session 193 serial 57731 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 18:02:02 - 18:02:05] not in wait at each sample [1 sample, 18:02:01] idle wait at each sample [session created at: 18:02:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-04 18:02:06.099 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 11916, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 18:00:01.673530 :92AB950C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 18:00:01.674124 :92AB9512:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 18:00:01.674250 :92AB9513:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:01.674268 :92AB9516:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 18:00:01.677980 :92AB9524:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:01.678074 :92AB9527:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 18:00:01.678304 :92AB952D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:01.678325 :92AB9530:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 18:00:01.680605 :92AB953E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:01.680709 :92AB9547:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:00:31.767411 :92AB9FA0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:00:39.150284 :92ABA1F2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:00:39.141 2025-05-04 18:00:39.150286 :92ABA1F3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11288 pso_num=24 pso_serial#=12 2025-05-04 18:00:39.150427 :92ABA1F4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11288 (legacy spawn) 2025-05-04 18:00:39.156911 :92ABA1F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:39.157101 :92ABA1FA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:00:39.157896 :92ABA1FF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:00:45.711550 :92ABA3EB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:00:45.697 2025-05-04 18:00:45.711552 :92ABA3EC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11428 pso_num=24 pso_serial#=13 2025-05-04 18:00:45.711681 :92ABA3ED:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11428 (legacy spawn) 2025-05-04 18:00:45.718095 :92ABA3EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:45.718271 :92ABA3F3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:00:45.719111 :92ABA3F8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:00:45.727640 :92ABA3FB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:00:45.712 2025-05-04 18:00:45.727642 :92ABA3FC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9800 pso_num=24 pso_serial#=14 2025-05-04 18:00:45.727778 :92ABA3FD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9800 (legacy spawn) 2025-05-04 18:00:45.734228 :92ABA3FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:45.734387 :92ABA407:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:00:45.735158 :92ABA408:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:00:48.832119 :92ABA503:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:00:48.822 2025-05-04 18:00:48.832121 :92ABA504:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7712 pso_num=24 pso_serial#=15 2025-05-04 18:00:48.832259 :92ABA505:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7712 (legacy spawn) 2025-05-04 18:00:48.838687 :92ABA506:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:48.838854 :92ABA50B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:00:48.839724 :92ABA510:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:00:48.848292 :92ABA513:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:00:48.837 2025-05-04 18:00:48.848294 :92ABA514:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11464 pso_num=24 pso_serial#=16 2025-05-04 18:00:48.848429 :92ABA515:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11464 (legacy spawn) 2025-05-04 18:00:48.854857 :92ABA516:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:48.855024 :92ABA51F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:00:48.855841 :92ABA520:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:00:51.956033 :92ABA618:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:00:51.948 2025-05-04 18:00:51.956034 :92ABA619:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10164 pso_num=24 pso_serial#=17 2025-05-04 18:00:51.956177 :92ABA61A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10164 (legacy spawn) 2025-05-04 18:00:51.962596 :92ABA61B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:51.962773 :92ABA620:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:00:51.963586 :92ABA625:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:00:53.905301 :92ABA700:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:00:53.893 2025-05-04 18:00:53.905303 :92ABA701:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9600 pso_num=24 pso_serial#=18 2025-05-04 18:00:53.905446 :92ABA702:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9600 (legacy spawn) 2025-05-04 18:00:53.911885 :92ABA703:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:53.912050 :92ABA708:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:00:53.912840 :92ABA70D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:00:53.921441 :92ABA710:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:00:53.908 2025-05-04 18:00:53.921443 :92ABA711:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=5020 pso_num=24 pso_serial#=19 2025-05-04 18:00:53.921573 :92ABA712:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=5020 (legacy spawn) 2025-05-04 18:00:53.928039 :92ABA713:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:53.928203 :92ABA71C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:00:53.929001 :92ABA71D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:01:01.585429 :92ABA92F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:01:01.579 2025-05-04 18:01:01.585431 :92ABA930:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11988 pso_num=24 pso_serial#=20 2025-05-04 18:01:01.585572 :92ABA931:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11988 osp_idx=22 osp_ver=223399 osp_pg=0 (spawn #148174) 2025-05-04 18:01:01.585573 :92ABA932:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 18:01:01.585573 :92ABA933:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=180 time=3652438750 2025-05-04 18:01:01.585574 :92ABA934:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 18:01:01.585574 :92ABA935:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 18:01:01.585574 :92ABA936:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 18:01:01.585575 :92ABA937:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 18:01:01.585575 :92ABA938:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 18:01:01.586069 :92ABA939:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 18:01:01.586731 :92ABA94E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:01:01.594240 :92ABA96A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 18:01:01.594625 :92ABA96B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:01:01.594662 :92ABA96E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 18:01:01.595522 :92ABA974:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:01:01.595643 :92ABA97D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:01:31.691565 :92ABB35B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:01:47.643239 :92ABBB66:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:01:47.632 2025-05-04 18:01:47.643241 :92ABBB67:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11868 pso_num=24 pso_serial#=21 2025-05-04 18:01:47.643387 :92ABBB68:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11868 (legacy spawn) 2025-05-04 18:01:47.650010 :92ABBB69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:01:47.650184 :92ABBB72:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:01:47.651006 :92ABBB73:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:01:54.187908 :92ABBD99:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:01:54.180 2025-05-04 18:01:54.187909 :92ABBD9A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11748 pso_num=24 pso_serial#=22 2025-05-04 18:01:54.188045 :92ABBD9B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11748 (legacy spawn) 2025-05-04 18:01:54.194455 :92ABBD9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:01:54.194621 :92ABBDA1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:01:54.195456 :92ABBDA6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:01:58.336627 :92ABBEA7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:01:58.322 2025-05-04 18:01:58.336629 :92ABBEA8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9064 pso_num=24 pso_serial#=23 2025-05-04 18:01:58.336762 :92ABBEA9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9064 (legacy spawn) 2025-05-04 18:01:58.343762 :92ABBEAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:01:58.343936 :92ABBEAF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:01:58.344755 :92ABBEB4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:01:58.353357 :92ABBEB7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:01:58.337 2025-05-04 18:01:58.353359 :92ABBEB8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3560 pso_num=24 pso_serial#=24 2025-05-04 18:01:58.353583 :92ABBEB9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3560 (legacy spawn) 2025-05-04 18:01:58.359992 :92ABBEBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:01:58.360160 :92ABBEC3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:01:58.360966 :92ABBEC4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:02:01.435857 :92ABBFB1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:02:01.433 2025-05-04 18:02:01.435859 :92ABBFB2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11440 pso_num=24 pso_serial#=25 2025-05-04 18:02:01.436000 :92ABBFB3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11440 (legacy spawn) 2025-05-04 18:02:01.442407 :92ABBFB4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.442579 :92ABBFB9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:02:01.443403 :92ABBFBE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:02:01.452046 :92ABBFC1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:02:01.449 2025-05-04 18:02:01.452049 :92ABBFC2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9468 pso_num=24 pso_serial#=26 2025-05-04 18:02:01.452185 :92ABBFC3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9468 (legacy spawn) 2025-05-04 18:02:01.458613 :92ABBFC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.458777 :92ABBFCD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:02:01.459562 :92ABBFCE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 18:02:01.658164 :92ABBFF5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 18:02:01.652 2025-05-04 18:02:01.658166 :92ABBFF6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11916 pso_num=24 pso_serial#=27 2025-05-04 18:02:01.658300 :92ABBFF7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11916 osp_idx=22 osp_ver=223406 osp_pg=0 (spawn #148176) 2025-05-04 18:02:01.658301 :92ABBFF8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 18:02:01.658301 :92ABBFF9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=181 time=3652498828 2025-05-04 18:02:01.658302 :92ABBFFA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 18:02:01.658302 :92ABBFFB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 18:02:01.658302 :92ABBFFC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 18:02:01.658303 :92ABBFFD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 18:02:01.658303 :92ABBFFE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 18:02:01.658791 :92ABBFFF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.659420 :92ABC014:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.670498 :92ABC030:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-04 18:02:01.670950 :92ABC031:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-04 18:02:01.673341 :92ABC032:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 18:02:01.673675 :92ABC033:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.673710 :92ABC036:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 18:02:01.674587 :92ABC03C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.674710 :92ABC045:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 18:02:01.976570 :92ABC209:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-04 18:02:01.978437 :92ABC245:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.978460 :92ABC248:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-04 18:02:01.996220 :92ABC251:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.996462 :92ABC259:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.996625 :92ABC261:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.996776 :92ABC269:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.996937 :92ABC271:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.997084 :92ABC279:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.997257 :92ABC281:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.997437 :92ABC289:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.997587 :92ABC291:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.997883 :92ABC299:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.998125 :92ABC2A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:02.016389 :92ABC2A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:02.735417*:92ABC34C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026B93AD0 estart=1746352922 eid=18110585 ctx=0x0000000777968158 cwh=0x0000000028D80068 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-04 18:02:02.735417*:92ABC34D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026B93AD0 curCtx=0x0000000028F0C8D0 pubTabIdxCnt=0 2025-05-04 18:02:02.735417*:92ABC34E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026B93AD0 2025-05-04 18:02:02.735417*:92ABC34F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026B93AD0 2025-05-04 18:02:02.735417*:92ABC350:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BFB820 estart=1746352921 eid=16777304 ctx=0x000000077B54EAC8 cwh=0x0000000024980608 cpu=562500 buffg=30083 interb=180224 r=22 rb=180224 w=0 wb=0 2025-05-04 18:02:02.735417*:92ABC351:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BFB820 curCtx=0x0000000028E4A6D0 pubTabIdxCnt=0 2025-05-04 18:02:02.735417*:92ABC352:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BFB820 2025-05-04 18:02:02.735417*:92ABC353:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BFB820 2025-05-04 18:02:02.735417*:92ABC354:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026B93AD0 curCtx=0x0000000028F0C8D0 xsc->flg4=65792 flags=1 2025-05-04 18:02:02.735417*:92ABC355:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026B93AD0 2025-05-04 18:02:03.666308 :92ABC410:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] 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-05-04 18:02:03.666308*:92ABC417:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BFB820 curCtx=0x0000000028E4A6D0 xsc->flg4=65792 flags=2 2025-05-04 18:02:03.666308*:92ABC418:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BFB820 2025-05-04 18:02:03.670520 :92ABC419:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.692645 :92ABC43B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.694768 :92ABC443:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.694986 :92ABC444:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.696004 :92ABC445:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.696110 :92ABC448:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.697054 :92ABC45A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.698744 :92ABC462:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.699043 :92ABC46A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.751036*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-04 18:02:03.751036*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-04 18:02:03.751036*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-04 18:02:03.751036*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-04 18:02:03.751036*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-04 18:02:03.751036*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-04 18:02:03.751036*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-04 18:02:03.821379 :92ABC4E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:05.738598 :92ABC5B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:05.738610 :92ABC5B5:db_trace:ktur.c@3079:ktuabt(): [10444:24:193] ABORT TRANSACTION - xid: 0x000a.009.0016765d ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 11916, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de