Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250817220158\orcl_j002_20840_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250817220158\orcl_j002_20840_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:35389M/63366M, Ph+PgF:40650M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 20840, image: ORACLE.EXE (J002) *** 2025-08-17 22:01:58.128 *** SESSION ID:(225.26131) 2025-08-17 22:01:58.128 *** 2025-08-17 22:01:58.128 Process diagnostic dump for ORACLE.EXE (J002), OS id=20840, pid: 28, proc_ser: 220, sid: 225, sess_ser: 26131 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20840 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.394493 sec, exc=0.394493 sec, total=0.394493 sec wait times: max=0.500000 sec, heur=6.419560 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=11 seq_num=12 snap_id=1 wait times: snap=0.511014 sec, exc=0.511014 sec, total=0.511014 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.510128 sec, exc=0.510128 sec, total=0.510128 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=9 seq_num=10 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499969 sec, exc=0.499969 sec, total=0.499969 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500019 sec, exc=0.500019 sec, total=0.500019 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499886 sec, exc=0.499886 sec, total=0.499886 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.510191 sec, exc=0.510191 sec, total=0.510191 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499972 sec, exc=0.499972 sec, total=0.499972 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=2 seq_num=3 snap_id=1 wait times: snap=0.500024 sec, exc=0.500024 sec, total=0.500024 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 225 serial 26131 --------------------------------------------------- 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:01:51 - 22:01:58] idle wait at each sample [session created at: 22:01:51] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-17 22:01:58.128 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 20840, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-17 22:00:07.038746 :C11BAF39:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:07.024 2025-08-17 22:00:07.038748 :C11BAF3A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14192 pso_num=28 pso_serial#=206 2025-08-17 22:00:07.038889 :C11BAF3B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14192 (legacy spawn) 2025-08-17 22:00:07.045433 :C11BAF3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:07.045599 :C11BAF44:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:07.046477 :C11BAF45:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:10.103978 :C11BB040:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:10.091 2025-08-17 22:00:10.103980 :C11BB041:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20808 pso_num=28 pso_serial#=207 2025-08-17 22:00:10.104119 :C11BB042:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20808 (legacy spawn) 2025-08-17 22:00:10.110537 :C11BB043:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:10.110705 :C11BB04B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:10.111494 :C11BB04D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:10.120233 :C11BB050:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:10.106 2025-08-17 22:00:10.120236 :C11BB051:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21984 pso_num=28 pso_serial#=208 2025-08-17 22:00:10.120371 :C11BB052:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21984 (legacy spawn) 2025-08-17 22:00:10.126816 :C11BB053:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:10.126981 :C11BB05C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:10.127771 :C11BB05D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:15.338785 :C11BB232:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:15.330 2025-08-17 22:00:15.338787 :C11BB233:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14932 pso_num=28 pso_serial#=209 2025-08-17 22:00:15.338927 :C11BB234:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14932 (legacy spawn) 2025-08-17 22:00:15.345508 :C11BB235:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:15.345688 :C11BB23A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:15.346550 :C11BB23F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:27.156763 :C11BB5F9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:27.149 2025-08-17 22:00:27.156765 :C11BB5FA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20620 pso_num=28 pso_serial#=210 2025-08-17 22:00:27.156899 :C11BB5FB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20620 (legacy spawn) 2025-08-17 22:00:27.163306 :C11BB5FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.163492 :C11BB601:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:27.164388 :C11BB606:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:27.325181 :C11BB729:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:27.320 2025-08-17 22:00:27.325183 :C11BB72A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22476 pso_num=28 pso_serial#=211 2025-08-17 22:00:27.325317 :C11BB72B:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=22476 osp_idx=26 osp_ver=242254 osp_pg=0 (spawn #499156) 2025-08-17 22:00:27.325317 :C11BB72C:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 22:00:27.325318 :C11BB72D:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=72 time=4148696626 2025-08-17 22:00:27.325318 :C11BB72E:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 22:00:27.325318 :C11BB72F:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 22:00:27.325319 :C11BB730:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 22:00:27.325319 :C11BB731:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 22:00:27.325319 :C11BB732:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 22:00:27.325833 :C11BB733:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.325879 :C11BB739:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-08-17 22:00:27.330288 :C11BB74C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.352596 :C11BB754:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.368465 :C11BB75C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.400371 :C11BB764:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.404301 :C11BB76C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.408080 :C11BB774:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.412287 :C11BB77C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.413441 :C11BB784:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.421798 :C11BB78C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.421811 :C11BB78D:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0003.005.0000c8b3 2025-08-17 22:00:27.422318 :C11BB795:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 3, ext 1, nex 2 2025-08-17 22:00:27.422325 :C11BB796:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00811a14 where: kdiwh187: kdisprobe 2025-08-17 22:00:27.433793 :C11BB797:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.433807 :C11BB798:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0003.003.0000c8ad 2025-08-17 22:00:27.434089 :C11BB7A0:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 3, ext 2, nex 3 2025-08-17 22:00:27.434096 :C11BB7A1:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00815711 where: ktspbwh1: ktspfsrch 2025-08-17 22:00:27.440899 :C11BB7A2:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 3, ext 3, nex 4 2025-08-17 22:00:27.440908 :C11BB7A3:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00815074 where: kdiwh22: kdifind 2025-08-17 22:00:27.458001 :C11BB7A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.458014 :C11BB7A5:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0003.002.0000c8b2 2025-08-17 22:00:27.458294 :C11BB7AD:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 3, ext 4, nex 5 2025-08-17 22:00:27.458300 :C11BB7AE:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x0081a9a4 where: ktspbwh1: ktspfsrch 2025-08-17 22:00:27.469368 :C11BB7AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:27.469385 :C11BB7B0:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0003.016.0000c8a3 2025-08-17 22:00:27.469689 :C11BB7B8:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 3, ext 5, nex 6 2025-08-17 22:00:27.469698 :C11BB7B9:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00815721 where: ktspbwh1: ktspfsrch 2025-08-17 22:00:28.030645 :C11BB852:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:28.030659 :C11BB853:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0003.011.0000c8ac 2025-08-17 22:00:28.031007 :C11BB85B:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 3, ext 6, nex 7 2025-08-17 22:00:28.031013 :C11BB85C:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x0081572a where: ktspbwh1: ktspfsrch 2025-08-17 22:00:28.601199*:C11BB869:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000292F36E0 estart=1755439227 eid=16780775 ctx=0x000000077BA4B1C8 cwh=0x00000000292E0B90 cpu=578125 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-08-17 22:00:28.601199*:C11BB86A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000292F36E0 curCtx=0x000000002995F5A0 pubTabIdxCnt=0 2025-08-17 22:00:28.601199*:C11BB86B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000292F36E0 2025-08-17 22:00:28.601199*:C11BB86C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000292F36E0 2025-08-17 22:00:28.601199*:C11BB86D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000292E1060 estart=1755439227 eid=16780754 ctx=0x0000000787AD5BF0 cwh=0x00000000292E0AA0 cpu=578125 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-08-17 22:00:28.601199*:C11BB86E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000292E1060 curCtx=0x0000000029961BA8 pubTabIdxCnt=0 2025-08-17 22:00:28.601199*:C11BB86F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000292E1060 2025-08-17 22:00:28.601199*:C11BB870:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000292E1060 2025-08-17 22:00:28.601199*:C11BB871:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000292F36E0 curCtx=0x000000002995F5A0 xsc->flg4=65792 flags=1 2025-08-17 22:00:28.601199*:C11BB872:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000292F36E0 2025-08-17 22:00:28.601199*:C11BB873:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000292E1060 curCtx=0x0000000029961BA8 xsc->flg4=65792 flags=2 2025-08-17 22:00:28.601199*:C11BB874:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000292E1060 2025-08-17 22:00:28.673855 :C11BB880:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:28.729960 :C11BB888:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:28.730373 :C11BB890:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:28.730713 :C11BB898:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:28.730735 :C11BB89B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:28.730806 :C11BB8A7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:28.733743 :C11BB8B5:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:43.296608 :C11BBDB9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:43.282 2025-08-17 22:00:43.296610 :C11BBDBA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19768 pso_num=28 pso_serial#=212 2025-08-17 22:00:43.296749 :C11BBDBB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19768 (legacy spawn) 2025-08-17 22:00:43.303247 :C11BBDBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:43.303415 :C11BBDC4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:43.304218 :C11BBDC6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:43.312922 :C11BBDC9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:43.298 2025-08-17 22:00:43.312924 :C11BBDCA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18016 pso_num=28 pso_serial#=213 2025-08-17 22:00:43.313082 :C11BBDCB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18016 (legacy spawn) 2025-08-17 22:00:43.319526 :C11BBDCC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:43.319690 :C11BBDD5:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:43.320474 :C11BBDD6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:46.383252 :C11BBEB9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:46.370 2025-08-17 22:00:46.383254 :C11BBEBA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21756 pso_num=28 pso_serial#=214 2025-08-17 22:00:46.383388 :C11BBEBB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21756 (legacy spawn) 2025-08-17 22:00:46.389786 :C11BBEBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:46.389953 :C11BBEC5:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:46.390755 :C11BBEC6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:46.399425 :C11BBEC9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:46.386 2025-08-17 22:00:46.399427 :C11BBECA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20812 pso_num=28 pso_serial#=215 2025-08-17 22:00:46.399569 :C11BBECB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20812 (legacy spawn) 2025-08-17 22:00:46.406361 :C11BBECC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:46.406529 :C11BBED4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:46.407471 :C11BBED6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:00:49.615901 :C11BBFCF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:00:49.603 2025-08-17 22:00:49.615902 :C11BBFD0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19304 pso_num=28 pso_serial#=216 2025-08-17 22:00:49.616045 :C11BBFD1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19304 (legacy spawn) 2025-08-17 22:00:49.622514 :C11BBFD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:00:49.622687 :C11BBFDB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:00:49.623473 :C11BBFDC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:01:29.373797 :C11BCD21:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:01:29.365 2025-08-17 22:01:29.373799 :C11BCD22:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20760 pso_num=28 pso_serial#=217 2025-08-17 22:01:29.373958 :C11BCD23:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20760 (legacy spawn) 2025-08-17 22:01:29.381278 :C11BCD24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 22:01:29.381504 :C11BCD29:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-17 22:01:29.382465 :C11BCD2E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:01:42.483210 :C11BD102:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:01:42.478 2025-08-17 22:01:42.483212 :C11BD103:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21656 pso_num=28 pso_serial#=218 2025-08-17 22:01:42.483349 :C11BD104:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21656 osp_idx=26 osp_ver=242261 osp_pg=0 (spawn #499160) 2025-08-17 22:01:42.483350 :C11BD105:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 22:01:42.483350 :C11BD106:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=73 time=4148771783 2025-08-17 22:01:42.483351 :C11BD107:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 22:01:42.483351 :C11BD108:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 22:01:42.483351 :C11BD109:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 22:01:42.483352 :C11BD10A:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 22:01:42.483352 :C11BD10B:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 22:01:42.483850 :C11BD10C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 22:01:42.483896 :C11BD112:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-08-17 22:01:42.503881 :C11BD123:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:01:51.507312 :C11BD3DD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:01:51.502 2025-08-17 22:01:51.507314 :C11BD3DE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21336 pso_num=28 pso_serial#=219 2025-08-17 22:01:51.507451 :C11BD3DF:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21336 osp_idx=26 osp_ver=242262 osp_pg=0 (spawn #499161) 2025-08-17 22:01:51.507451 :C11BD3E0:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 22:01:51.507452 :C11BD3E1:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=74 time=4148780814 2025-08-17 22:01:51.507452 :C11BD3E2:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 22:01:51.507452 :C11BD3E3:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 22:01:51.507453 :C11BD3E4:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 22:01:51.507453 :C11BD3E5:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 22:01:51.507454 :C11BD3E6:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 22:01:51.507944 :C11BD3E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 22:01:51.507999 :C11BD3ED:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-08-17 22:01:51.512156 :C11BD3FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 22:01:51.512212 :C11BD400:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 22:01:51.512572 :C11BD401:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 22:01:51.512591 :C11BD402:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 22:01:51.512815 :C11BD403:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 22:01:51.512829 :C11BD404:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 22:01:51.513048 :C11BD405:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 22:01:51.513062 :C11BD406:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 22:01:51.513273 :C11BD407:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 22:01:51.513289 :C11BD408:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 22:01:51.513518 :C11BD409:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 22:01:51.513531 :C11BD40A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 22:01:51.515902 :C11BD438:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 22:01:51.710581 :C11BD80D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 22:01:51.706 2025-08-17 22:01:51.710584 :C11BD80E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20840 pso_num=28 pso_serial#=220 2025-08-17 22:01:51.710721 :C11BD811:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=20840 osp_idx=26 osp_ver=242263 osp_pg=0 (spawn #499163) 2025-08-17 22:01:51.710722 :C11BD812:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 22:01:51.710722 :C11BD813:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=75 time=4148781017 2025-08-17 22:01:51.710723 :C11BD814:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 22:01:51.710723 :C11BD815:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 22:01:51.710724 :C11BD816:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 22:01:51.710724 :C11BD817:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 22:01:51.710725 :C11BD818:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 22:01:51.711317 :C11BD822:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 20840, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de