Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250430220008\orcl_j000_12188_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250430220008\orcl_j000_12188_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:37611M/63366M, Ph+PgF:45168M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12188, image: ORACLE.EXE (J000) *** 2025-04-30 22:00:08.291 *** SESSION ID:(177.64407) 2025-04-30 22:00:08.291 *** 2025-04-30 22:00:08.291 Process diagnostic dump for ORACLE.EXE (J000), OS id=12188, pid: 22, proc_ser: 131, sid: 177, sess_ser: 64407 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12188 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.084554 sec, exc=0.084554 sec, total=0.084554 sec wait times: max=0.500000 sec, heur=4.080859 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.000075 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000014 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499975 sec, exc=0.499975 sec, total=0.499975 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510139 sec, exc=0.510139 sec, total=0.510139 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=4 seq_num=5 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=3 seq_num=4 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 5: 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.486057 sec, exc=0.486057 sec, total=0.486057 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 64407 --------------------------------------------------- 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-04-30 22:00:08.291 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12188, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-30 21:57:31.107807 :90F6DE78:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:57:31.116220 :90F6DE7B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:57:31.101 2025-04-30 21:57:31.116222 :90F6DE7C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11728 pso_num=22 pso_serial#=117 2025-04-30 21:57:31.116348 :90F6DE7D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11728 (legacy spawn) 2025-04-30 21:57:31.122685 :90F6DE7E: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-04-30 21:57:31.122864 :90F6DE87: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-04-30 21:57:31.123642 :90F6DE88:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:57:33.190383 :90F6DF40:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:57:33.184 2025-04-30 21:57:33.190385 :90F6DF41:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9148 pso_num=22 pso_serial#=118 2025-04-30 21:57:33.190523 :90F6DF42:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9148 osp_idx=0 osp_ver=140623 osp_pg=0 (spawn #135149) 2025-04-30 21:57:33.190524 :90F6DF43:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 21:57:33.190524 :90F6DF44:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=227 time=3321042671 2025-04-30 21:57:33.190524 :90F6DF45:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 21:57:33.190525 :90F6DF46:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 21:57:33.190525 :90F6DF47:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 21:57:33.190525 :90F6DF48:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 21:57:33.190526 :90F6DF49:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 21:57:33.191023 :90F6DF4A: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-04-30 21:57:33.191661 :90F6DF5F: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-04-30 21:57:33.199146 :90F6DF7B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-30 21:57:33.199516 :90F6DF7C: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-04-30 21:57:33.199555 :90F6DF7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-30 21:57:33.200420 :90F6DF85: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-04-30 21:57:33.200534 :90F6DF8E: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-04-30 21:58:03.318871 :90F6E95A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:58:07.500895 :90F6EA7C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:58:07.498 2025-04-30 21:58:07.500897 :90F6EA7D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10404 pso_num=22 pso_serial#=119 2025-04-30 21:58:07.501028 :90F6EA7E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10404 (legacy spawn) 2025-04-30 21:58:07.507556 :90F6EA7F: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-04-30 21:58:07.507728 :90F6EA84: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-04-30 21:58:07.508517 :90F6EA89:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:58:07.517235 :90F6EA8C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:58:07.513 2025-04-30 21:58:07.517237 :90F6EA8D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8568 pso_num=22 pso_serial#=120 2025-04-30 21:58:07.517370 :90F6EA8E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8568 (legacy spawn) 2025-04-30 21:58:07.523762 :90F6EA8F: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-04-30 21:58:07.523926 :90F6EA96: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-04-30 21:58:07.524705 :90F6EA99:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:58:08.520839 :90F6EB05:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:58:08.513 2025-04-30 21:58:08.520840 :90F6EB06:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12136 pso_num=22 pso_serial#=121 2025-04-30 21:58:08.520977 :90F6EB07:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12136 (legacy spawn) 2025-04-30 21:58:08.527410 :90F6EB08: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-04-30 21:58:08.527582 :90F6EB11: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-04-30 21:58:08.528376 :90F6EB12:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:58:10.786376 :90F6EB9F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:58:10.774 2025-04-30 21:58:10.786378 :90F6EBA0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11800 pso_num=22 pso_serial#=122 2025-04-30 21:58:10.786515 :90F6EBA1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11800 (legacy spawn) 2025-04-30 21:58:10.792936 :90F6EBA2: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-04-30 21:58:10.863235 :90F6EBA7: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-04-30 21:58:10.864174 :90F6EBAC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:58:10.872810 :90F6EBDC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:58:10.867 2025-04-30 21:58:10.872812 :90F6EBDD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6392 pso_num=22 pso_serial#=123 2025-04-30 21:58:10.872943 :90F6EBDE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=6392 (legacy spawn) 2025-04-30 21:58:10.879367 :90F6EBDF: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-04-30 21:58:10.879535 :90F6EBE8: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-04-30 21:58:10.880328 :90F6EBE9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:58:28.288622 :90F6F11C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:58:28.283 2025-04-30 21:58:28.288625 :90F6F11D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8892 pso_num=22 pso_serial#=124 2025-04-30 21:58:28.288760 :90F6F11E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8892 osp_idx=0 osp_ver=140629 osp_pg=0 (spawn #135151) 2025-04-30 21:58:28.288761 :90F6F11F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 21:58:28.288761 :90F6F120:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=228 time=3321097765 2025-04-30 21:58:28.288762 :90F6F121:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 21:58:28.288762 :90F6F122:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 21:58:28.288762 :90F6F123:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 21:58:28.288762 :90F6F124:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 21:58:28.288763 :90F6F125:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 21:58:28.289250 :90F6F126: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-04-30 21:58:28.289942 :90F6F13B: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-04-30 21:58:28.295385 :90F6F157: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-04-30 21:58:28.296358 :90F6F15F: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-04-30 21:58:28.296492 :90F6F168: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-04-30 21:58:33.294766 :90F6F306: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-04-30 21:58:33.294786*:90F6F30B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCCBB58 estart=1746021512 eid=16838206 ctx=0x000000077BD98A08 cwh=0x000000000FC802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-30 21:58:33.294786*:90F6F30C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 pubTabIdxCnt=0 2025-04-30 21:58:33.294786*:90F6F30D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCCBB58 2025-04-30 21:58:33.294786*:90F6F30E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCCBB58 2025-04-30 21:58:33.294786*:90F6F30F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 xsc->flg4=65792 flags=2 2025-04-30 21:58:33.294786*:90F6F310:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCCBB58 2025-04-30 21:58:33.295311 :90F6F311: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-04-30 21:58:33.306169 :90F6F319:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-30 21:58:33.306650 :90F6F31A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-30 21:58:33.309019 :90F6F31B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-30 21:58:33.309353 :90F6F31C: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-04-30 21:58:33.309388 :90F6F31F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-30 21:58:33.310328 :90F6F325: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-04-30 21:58:33.310454 :90F6F32E: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-04-30 21:59:03.393498 :90F6FC96:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:59:04.834197 :90F6FCE7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:59:04.821 2025-04-30 21:59:04.834199 :90F6FCE8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11340 pso_num=22 pso_serial#=125 2025-04-30 21:59:04.834335 :90F6FCE9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11340 (legacy spawn) 2025-04-30 21:59:04.840782 :90F6FCEA: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-04-30 21:59:04.840957 :90F6FCEF: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-04-30 21:59:04.841764 :90F6FCF4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:59:10.683098 :90F6FEAF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:59:10.675 2025-04-30 21:59:10.683100 :90F6FEB0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4320 pso_num=22 pso_serial#=126 2025-04-30 21:59:10.683238 :90F6FEB1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4320 (legacy spawn) 2025-04-30 21:59:10.689673 :90F6FEB2: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-04-30 21:59:10.689847 :90F6FEB7: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-04-30 21:59:10.690706 :90F6FEBC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:59:20.483957 :90F701A8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:59:20.469 2025-04-30 21:59:20.483959 :90F701A9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12124 pso_num=22 pso_serial#=127 2025-04-30 21:59:20.484099 :90F701AA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12124 (legacy spawn) 2025-04-30 21:59:20.490466 :90F701AB: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-04-30 21:59:20.490671 :90F701B0: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-04-30 21:59:20.491438 :90F701B5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:59:20.500219 :90F701B8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:59:20.485 2025-04-30 21:59:20.500221 :90F701B9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11872 pso_num=22 pso_serial#=128 2025-04-30 21:59:20.500354 :90F701BA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11872 (legacy spawn) 2025-04-30 21:59:20.518139 :90F701BB: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-04-30 21:59:20.518303 :90F701C4: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-04-30 21:59:20.519193 :90F701C5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:59:23.612041 :90F702DA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:59:23.599 2025-04-30 21:59:23.612043 :90F702DB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11320 pso_num=22 pso_serial#=129 2025-04-30 21:59:23.612176 :90F702DC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11320 (legacy spawn) 2025-04-30 21:59:23.618677 :90F702DD: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-04-30 21:59:23.618843 :90F702E2: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-04-30 21:59:23.619627 :90F702E7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:59:23.628108 :90F702EA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:59:23.614 2025-04-30 21:59:23.628111 :90F702EB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11416 pso_num=22 pso_serial#=130 2025-04-30 21:59:23.628248 :90F702EC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11416 (legacy spawn) 2025-04-30 21:59:23.634647 :90F702ED: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-04-30 21:59:23.634818 :90F702F6: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-04-30 21:59:23.635591 :90F702F7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-30 21:59:33.390058 :90F705FE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-30 21:59:33.384 2025-04-30 21:59:33.390060 :90F705FF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12188 pso_num=22 pso_serial#=131 2025-04-30 21:59:33.390203 :90F70600:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12188 osp_idx=0 osp_ver=140636 osp_pg=0 (spawn #135153) 2025-04-30 21:59:33.390203 :90F70601:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 21:59:33.390204 :90F70602:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=229 time=3321162875 2025-04-30 21:59:33.390204 :90F70603:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 21:59:33.390205 :90F70604:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 21:59:33.390205 :90F70605:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 21:59:33.390206 :90F70606:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 21:59:33.390206 :90F70607:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 21:59:33.390703 :90F70608: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-04-30 21:59:33.391362 :90F7061D: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-04-30 21:59:33.398874 :90F70639:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-30 21:59:33.399276 :90F7063A: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-04-30 21:59:33.399316 :90F7063D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-30 21:59:33.400176 :90F70643: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-04-30 21:59:33.400296 :90F7064C: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-04-30 22:00:02.042209 :90F710D1: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-04-30 22:00:02.042209*:90F710E1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCDC718 estart=1746021601 eid=16779567 ctx=0x000000077FD6A000 cwh=0x000000000FCD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-30 22:00:02.042209*:90F710E2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 pubTabIdxCnt=0 2025-04-30 22:00:02.042209*:90F710E3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCDC718 2025-04-30 22:00:02.042209*:90F710E4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCDC718 2025-04-30 22:00:02.042209*:90F710E5:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 xsc->flg4=65792 flags=2 2025-04-30 22:00:02.042209*:90F710E6:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCDC718 2025-04-30 22:00:02.047805 :90F710F5: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-04-30 22:00:02.047848 :90F71104:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-04-30 22:00:02.058446 :90F71152: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-04-30 22:00:02.059340 :90F71165: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-04-30 22:00:02.064031 :90F712BB: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-04-30 22:00:02.064838 :90F712C3: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-04-30 22:00:02.070520 :90F712EA: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-04-30 22:00:02.070659 :90F712F9: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-04-30 22:00:02.071385 :90F71301: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-04-30 22:00:02.071585 :90F71309:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-04-30 22:00:02.072141 :90F7130B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-04-30 22:00:02.072570 :90F7130D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-04-30 22:00:02.072648 :90F7130F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-04-30 22:00:02.073212 :90F71314: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-04-30 22:00:02.073295 :90F71329: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-04-30 22:00:02.167182 :90F71443: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-04-30 22:00:04.203437 :90F7189C: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-04-30 22:00:04.203437*:90F7189E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCDC718 estart=1746021604 eid=16779573 ctx=0x000000077FD6A000 cwh=0x000000000FCD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-30 22:00:04.203437*:90F7189F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 pubTabIdxCnt=0 2025-04-30 22:00:04.203437*:90F718A0:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCDC718 2025-04-30 22:00:04.203437*:90F718A1:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCDC718 2025-04-30 22:00:04.203437*:90F718A2:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 xsc->flg4=65792 flags=2 2025-04-30 22:00:04.203437*:90F718A3:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCDC718 2025-04-30 22:00:04.205787 :90F718A4: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-04-30 22:00:04.205823 :90F718A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-04-30 22:00:04.212540 :90F718AD: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-04-30 22:00:04.213200 :90F718B5: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-04-30 22:00:04.213992 :90F718BD: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-04-30 22:00:04.214216 :90F718C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-04-30 22:00:04.215605 :90F718C6: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-04-30 22:00:04.215644 :90F718C9: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-04-30 22:00:04.215713 :90F718D6: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: 12188, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de