Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250520220006\orcl_j001_10660_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250520220006\orcl_j001_10660_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:37355M/63366M, Ph+PgF:44492M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 10660, image: ORACLE.EXE (J001) *** 2025-05-20 22:00:06.100 *** SESSION ID:(201.47049) 2025-05-20 22:00:06.100 *** 2025-05-20 22:00:06.100 Process diagnostic dump for ORACLE.EXE (J001), OS id=10660, pid: 25, proc_ser: 31, sid: 201, sess_ser: 47049 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10660 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) 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.081843 sec, exc=0.081843 sec, total=0.081843 sec wait times: max=0.500000 sec, heur=3.880670 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.000014 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.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.000010 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.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.000012 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.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.000009 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.509551 sec, exc=0.509551 sec, total=0.509551 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 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.193139 sec, exc=0.193139 sec, total=0.193139 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.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.000007 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.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.000008 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.499152 sec, exc=0.499152 sec, total=0.499152 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 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.096909 sec, exc=0.096909 sec, total=0.096909 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 201 serial 47049 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [4 samples, 22:00:02 - 22:00:06] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-20 22:00:06.100 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 10660, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-20 21:51:34.190736 :99CBDD08:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13976 osp_idx=23 osp_ver=109161 osp_pg=0 (spawn #202485) 2025-05-20 21:51:34.190737 :99CBDD09:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:51:34.190737 :99CBDD0A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=160 time=753704047 2025-05-20 21:51:34.190738 :99CBDD0B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:51:34.190738 :99CBDD0C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:51:34.190738 :99CBDD0D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:51:34.190739 :99CBDD0E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:51:34.190739 :99CBDD0F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:51:34.191213 :99CBDD10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 21:52:04.274807 :99CBE672:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-20 21:52:34.309575 :99CBF039:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-20 21:52:34.299 2025-05-20 21:52:34.309578 :99CBF03A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7672 pso_num=25 pso_serial#=23 2025-05-20 21:52:34.309714 :99CBF03B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7672 osp_idx=23 osp_ver=109162 osp_pg=0 (spawn #202487) 2025-05-20 21:52:34.309715 :99CBF03C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:52:34.309715 :99CBF03D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=161 time=753764172 2025-05-20 21:52:34.309716 :99CBF03E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:52:34.309716 :99CBF03F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:52:34.309716 :99CBF040:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:52:34.309717 :99CBF041:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:52:34.309717 :99CBF042:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:52:34.310188 :99CBF043:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 21:53:04.416374 :99CBFACD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-20 21:53:29.370475 :99CC0316:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-20 21:53:29.358 2025-05-20 21:53:29.370478 :99CC0317:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14148 pso_num=25 pso_serial#=24 2025-05-20 21:53:29.370622 :99CC0318:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14148 osp_idx=23 osp_ver=109163 osp_pg=0 (spawn #202489) 2025-05-20 21:53:29.370622 :99CC0319:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:53:29.370623 :99CC031A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=162 time=753819235 2025-05-20 21:53:29.370623 :99CC031B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:53:29.370624 :99CC031C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:53:29.370624 :99CC031D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:53:29.370624 :99CC031E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:53:29.370625 :99CC031F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:53:29.371119 :99CC0320:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 21:53:34.374747 :99CC04AC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-20 21:53:34.374777*:99CC04AD:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DCBB58 estart=1747749213 eid=16869931 ctx=0x000000077BD98A08 cwh=0x0000000024D801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-20 21:53:34.374777*:99CC04AE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 pubTabIdxCnt=0 2025-05-20 21:53:34.374777*:99CC04AF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DCBB58 2025-05-20 21:53:34.374777*:99CC04B0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DCBB58 2025-05-20 21:53:34.374777*:99CC04B1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 xsc->flg4=65792 flags=2 2025-05-20 21:53:34.374777*:99CC04B2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DCBB58 2025-05-20 21:53:34.375296 :99CC04B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 21:53:34.382871 :99CC04BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-20 21:53:34.383236 :99CC04BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 21:53:34.383271 :99CC04BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-20 21:53:34.384197 :99CC04C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 21:53:34.384331 :99CC04CD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-20 21:54:04.438012 :99CC0E1E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-20 21:54:34.470746 :99CC17DF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-20 21:54:34.460 2025-05-20 21:54:34.470748 :99CC17E0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13204 pso_num=25 pso_serial#=25 2025-05-20 21:54:34.470875 :99CC17E1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13204 osp_idx=23 osp_ver=109164 osp_pg=0 (spawn #202491) 2025-05-20 21:54:34.470876 :99CC17E2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:54:34.470876 :99CC17E3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=163 time=753884329 2025-05-20 21:54:34.470877 :99CC17E4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:54:34.470877 :99CC17E5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:54:34.470878 :99CC17E6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:54:34.470878 :99CC17E7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:54:34.470878 :99CC17E8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:54:34.471351 :99CC17E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 21:55:04.555685 :99CC2159:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-20 21:55:34.570727 :99CC2B09:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-20 21:55:34.560 2025-05-20 21:55:34.570728 :99CC2B0A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14092 pso_num=25 pso_serial#=26 2025-05-20 21:55:34.570901 :99CC2B0B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14092 osp_idx=23 osp_ver=109165 osp_pg=0 (spawn #202493) 2025-05-20 21:55:34.570902 :99CC2B0C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:55:34.570902 :99CC2B0D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=164 time=753944438 2025-05-20 21:55:34.570902 :99CC2B0E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:55:34.570903 :99CC2B0F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:55:34.570903 :99CC2B10:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:55:34.570904 :99CC2B11:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:55:34.570904 :99CC2B12:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:55:34.571403 :99CC2B13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 21:56:04.644039 :99CC359A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-20 21:56:34.640823 :99CC3F32:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-20 21:56:34.630 2025-05-20 21:56:34.640825 :99CC3F33:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14296 pso_num=25 pso_serial#=27 2025-05-20 21:56:34.640958 :99CC3F34:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14296 osp_idx=23 osp_ver=109166 osp_pg=0 (spawn #202495) 2025-05-20 21:56:34.640959 :99CC3F35:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:56:34.640959 :99CC3F36:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=165 time=754004500 2025-05-20 21:56:34.640960 :99CC3F37:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:56:34.640960 :99CC3F38:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:56:34.640960 :99CC3F39:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:56:34.640961 :99CC3F3A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:56:34.640961 :99CC3F3B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:56:34.641426 :99CC3F3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 21:57:04.747628 :99CC48A6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-20 21:57:34.720611 :99CC5237:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-20 21:57:34.709 2025-05-20 21:57:34.720613 :99CC5238:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13388 pso_num=25 pso_serial#=28 2025-05-20 21:57:34.720734 :99CC5239:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13388 osp_idx=23 osp_ver=109167 osp_pg=0 (spawn #202498) 2025-05-20 21:57:34.720735 :99CC523A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:57:34.720735 :99CC523B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=166 time=754064579 2025-05-20 21:57:34.720736 :99CC523C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:57:34.720736 :99CC523D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:57:34.720736 :99CC523E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:57:34.720737 :99CC523F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:57:34.720737 :99CC5240:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:57:34.721212 :99CC5241:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 21:58:04.809397 :99CC5C37:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-20 21:58:20.052407 :99CC61B0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-20 21:58:20.040 2025-05-20 21:58:20.052409 :99CC61B1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9500 pso_num=25 pso_serial#=29 2025-05-20 21:58:20.052557 :99CC61B2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9500 osp_idx=23 osp_ver=109168 osp_pg=0 (spawn #202500) 2025-05-20 21:58:20.052558 :99CC61B3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:58:20.052558 :99CC61B4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=167 time=754109907 2025-05-20 21:58:20.052559 :99CC61B5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:58:20.052559 :99CC61B6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:58:20.052560 :99CC61B7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:58:20.052560 :99CC61B8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:58:20.052560 :99CC61B9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:58:20.053079 :99CC61BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 21:58:20.053122 :99CC61C0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-20 21:58:20.057122 :99CC61E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-20 21:58:20.057164 :99CC61E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-20 21:58:20.057418 :99CC61EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-20 21:58:20.057433 :99CC61EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-20 21:58:20.057648 :99CC61EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-20 21:58:20.057661 :99CC61ED:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-20 21:58:20.057864 :99CC61EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-20 21:58:20.057877 :99CC61EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-20 21:58:20.058081 :99CC61F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-20 21:58:20.058094 :99CC61F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-20 21:58:20.058290 :99CC61F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-20 21:58:20.058302 :99CC61F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-20 21:58:20.060717 :99CC61F4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-20 21:58:34.818780 :99CC66A2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-20 21:58:34.807 2025-05-20 21:58:34.818782 :99CC66A3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13052 pso_num=25 pso_serial#=30 2025-05-20 21:58:34.818918 :99CC66A4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13052 osp_idx=23 osp_ver=109169 osp_pg=0 (spawn #202502) 2025-05-20 21:58:34.818918 :99CC66A5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:58:34.818919 :99CC66A6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=168 time=754124688 2025-05-20 21:58:34.818921 :99CC66A7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:58:34.818921 :99CC66A8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:58:34.818921 :99CC66A9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:58:34.818922 :99CC66AA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:58:34.818922 :99CC66AB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:58:34.819411 :99CC66AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 21:59:04.909550 :99CC703E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-20 21:59:34.914636 :99CC79F7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-20 21:59:34.903 2025-05-20 21:59:34.914638 :99CC79F8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10660 pso_num=25 pso_serial#=31 2025-05-20 21:59:34.914773 :99CC79F9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10660 osp_idx=23 osp_ver=109170 osp_pg=0 (spawn #202504) 2025-05-20 21:59:34.914773 :99CC79FA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-20 21:59:34.914774 :99CC79FB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=169 time=754184782 2025-05-20 21:59:34.914774 :99CC79FC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:59:34.914775 :99CC79FD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:59:34.914775 :99CC79FE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:59:34.914776 :99CC79FF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:59:34.914776 :99CC7A00:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:59:34.915243 :99CC7A01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.195114 :99CC8422:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-20 22:00:02.195114*:99CC842E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DDB898 estart=1747749601 eid=16780800 ctx=0x000000077FD6A000 cwh=0x0000000024DD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-20 22:00:02.195114*:99CC842F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DDB898 curCtx=0x0000000024DD7AB8 pubTabIdxCnt=0 2025-05-20 22:00:02.195114*:99CC8430:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DDB898 2025-05-20 22:00:02.195114*:99CC8431:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DDB898 2025-05-20 22:00:02.195114*:99CC8436:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DDB898 curCtx=0x0000000024DD7AB8 xsc->flg4=65792 flags=2 2025-05-20 22:00:02.195114*:99CC8437:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DDB898 2025-05-20 22:00:02.201289 :99CC8444:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.201345 :99CC8449:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-05-20 22:00:02.206641 :99CC8465:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-05-20 22:00:02.207260 :99CC8470:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 e5d5110000000000 2025-05-20 22:00:02.209104 :99CC848F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.209173 :99CC849C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-20 22:00:02.209182 :99CC84A3:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-05-20 22:00:02.209187 :99CC84A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.209214 :99CC84B0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-20 22:00:02.209866 :99CC84BC:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 225900761) 2025-05-20 22:00:02.209870 :99CC84BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.209891 :99CC84C9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-20 22:00:02.209897 :99CC84D5:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65561 1) 2025-05-20 22:00:02.209900 :99CC84D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.209925 :99CC84E2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-20 22:00:02.209929 :99CC84EB:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65561) 2025-05-20 22:00:02.209931 :99CC84EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.209973 :99CC8510:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-20 22:00:02.210491 :99CC85BE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-20 22:00:02.210508 :99CC85C5:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-05-20 22:00:02.211373 :99CC85CC:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 e6d5110000000000 2025-05-20 22:00:02.211760 :99CC85CD:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 225900761) 2025-05-20 22:00:02.211768 :99CC85CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.211806 :99CC85D7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-20 22:00:02.220151 :99CC8650:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-05-20 22:00:02.222036 :99CC8651:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.222115 :99CC8654:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-20 22:00:02.222253 :99CC8661:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-20 22:00:02.320027 :99CC876A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-20 22:00:04.012345 :99CC8ABF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 10660, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de