Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250819220008\orcl_j000_21392_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250819220008\orcl_j000_21392_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:35336M/63366M, Ph+PgF:40533M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 21392, image: ORACLE.EXE (J000) *** 2025-08-19 22:00:08.701 *** SESSION ID:(177.27131) 2025-08-19 22:00:08.701 *** 2025-08-19 22:00:08.701 Process diagnostic dump for ORACLE.EXE (J000), OS id=21392, pid: 22, proc_ser: 59, sid: 177, sess_ser: 27131 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21392 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.227002 sec, exc=0.227002 sec, total=0.227002 sec wait times: max=0.500000 sec, heur=4.676891 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499010 sec, exc=0.499010 sec, total=0.499010 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.454108 sec, exc=0.454108 sec, total=0.454108 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.510096 sec, exc=0.510096 sec, total=0.510096 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.486603 sec, exc=0.486603 sec, total=0.486603 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 177 serial 27131 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 22:00:04 - 22:00:08] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-19 22:00:08.701 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 21392, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-19 21:57:22.449645 :C1FC53A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:57:38.702180 :C1FC5907:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:57:38.688 2025-08-19 21:57:38.702182 :C1FC5908:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17204 pso_num=22 pso_serial#=43 2025-08-19 21:57:38.702320 :C1FC5909:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17204 (legacy spawn) 2025-08-19 21:57:38.708772 :C1FC590A: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-08-19 21:57:38.708954 :C1FC5912: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-08-19 21:57:38.709738 :C1FC5914:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:57:38.718440 :C1FC5917:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:57:38.703 2025-08-19 21:57:38.718442 :C1FC5918:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15056 pso_num=22 pso_serial#=44 2025-08-19 21:57:38.718576 :C1FC5919:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15056 (legacy spawn) 2025-08-19 21:57:38.724999 :C1FC591A: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-08-19 21:57:38.725164 :C1FC591F: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-08-19 21:57:38.725981 :C1FC5924:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:57:41.786216 :C1FC5A12:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:57:41.773 2025-08-19 21:57:41.786218 :C1FC5A13:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20520 pso_num=22 pso_serial#=45 2025-08-19 21:57:41.786352 :C1FC5A14:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20520 (legacy spawn) 2025-08-19 21:57:41.792834 :C1FC5A20: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-08-19 21:57:41.793006 :C1FC5A25: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-08-19 21:57:41.793783 :C1FC5A2A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:57:41.812526 :C1FC5A2D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:57:41.804 2025-08-19 21:57:41.812528 :C1FC5A2E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18368 pso_num=22 pso_serial#=46 2025-08-19 21:57:41.812668 :C1FC5A2F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18368 (legacy spawn) 2025-08-19 21:57:41.819234 :C1FC5A30: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-08-19 21:57:41.819398 :C1FC5A39: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-08-19 21:57:41.820210 :C1FC5A3A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:57:52.329381 :C1FC5D25:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:57:52.323 2025-08-19 21:57:52.329383 :C1FC5D26:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19264 pso_num=22 pso_serial#=47 2025-08-19 21:57:52.329521 :C1FC5D27:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19264 osp_idx=0 osp_ver=535874 osp_pg=0 (spawn #505813) 2025-08-19 21:57:52.329522 :C1FC5D28:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:57:52.329522 :C1FC5D29:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=224 time=26374346 2025-08-19 21:57:52.329523 :C1FC5D2A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:57:52.329523 :C1FC5D2B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:57:52.329523 :C1FC5D2C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:57:52.329524 :C1FC5D2D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:57:52.329524 :C1FC5D2E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:57:52.330084 :C1FC5D2F: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-08-19 21:57:52.330730 :C1FC5D44: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-08-19 21:57:52.338270 :C1FC5D60:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-19 21:57:52.338638 :C1FC5D61: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-08-19 21:57:52.338675 :C1FC5D64:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-19 21:57:52.339569 :C1FC5D6A: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-08-19 21:57:52.339684 :C1FC5D73: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-08-19 21:58:22.422994 :C1FC66B5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:58:23.430919 :C1FC6768:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:58:23.423 2025-08-19 21:58:23.430920 :C1FC6769:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21720 pso_num=22 pso_serial#=48 2025-08-19 21:58:23.431055 :C1FC676A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21720 (legacy spawn) 2025-08-19 21:58:23.455640 :C1FC676B: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-08-19 21:58:23.455822 :C1FC6770: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-08-19 21:58:23.456687 :C1FC6775:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:58:29.027835 :C1FC6917:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:58:29.018 2025-08-19 21:58:29.027837 :C1FC6918:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18712 pso_num=22 pso_serial#=49 2025-08-19 21:58:29.027973 :C1FC6919:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18712 (legacy spawn) 2025-08-19 21:58:29.034504 :C1FC691A: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-08-19 21:58:29.034681 :C1FC691F: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-08-19 21:58:29.035504 :C1FC6924:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:58:51.181311 :C1FC6FEB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:58:51.166 2025-08-19 21:58:51.181313 :C1FC6FEC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19376 pso_num=22 pso_serial#=50 2025-08-19 21:58:51.181461 :C1FC6FED:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19376 (legacy spawn) 2025-08-19 21:58:51.188242 :C1FC6FEE: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-08-19 21:58:51.188425 :C1FC6FF4: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-08-19 21:58:51.189363 :C1FC6FF8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:58:51.198785 :C1FC6FFB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:58:51.197 2025-08-19 21:58:51.198787 :C1FC6FFC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3328 pso_num=22 pso_serial#=51 2025-08-19 21:58:51.198929 :C1FC6FFD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=3328 (legacy spawn) 2025-08-19 21:58:51.205810 :C1FC6FFE: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-08-19 21:58:51.205992 :C1FC7007: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-08-19 21:58:51.206895 :C1FC7008:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:58:52.421827 :C1FC7033:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:58:52.416 2025-08-19 21:58:52.421829 :C1FC7034:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16248 pso_num=22 pso_serial#=52 2025-08-19 21:58:52.421965 :C1FC7035:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16248 osp_idx=0 osp_ver=535879 osp_pg=0 (spawn #505815) 2025-08-19 21:58:52.421965 :C1FC7036:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:58:52.421966 :C1FC7037:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=225 time=26434424 2025-08-19 21:58:52.421966 :C1FC7038:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:58:52.421966 :C1FC7039:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:58:52.421967 :C1FC703A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:58:52.421967 :C1FC703B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:58:52.421967 :C1FC703C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:58:52.422454 :C1FC703D: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-08-19 21:58:52.423086 :C1FC7052: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-08-19 21:58:52.434132 :C1FC706F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-19 21:58:52.434591 :C1FC7070:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-19 21:58:52.436986 :C1FC7071:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-19 21:58:52.437336 :C1FC7072: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-08-19 21:58:52.437375 :C1FC7075:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-19 21:58:52.438227 :C1FC707B: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-08-19 21:58:52.438348 :C1FC7083: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-08-19 21:59:22.538264 :C1FC7A4A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:59:25.670691 :C1FC7B32:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:59:25.657 2025-08-19 21:59:25.670693 :C1FC7B33:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21992 pso_num=22 pso_serial#=53 2025-08-19 21:59:25.670843 :C1FC7B34:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21992 (legacy spawn) 2025-08-19 21:59:25.677391 :C1FC7B35: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-08-19 21:59:25.677563 :C1FC7B3A: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-08-19 21:59:25.678356 :C1FC7B3F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:59:27.681846 :C1FC7C27:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:59:27.667 2025-08-19 21:59:27.681848 :C1FC7C28:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16692 pso_num=22 pso_serial#=54 2025-08-19 21:59:27.681984 :C1FC7C29:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16692 (legacy spawn) 2025-08-19 21:59:27.688436 :C1FC7C2A: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-08-19 21:59:27.688631 :C1FC7C2F: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-08-19 21:59:27.689462 :C1FC7C34:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:59:27.698310 :C1FC7C37:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:59:27.683 2025-08-19 21:59:27.698312 :C1FC7C38:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18472 pso_num=22 pso_serial#=55 2025-08-19 21:59:27.698468 :C1FC7C39:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18472 (legacy spawn) 2025-08-19 21:59:27.704914 :C1FC7C3A: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-08-19 21:59:27.705085 :C1FC7C43: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-08-19 21:59:27.705879 :C1FC7C44:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:59:30.794976 :C1FC7D27:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:59:30.781 2025-08-19 21:59:30.794978 :C1FC7D28:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13804 pso_num=22 pso_serial#=56 2025-08-19 21:59:30.795111 :C1FC7D29:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13804 (legacy spawn) 2025-08-19 21:59:30.801553 :C1FC7D2A: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-08-19 21:59:30.801726 :C1FC7D2F: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-08-19 21:59:30.802523 :C1FC7D34:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:59:30.811159 :C1FC7D37:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:59:30.797 2025-08-19 21:59:30.811161 :C1FC7D38:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20296 pso_num=22 pso_serial#=57 2025-08-19 21:59:30.811297 :C1FC7D39:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20296 (legacy spawn) 2025-08-19 21:59:30.817802 :C1FC7D3A: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-08-19 21:59:30.817959 :C1FC7D43: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-08-19 21:59:30.818769 :C1FC7D44:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:59:37.323399 :C1FC7F1F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:59:37.314 2025-08-19 21:59:37.323402 :C1FC7F20:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19388 pso_num=22 pso_serial#=58 2025-08-19 21:59:37.323573 :C1FC7F21:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19388 (legacy spawn) 2025-08-19 21:59:37.349819 :C1FC7F22: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-08-19 21:59:37.349999 :C1FC7F27: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-08-19 21:59:37.350847 :C1FC7F2C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-19 21:59:52.477677 :C1FC83D6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 21:59:52.471 2025-08-19 21:59:52.477679 :C1FC83D7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21392 pso_num=22 pso_serial#=59 2025-08-19 21:59:52.477814 :C1FC83D8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21392 osp_idx=0 osp_ver=535886 osp_pg=0 (spawn #505818) 2025-08-19 21:59:52.477815 :C1FC83D9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:59:52.477815 :C1FC83DA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=226 time=26494487 2025-08-19 21:59:52.477816 :C1FC83DB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:59:52.477816 :C1FC83DC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:59:52.477816 :C1FC83DD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:59:52.477817 :C1FC83DE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:59:52.477817 :C1FC83DF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:59:52.478306 :C1FC83E0: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-08-19 21:59:52.478954 :C1FC83F5: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-08-19 21:59:52.486413 :C1FC8411:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-19 21:59:52.486779 :C1FC8412: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-08-19 21:59:52.486817 :C1FC8415:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-19 21:59:52.487651 :C1FC841B: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-08-19 21:59:52.487781 :C1FC8424: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-08-19 22:00:02.041465 :C1FC88C7: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-08-19 22:00:02.041465*:C1FC88E8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCDC718 estart=1755612001 eid=16786397 ctx=0x000000077FD6A000 cwh=0x000000000FCD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-19 22:00:02.041465*:C1FC88E9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 pubTabIdxCnt=0 2025-08-19 22:00:02.041465*:C1FC88EA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCDC718 2025-08-19 22:00:02.041465*:C1FC88EB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCDC718 2025-08-19 22:00:02.047228 :C1FC88EF: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-08-19 22:00:02.047228*:C1FC8905:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 xsc->flg4=65792 flags=2 2025-08-19 22:00:02.047228*:C1FC8907:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCDC718 2025-08-19 22:00:02.048266 :C1FC890E: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-08-19 22:00:02.048321 :C1FC8911:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-08-19 22:00:02.059596 :C1FC8967: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-08-19 22:00:02.064660 :C1FC8996: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-08-19 22:00:02.065655 :C1FC899F: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-08-19 22:00:02.071588 :C1FC8B54:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.071730 :C1FC8B6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.071995 :C1FC8B7E: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-08-19 22:00:02.072682 :C1FC8B9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-08-19 22:00:02.074080 :C1FC8BD1: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-08-19 22:00:02.074123 :C1FC8BD3: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-08-19 22:00:02.074157 :C1FC8BDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.074239 :C1FC8BE7: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-08-19 22:00:02.166534 :C1FC8C7E: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-08-19 22:00:02.168755 :C1FC8C83: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-08-19 22:00:02.168800 :C1FC8C86:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-08-19 22:00:02.171383 :C1FC8C8C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-08-19 22:00:02.174729 :C1FC8C8D: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-08-19 22:00:02.174828 :C1FC8C90:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.174905 :C1FC8C9D: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-08-19 22:00:04.019606 :C1FC905F: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-08-19 22:00:04.021605 :C1FC9064: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-08-19 22:00:04.021644 :C1FC9067:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-08-19 22:00:04.028215 :C1FC906D: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-08-19 22:00:04.028955 :C1FC9075: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-08-19 22:00:04.029677 :C1FC907D: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-08-19 22:00:04.029870 :C1FC9085:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-08-19 22:00:04.031224 :C1FC9086: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-08-19 22:00:04.031262 :C1FC9089:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-19 22:00:04.031343 :C1FC9096: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-08-19 22:00:07.482916 :C1FC92D2: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 21392, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de