Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250901220008\orcl_j000_22652_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250901220008\orcl_j000_22652_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:35006M/63366M, Ph+PgF:39917M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 22652, image: ORACLE.EXE (J000) *** 2025-09-01 22:00:08.152 *** SESSION ID:(177.26199) 2025-09-01 22:00:08.152 *** 2025-09-01 22:00:08.152 Process diagnostic dump for ORACLE.EXE (J000), OS id=22652, pid: 22, proc_ser: 36, sid: 177, sess_ser: 26199 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22652 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.133558 sec, exc=0.133558 sec, total=0.133558 sec wait times: max=0.500000 sec, heur=6.231712 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.510098 sec, exc=0.510098 sec, total=0.510098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.510108 sec, exc=0.510108 sec, total=0.510108 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499211 sec, exc=0.499211 sec, total=0.499211 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=4 seq_num=5 snap_id=1 wait times: snap=0.078906 sec, exc=0.078906 sec, total=0.078906 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time Sampled Session History of session 177 serial 26199 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:00:01 - 22:00:08] idle wait at each sample [session created at: 22:00:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-01 22:00:08.152 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 22652, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-01 21:57:28.118135 :C7B4F74A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21224 (legacy spawn) 2025-09-01 21:57:28.124643 :C7B4F74B: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-09-01 21:57:28.124812 :C7B4F750: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-09-01 21:57:28.125603 :C7B4F755:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:57:30.345489 :C7B4F826:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:57:30.336 2025-09-01 21:57:30.345491 :C7B4F827:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16664 pso_num=22 pso_serial#=21 2025-09-01 21:57:30.345626 :C7B4F828:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16664 (legacy spawn) 2025-09-01 21:57:30.352349 :C7B4F829: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-09-01 21:57:30.352521 :C7B4F82E: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-09-01 21:57:30.353338 :C7B4F833:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:57:31.576556 :C7B4F894:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:57:31.571 2025-09-01 21:57:31.576558 :C7B4F895:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23224 pso_num=22 pso_serial#=22 2025-09-01 21:57:31.576730 :C7B4F896:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23224 osp_idx=0 osp_ver=563389 osp_pg=0 (spawn #549153) 2025-09-01 21:57:31.576730 :C7B4F897:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:57:31.576731 :C7B4F898:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=37 time=1149502877 2025-09-01 21:57:31.576731 :C7B4F899:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:57:31.576732 :C7B4F89A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:57:31.576732 :C7B4F89B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:57:31.576732 :C7B4F89C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:57:31.576733 :C7B4F89D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:57:31.577272 :C7B4F89E: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-09-01 21:57:31.577910 :C7B4F8B3: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-09-01 21:57:31.585466 :C7B4F8CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-01 21:57:31.585839 :C7B4F8D0: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-09-01 21:57:31.585876 :C7B4F8D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-01 21:57:31.586732 :C7B4F8D9: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-09-01 21:57:31.586849 :C7B4F8E2: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-09-01 21:58:01.686991 :C7B502E2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:58:04.442356 :C7B503A3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:58:04.433 2025-09-01 21:58:04.442359 :C7B503A4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20504 pso_num=22 pso_serial#=23 2025-09-01 21:58:04.442496 :C7B503A5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20504 (legacy spawn) 2025-09-01 21:58:04.449025 :C7B503A6: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-09-01 21:58:04.449195 :C7B503AB: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-09-01 21:58:04.450015 :C7B503B0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:58:10.198399 :C7B50585:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:58:10.183 2025-09-01 21:58:10.198401 :C7B50586:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18824 pso_num=22 pso_serial#=24 2025-09-01 21:58:10.198565 :C7B50587:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18824 (legacy spawn) 2025-09-01 21:58:10.205143 :C7B50588: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-09-01 21:58:10.205322 :C7B5058D: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-09-01 21:58:10.206152 :C7B50592:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:58:10.215123 :C7B50595:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:58:10.214 2025-09-01 21:58:10.215125 :C7B50596:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22072 pso_num=22 pso_serial#=25 2025-09-01 21:58:10.215266 :C7B50597:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22072 (legacy spawn) 2025-09-01 21:58:10.221900 :C7B50598: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-09-01 21:58:10.222067 :C7B505A1: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-09-01 21:58:10.222910 :C7B505A2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:58:13.508994 :C7B506AA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:58:13.496 2025-09-01 21:58:13.508996 :C7B506AB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20296 pso_num=22 pso_serial#=26 2025-09-01 21:58:13.509132 :C7B506AC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20296 (legacy spawn) 2025-09-01 21:58:13.515533 :C7B506D0: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-09-01 21:58:13.515708 :C7B506D5: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-09-01 21:58:13.516545 :C7B506DA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:58:13.525120 :C7B506DD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:58:13.511 2025-09-01 21:58:13.525122 :C7B506DE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20640 pso_num=22 pso_serial#=27 2025-09-01 21:58:13.525268 :C7B506DF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20640 (legacy spawn) 2025-09-01 21:58:13.531806 :C7B506E0: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-09-01 21:58:13.531967 :C7B506E9: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-09-01 21:58:13.532769 :C7B506EA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:58:16.630257 :C7B507EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:58:16.624 2025-09-01 21:58:16.630259 :C7B507ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21864 pso_num=22 pso_serial#=28 2025-09-01 21:58:16.630405 :C7B507EE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21864 osp_idx=0 osp_ver=563395 osp_pg=0 (spawn #549155) 2025-09-01 21:58:16.630406 :C7B507EF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:58:16.630407 :C7B507F0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=38 time=1149547940 2025-09-01 21:58:16.630407 :C7B507F1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:58:16.630407 :C7B507F2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:58:16.630407 :C7B507F3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:58:16.630408 :C7B507F4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:58:16.630408 :C7B507F5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:58:16.630907 :C7B507F6: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-09-01 21:58:16.631562 :C7B5080B: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-09-01 21:58:16.637024 :C7B50827: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-09-01 21:58:16.638080 :C7B5082F: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-09-01 21:58:16.638220 :C7B50838: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-09-01 21:58:31.659245 :C7B50CF4: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-09-01 21:58:31.659266*:C7B50CFB:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000012D5BB58 estart=1756735111 eid=17034943 ctx=0x000000077BD98A08 cwh=0x000000000FDC02A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-01 21:58:31.659266*:C7B50CFC:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000012D5BB58 curCtx=0x000000000FDCF300 pubTabIdxCnt=0 2025-09-01 21:58:31.659266*:C7B50CFD:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000012D5BB58 2025-09-01 21:58:31.659266*:C7B50CFE:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000012D5BB58 2025-09-01 21:58:31.659266*:C7B50CFF:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000012D5BB58 curCtx=0x000000000FDCF300 xsc->flg4=65792 flags=2 2025-09-01 21:58:31.659266*:C7B50D00:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000012D5BB58 2025-09-01 21:58:31.659807 :C7B50D01: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-09-01 21:58:31.670633 :C7B50D09:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-01 21:58:31.671108 :C7B50D0A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-01 21:58:31.673457 :C7B50D0B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-01 21:58:31.673799 :C7B50D0C: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-09-01 21:58:31.673839 :C7B50D0F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-01 21:58:31.674744 :C7B50D15: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-09-01 21:58:31.674883 :C7B50D1E: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-09-01 21:59:01.768702 :C7B516C6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:59:10.697191 :C7B51991:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:59:10.684 2025-09-01 21:59:10.697193 :C7B51992:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21580 pso_num=22 pso_serial#=29 2025-09-01 21:59:10.697339 :C7B51993:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21580 (legacy spawn) 2025-09-01 21:59:10.719474 :C7B51994: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-09-01 21:59:10.719649 :C7B51999: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-09-01 21:59:10.720453 :C7B5199E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:59:10.729137 :C7B519A1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:59:10.715 2025-09-01 21:59:10.729139 :C7B519A2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22532 pso_num=22 pso_serial#=30 2025-09-01 21:59:10.729273 :C7B519A3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22532 (legacy spawn) 2025-09-01 21:59:10.735836 :C7B519A4: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-09-01 21:59:10.735998 :C7B519AD: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-09-01 21:59:10.736855 :C7B519AE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:59:12.642254 :C7B51A6D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:59:12.633 2025-09-01 21:59:12.642256 :C7B51A6E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21996 pso_num=22 pso_serial#=31 2025-09-01 21:59:12.642389 :C7B51A6F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21996 (legacy spawn) 2025-09-01 21:59:12.648921 :C7B51A70: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-09-01 21:59:12.649093 :C7B51A79: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-09-01 21:59:12.649911 :C7B51A7A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:59:22.981721 :C7B51DE3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:59:22.978 2025-09-01 21:59:22.981723 :C7B51DE4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22340 pso_num=22 pso_serial#=32 2025-09-01 21:59:22.981861 :C7B51DE5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22340 (legacy spawn) 2025-09-01 21:59:22.988303 :C7B51DE6: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-09-01 21:59:22.988470 :C7B51DEB: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-09-01 21:59:22.989270 :C7B51DF0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:59:22.998045 :C7B51DF3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:59:22.994 2025-09-01 21:59:22.998047 :C7B51DF4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22780 pso_num=22 pso_serial#=33 2025-09-01 21:59:22.998188 :C7B51DF5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22780 (legacy spawn) 2025-09-01 21:59:23.004599 :C7B51DF6: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-09-01 21:59:23.004759 :C7B51DFF: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-09-01 21:59:23.005548 :C7B51E00:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:59:26.197947 :C7B51EE9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:59:26.185 2025-09-01 21:59:26.197950 :C7B51EEA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23368 pso_num=22 pso_serial#=34 2025-09-01 21:59:26.198099 :C7B51EEB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23368 (legacy spawn) 2025-09-01 21:59:26.204825 :C7B51EEC: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-09-01 21:59:26.205012 :C7B51EF1: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-09-01 21:59:26.205900 :C7B51EF6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:59:26.215050 :C7B51EF9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:59:26.200 2025-09-01 21:59:26.215052 :C7B51EFA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22312 pso_num=22 pso_serial#=35 2025-09-01 21:59:26.215233 :C7B51EFB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22312 (legacy spawn) 2025-09-01 21:59:26.222106 :C7B51EFC: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-09-01 21:59:26.222290 :C7B51F05: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-09-01 21:59:26.223209 :C7B51F06:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-01 21:59:31.731368 :C7B520CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 21:59:31.726 2025-09-01 21:59:31.731370 :C7B520CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22652 pso_num=22 pso_serial#=36 2025-09-01 21:59:31.731513 :C7B520CC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22652 osp_idx=0 osp_ver=563403 osp_pg=0 (spawn #549157) 2025-09-01 21:59:31.731514 :C7B520CD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:59:31.731514 :C7B520CE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=39 time=1149623033 2025-09-01 21:59:31.731515 :C7B520CF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:59:31.731515 :C7B520D0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:59:31.731516 :C7B520D1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:59:31.731516 :C7B520D2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:59:31.731516 :C7B520D3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:59:31.732009 :C7B520D4: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-09-01 21:59:31.732643 :C7B520E9: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-09-01 21:59:31.740182 :C7B52106:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-01 21:59:31.740614 :C7B52107: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-09-01 21:59:31.740651 :C7B5210A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-01 21:59:31.741506 :C7B52110: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-09-01 21:59:31.741630 :C7B52119: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-09-01 22:00:01.794421 :C7B52C89: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-09-01 22:00:01.794421*:C7B52CB3:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000012D6C718 estart=1756735200 eid=16787200 ctx=0x000000077FD6A000 cwh=0x0000000012D60068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-01 22:00:01.794421*:C7B52CB4:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000012D6C718 curCtx=0x0000000012D68938 pubTabIdxCnt=0 2025-09-01 22:00:01.794421*:C7B52CB5:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000012D6C718 2025-09-01 22:00:01.794421*:C7B52CB6:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000012D6C718 2025-09-01 22:00:01.794421*:C7B52CCC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000012D6C718 curCtx=0x0000000012D68938 xsc->flg4=65792 flags=2 2025-09-01 22:00:01.794421*:C7B52CCD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000012D6C718 2025-09-01 22:00:01.801512 :C7B52CCE: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-09-01 22:00:01.801575 :C7B52CD1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-09-01 22:00:01.811640 :C7B52D13: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-09-01 22:00:01.813896 :C7B52D30: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-09-01 22:00:01.819006 :C7B52D5D: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-09-01 22:00:01.819794 :C7B52D65: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-09-01 22:00:01.825798 :C7B52ED4: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-09-01 22:00:01.825959 :C7B52F4A: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-09-01 22:00:01.826698 :C7B52F84: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-09-01 22:00:01.826742 :C7B52F86: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-09-01 22:00:01.827003 :C7B52F99:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-09-01 22:00:01.828698 :C7B52FB3: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-09-01 22:00:01.828743 :C7B52FB6: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-09-01 22:00:01.828804 :C7B52FC3: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-09-01 22:00:01.919252 :C7B52FEA: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-09-01 22:00:01.921554 :C7B52FEF: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-09-01 22:00:01.921599 :C7B52FF2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-09-01 22:00:01.924176 :C7B52FF8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-09-01 22:00:01.927627 :C7B52FF9: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-09-01 22:00:01.927799 :C7B52FFC: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-09-01 22:00:01.927883 :C7B53009: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-09-01 22:00:04.007463 :C7B53448: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: 22652, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de