Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250522220955\orcl_j000_9964_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250522220955\orcl_j000_9964_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:37352M/63366M, Ph+PgF:44419M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 9964, image: ORACLE.EXE (J000) *** 2025-05-22 22:09:55.576 *** SESSION ID:(177.5911) 2025-05-22 22:09:55.576 *** 2025-05-22 22:09:55.576 Process diagnostic dump for ORACLE.EXE (J000), OS id=9964, pid: 22, proc_ser: 146, sid: 177, sess_ser: 5911 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9964 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.367817 sec, exc=0.367817 sec, total=0.367817 sec wait times: max=0.500000 sec, heur=16.442497 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=0.515401 sec, exc=0.515401 sec, total=0.515401 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.510098 sec, exc=0.510098 sec, total=0.510098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=0.510104 sec, exc=0.510104 sec, total=0.510104 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time Sampled Session History of session 177 serial 5911 --------------------------------------------------- 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 --------------------------------------------------- [17 samples, 22:09:39 - 22:09:55] idle wait at each sample [session created at: 22:09:39] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-22 22:09:55.576 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 9964, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-22 22:06:16.118032 :9AAACC31:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:06:16.118858 :9AAACC36:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:06:30.341816 :9AAAD09E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:06:30.333 2025-05-22 22:06:30.341818 :9AAAD09F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14288 pso_num=22 pso_serial#=128 2025-05-22 22:06:30.341955 :9AAAD0A0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14288 (legacy spawn) 2025-05-22 22:06:30.348403 :9AAAD0A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:06:30.348570 :9AAAD0A6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:06:30.349369 :9AAAD0AB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:06:36.985970 :9AAAD27C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:06:36.972 2025-05-22 22:06:36.985972 :9AAAD27D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11896 pso_num=22 pso_serial#=129 2025-05-22 22:06:36.986104 :9AAAD27E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11896 (legacy spawn) 2025-05-22 22:06:36.992510 :9AAAD27F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:06:36.992681 :9AAAD288:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:06:36.993489 :9AAAD289:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:06:37.002082 :9AAAD28C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:06:36.988 2025-05-22 22:06:37.002084 :9AAAD28D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10696 pso_num=22 pso_serial#=130 2025-05-22 22:06:37.002254 :9AAAD28E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10696 (legacy spawn) 2025-05-22 22:06:37.008727 :9AAAD28F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:06:37.008892 :9AAAD298:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:06:37.009711 :9AAAD299:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:06:38.821296 :9AAAD335:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:06:38.816 2025-05-22 22:06:38.821298 :9AAAD336:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7776 pso_num=22 pso_serial#=131 2025-05-22 22:06:38.821437 :9AAAD337:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7776 osp_idx=0 osp_ver=227591 osp_pg=0 (spawn #209195) 2025-05-22 22:06:38.821438 :9AAAD338:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 22:06:38.821438 :9AAAD339:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=253 time=927373719 2025-05-22 22:06:38.821439 :9AAAD33A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 22:06:38.821439 :9AAAD33B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 22:06:38.821439 :9AAAD33C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 22:06:38.821440 :9AAAD33D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 22:06:38.821440 :9AAAD33E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 22:06:38.821923 :9AAAD33F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 22:06:38.822573 :9AAAD354:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:06:38.833708 :9AAAD370:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-22 22:06:38.834196 :9AAAD371:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-22 22:06:38.836641 :9AAAD372:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-22 22:06:38.836988 :9AAAD373:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:06:38.837026 :9AAAD376:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-22 22:06:38.837927 :9AAAD37C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:06:38.838058 :9AAAD385:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:07:08.958211 :9AAADC9F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:07:13.191205 :9AAADDD5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:07:13.177 2025-05-22 22:07:13.191207 :9AAADDD6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12580 pso_num=22 pso_serial#=132 2025-05-22 22:07:13.191342 :9AAADDD7:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12580 (legacy spawn) 2025-05-22 22:07:13.197764 :9AAADDD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:07:13.197934 :9AAADDDE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:07:13.198712 :9AAADDE2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:07:13.207274 :9AAADDE5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:07:13.193 2025-05-22 22:07:13.207276 :9AAADDE6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13724 pso_num=22 pso_serial#=133 2025-05-22 22:07:13.207416 :9AAADDE7:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13724 (legacy spawn) 2025-05-22 22:07:13.213931 :9AAADDE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:07:13.214093 :9AAADDF1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:07:13.214884 :9AAADDF2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:07:16.301908 :9AAADEE7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:07:16.288 2025-05-22 22:07:16.301910 :9AAADEE8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11976 pso_num=22 pso_serial#=134 2025-05-22 22:07:16.302057 :9AAADEE9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11976 (legacy spawn) 2025-05-22 22:07:16.308674 :9AAADEEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:07:16.308848 :9AAADEEF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:07:16.309707 :9AAADEF4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:07:16.318730 :9AAADEF7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:07:16.303 2025-05-22 22:07:16.318733 :9AAADEF8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12404 pso_num=22 pso_serial#=135 2025-05-22 22:07:16.318880 :9AAADEF9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12404 (legacy spawn) 2025-05-22 22:07:16.325484 :9AAADEFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:07:16.325656 :9AAADF03:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:07:16.326437 :9AAADF04:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:07:18.329707 :9AAADF88:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:07:18.321 2025-05-22 22:07:18.329709 :9AAADF89:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12436 pso_num=22 pso_serial#=136 2025-05-22 22:07:18.329847 :9AAADF8A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12436 (legacy spawn) 2025-05-22 22:07:18.336385 :9AAADF8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:07:18.336562 :9AAADF90:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:07:18.337464 :9AAADFEE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:07:38.525084 :9AAAE5CC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:07:38.516 2025-05-22 22:07:38.525086 :9AAAE5CD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13488 pso_num=22 pso_serial#=137 2025-05-22 22:07:38.525219 :9AAAE5CE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13488 (legacy spawn) 2025-05-22 22:07:38.531725 :9AAAE5CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:07:38.531926 :9AAAE5D4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:07:38.532746 :9AAAE5D9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:07:38.912371 :9AAAE608:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:07:38.906 2025-05-22 22:07:38.912373 :9AAAE609:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13000 pso_num=22 pso_serial#=138 2025-05-22 22:07:38.912507 :9AAAE60A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13000 osp_idx=0 osp_ver=227598 osp_pg=0 (spawn #209197) 2025-05-22 22:07:38.912507 :9AAAE60B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 22:07:38.912508 :9AAAE60C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=254 time=927433813 2025-05-22 22:07:38.912508 :9AAAE60D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 22:07:38.912508 :9AAAE60E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 22:07:38.912509 :9AAAE60F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 22:07:38.912509 :9AAAE610:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 22:07:38.912510 :9AAAE611:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 22:07:38.912993 :9AAAE612:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 22:07:38.913608 :9AAAE627:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:07:38.921125 :9AAAE643:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-22 22:07:38.921523 :9AAAE644:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:07:38.921559 :9AAAE647:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-22 22:07:38.922403 :9AAAE64D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:07:38.922520 :9AAAE656:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:08:09.056067 :9AAAEFD0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:08:12.727117 :9AAAF109:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:08:12.717 2025-05-22 22:08:12.727119 :9AAAF10A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13028 pso_num=22 pso_serial#=139 2025-05-22 22:08:12.727256 :9AAAF10B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13028 (legacy spawn) 2025-05-22 22:08:12.733685 :9AAAF10C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:08:12.733858 :9AAAF111:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:08:12.734672 :9AAAF116:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:08:13.988295 :9AAAF188:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:08:13.983 2025-05-22 22:08:13.988297 :9AAAF189:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13440 pso_num=22 pso_serial#=140 2025-05-22 22:08:13.988434 :9AAAF18A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13440 osp_idx=0 osp_ver=227600 osp_pg=0 (spawn #209199) 2025-05-22 22:08:13.988434 :9AAAF18B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 22:08:13.988435 :9AAAF18C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=255 time=927468891 2025-05-22 22:08:13.988435 :9AAAF18D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 22:08:13.988435 :9AAAF18E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 22:08:13.988436 :9AAAF18F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 22:08:13.988436 :9AAAF190:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 22:08:13.988436 :9AAAF191:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 22:08:13.988951 :9AAAF192:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 22:08:13.989608 :9AAAF1A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:08:13.995211 :9AAAF1C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:08:13.996212 :9AAAF1CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:08:13.996339 :9AAAF1D4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:08:39.024934 :9AAAF9A0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:08:39.024956*:9AAAF9A2:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCCBB58 estart=1747922918 eid=16873120 ctx=0x000000077BD98A08 cwh=0x000000000FC802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-22 22:08:39.024956*:9AAAF9A3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 pubTabIdxCnt=0 2025-05-22 22:08:39.024956*:9AAAF9A4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCCBB58 2025-05-22 22:08:39.024956*:9AAAF9A5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCCBB58 2025-05-22 22:08:39.024956*:9AAAF9A6:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 xsc->flg4=65792 flags=2 2025-05-22 22:08:39.024956*:9AAAF9A7:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCCBB58 2025-05-22 22:08:39.025509 :9AAAF9A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:08:39.036405 :9AAAF9B0:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-22 22:08:39.036851 :9AAAF9B1:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-22 22:08:39.039213 :9AAAF9B2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-22 22:08:39.039562 :9AAAF9B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:08:39.039598 :9AAAF9B6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-22 22:08:39.040463 :9AAAF9BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:08:39.040585 :9AAAF9C4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:09:09.147472 :9AAB0324:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:09:20.924896 :9AAB06BE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:09:20.916 2025-05-22 22:09:20.924898 :9AAB06BF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14200 pso_num=22 pso_serial#=141 2025-05-22 22:09:20.925036 :9AAB06C0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14200 (legacy spawn) 2025-05-22 22:09:20.931516 :9AAB06C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] 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-22 22:09:20.931566 :9AAB06CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:09:20.931737 :9AAB06DE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:09:20.932546 :9AAB06E5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:09:22.748209 :9AAB07A1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:09:22.740 2025-05-22 22:09:22.748211 :9AAB07A2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10936 pso_num=22 pso_serial#=142 2025-05-22 22:09:22.748353 :9AAB07A3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10936 (legacy spawn) 2025-05-22 22:09:22.754905 :9AAB07A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:09:22.755087 :9AAB07A9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:09:22.755907 :9AAB07AE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:09:38.066600 :9AAB0C20:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:09:38.058 2025-05-22 22:09:38.066602 :9AAB0C21:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14220 pso_num=22 pso_serial#=143 2025-05-22 22:09:38.066738 :9AAB0C22:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14220 (legacy spawn) 2025-05-22 22:09:38.073208 :9AAB0C23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:09:38.073381 :9AAB0C28:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:09:38.074204 :9AAB0C2D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:09:38.401007 :9AAB0C30:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:09:38.386 2025-05-22 22:09:38.401009 :9AAB0C31:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14268 pso_num=22 pso_serial#=144 2025-05-22 22:09:38.401145 :9AAB0C32:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14268 (legacy spawn) 2025-05-22 22:09:38.407716 :9AAB0C33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:09:38.407749 :9AAB0C36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:09:38.407905 :9AAB0C3D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:09:38.408729 :9AAB0C4A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:09:38.417832 :9AAB0C4D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:09:38.417 2025-05-22 22:09:38.417835 :9AAB0C4E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13504 pso_num=22 pso_serial#=145 2025-05-22 22:09:38.417998 :9AAB0C4F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13504 (legacy spawn) 2025-05-22 22:09:38.424840 :9AAB0C50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:09:38.425023 :9AAB0C59:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-22 22:09:38.425919 :9AAB0C5A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-22 22:09:39.131378 :9AAB0C9F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-22 22:09:39.125 2025-05-22 22:09:39.131379 :9AAB0CA0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9964 pso_num=22 pso_serial#=146 2025-05-22 22:09:39.131513 :9AAB0CA1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9964 osp_idx=0 osp_ver=227606 osp_pg=0 (spawn #209201) 2025-05-22 22:09:39.131514 :9AAB0CA2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 22:09:39.131515 :9AAB0CA3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=927554032 2025-05-22 22:09:39.131515 :9AAB0CA4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 22:09:39.131516 :9AAB0CA5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 22:09:39.131516 :9AAB0CA6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 22:09:39.131516 :9AAB0CA7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 22:09:39.131516 :9AAB0CA8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 22:09:39.132003 :9AAB0CA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 22:09:39.132716 :9AAB0CBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:09:39.140159 :9AAB0CDB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-22 22:09:39.140628 :9AAB0CDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:09:39.140665 :9AAB0CDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-22 22:09:39.141558 :9AAB0CE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:09:39.141681 :9AAB0CEE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 9964, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de