Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250512220005\orcl_j002_12424_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250512220005\orcl_j002_12424_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:37430M/63366M, Ph+PgF:44756M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 12424, image: ORACLE.EXE (J002) *** 2025-05-12 22:00:05.990 *** SESSION ID:(225.22997) 2025-05-12 22:00:05.990 *** 2025-05-12 22:00:05.990 Process diagnostic dump for ORACLE.EXE (J002), OS id=12424, pid: 28, proc_ser: 29, sid: 225, sess_ser: 22997 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12424 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) 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.298915 sec, exc=0.298915 sec, total=0.298915 sec wait times: max=0.500000 sec, heur=3.903189 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.000011 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.499917 sec, exc=0.499917 sec, total=0.499917 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 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.500079 sec, exc=0.500079 sec, total=0.500079 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.499208 sec, exc=0.499208 sec, total=0.499208 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 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.016423 sec, exc=0.016423 sec, total=0.016423 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.504588 sec, exc=0.504588 sec, total=0.504588 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.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.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.500000 sec, exc=0.500000 sec, total=0.500000 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=1 seq_num=2 snap_id=1 wait times: snap=0.499145 sec, exc=0.499145 sec, total=0.499145 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 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.084849 sec, exc=0.084849 sec, total=0.084849 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 225 serial 22997 --------------------------------------------------- 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:05] 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-12 22:00:05.990 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 12424, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-12 13:00:04.179570 :9618B0A4: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-05-12 13:00:04.180001 :9618B0AC: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-05-12 13:00:04.182555 :9618B0B4: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-05-12 13:00:04.183642 :9618B0BC: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-05-12 13:00:04.232341 :9618B0C4: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-05-12 13:00:04.233220 :9618B0CC: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-05-12 13:00:04.233453 :9618B0D4: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-05-12 13:00:04.233677 :9618B0DC: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-05-12 13:00:04.235477 :9618B0E4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 14:50:48.592923 :96213C75:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 14:50:48.584 2025-05-12 14:50:48.592925 :96213C76:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11828 pso_num=28 pso_serial#=19 2025-05-12 14:50:48.593062 :96213C77:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11828 (legacy spawn) 2025-05-12 14:50:48.604409 :96213C85: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-05-12 14:50:48.604547 :96213C8E: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-05-12 14:50:48.605516 :96213C8F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 16:00:12.582007 :96269B1D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 16:00:12.572 2025-05-12 16:00:12.582009 :96269B1E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=3024 pso_num=28 pso_serial#=20 2025-05-12 16:00:12.582144 :96269B1F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=3024 (legacy spawn) 2025-05-12 16:00:12.588629 :96269B20: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-05-12 16:00:12.588802 :96269B29: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-05-12 16:00:12.589588 :96269B2A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 16:00:15.902349 :96269C27:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 16:00:15.894 2025-05-12 16:00:15.902351 :96269C28:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12076 pso_num=28 pso_serial#=21 2025-05-12 16:00:15.902493 :96269C29:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12076 (legacy spawn) 2025-05-12 16:00:15.908969 :96269C2A: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-05-12 16:00:15.909155 :96269C31: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-05-12 16:00:15.910005 :96269C34:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 16:00:25.041051 :96269EFB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 16:00:25.033 2025-05-12 16:00:25.041053 :96269EFC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13056 pso_num=28 pso_serial#=22 2025-05-12 16:00:25.041190 :96269EFD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13056 (legacy spawn) 2025-05-12 16:00:25.047574 :96269EFE: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-05-12 16:00:25.047764 :96269F03: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-05-12 16:00:25.048543 :96269F08:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 16:00:26.866171 :96269FB7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 16:00:26.861 2025-05-12 16:00:26.866173 :96269FB8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11416 pso_num=28 pso_serial#=23 2025-05-12 16:00:26.866308 :96269FB9:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=11416 osp_idx=26 osp_ver=109672 osp_pg=0 (spawn #174988) 2025-05-12 16:00:26.866309 :96269FBA:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 16:00:26.866309 :96269FBB:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=220 time=41436735 2025-05-12 16:00:26.866309 :96269FBC:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 16:00:26.866310 :96269FBD:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 16:00:26.866310 :96269FBE:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 16:00:26.866310 :96269FBF:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 16:00:26.866311 :96269FC0:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 16:00:26.866797 :96269FC1: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-05-12 16:00:26.866840 :96269FC7: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-05-12 16:00:26.871241 :96269FDA: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-05-12 16:00:26.893209 :96269FE2: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-05-12 16:00:26.908427 :96269FEA: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-05-12 16:00:26.932931 :96269FF2: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-05-12 16:00:26.936594 :96269FFA: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-05-12 16:00:26.940396 :9626A002: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-05-12 16:00:26.944447 :9626A00A: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-05-12 16:00:26.945504 :9626A012: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-05-12 16:00:28.001561*:9626A059:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029122780 estart=1747036826 eid=16778427 ctx=0x000000077BA4B1C8 cwh=0x00000000290E0828 cpu=437500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-12 16:00:28.001561*:9626A05A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029122780 curCtx=0x00000000291214B8 pubTabIdxCnt=0 2025-05-12 16:00:28.001561*:9626A05B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029122780 2025-05-12 16:00:28.001561*:9626A05C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029122780 2025-05-12 16:00:28.001561*:9626A05D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000290F2D18 estart=1747036826 eid=16778420 ctx=0x0000000787AD5BF0 cwh=0x00000000290E0410 cpu=437500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-12 16:00:28.001561*:9626A05E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000290F2D18 curCtx=0x0000000029123720 pubTabIdxCnt=0 2025-05-12 16:00:28.001561*:9626A05F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000290F2D18 2025-05-12 16:00:28.001561*:9626A060:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000290F2D18 2025-05-12 16:00:28.121039*:9626A061:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029122780 curCtx=0x00000000291214B8 xsc->flg4=65792 flags=1 2025-05-12 16:00:28.121039*:9626A062:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029122780 2025-05-12 16:00:28.121039*:9626A063:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000290F2D18 curCtx=0x0000000029123720 xsc->flg4=65792 flags=2 2025-05-12 16:00:28.121039*:9626A064:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000290F2D18 2025-05-12 16:00:28.312862 :9626A065: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-05-12 16:00:28.340278 :9626A068: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-05-12 16:00:28.340885 :9626A069: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-05-12 16:00:28.353926 :9626A0A7: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-05-12 16:00:28.356806 :9626A0B8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 16:00:29.924990 :9626A1BC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 16:00:29.918 2025-05-12 16:00:29.924993 :9626A1BD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8520 pso_num=28 pso_serial#=24 2025-05-12 16:00:29.925147 :9626A1BE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8520 (legacy spawn) 2025-05-12 16:00:29.931927 :9626A1BF: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-05-12 16:00:29.932108 :9626A1C8: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-05-12 16:00:29.932972 :9626A1C9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 16:00:29.942289 :9626A1CC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 16:00:29.934 2025-05-12 16:00:29.942291 :9626A1CD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11408 pso_num=28 pso_serial#=25 2025-05-12 16:00:29.942455 :9626A1CE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11408 (legacy spawn) 2025-05-12 16:00:29.949597 :9626A1CF: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-05-12 16:00:29.949771 :9626A1D7: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-05-12 16:00:29.950717 :9626A1D9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 17:46:00.317723 :962EC7AC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 17:46:00.303 2025-05-12 17:46:00.317725 :962EC7AD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=3056 pso_num=28 pso_serial#=26 2025-05-12 17:46:00.317858 :962EC7AE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=3056 (legacy spawn) 2025-05-12 17:46:00.324298 :962EC7AF: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-05-12 17:46:00.368580 :962EC7BF: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-05-12 17:46:00.369307 :962EC7C6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 21:00:08.643226 :963DC468:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 21:00:08.628 2025-05-12 21:00:08.643228 :963DC469:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11668 pso_num=28 pso_serial#=27 2025-05-12 21:00:08.643356 :963DC46A:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=11668 osp_idx=26 osp_ver=109676 osp_pg=0 (spawn #175683) 2025-05-12 21:00:08.643357 :963DC46B:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:00:08.643358 :963DC46C:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=221 time=59418500 2025-05-12 21:00:08.643358 :963DC46D:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:00:08.643358 :963DC46E:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:00:08.643359 :963DC46F:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:00:08.643359 :963DC470:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:00:08.643359 :963DC471:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:00:08.643903 :963DC472: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-05-12 21:00:08.643949 :963DC478: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-05-12 21:00:08.651534 :963DC486: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-05-12 21:00:08.652645 :963DC48E: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-05-12 21:00:08.653194 :963DC496: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-05-12 21:00:08.653643 :963DC49E: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-05-12 21:00:08.654068 :963DC4A6: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-05-12 21:00:08.656556 :963DC4AE: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-05-12 21:00:08.657654 :963DC4B6: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-05-12 21:00:08.706137 :963DC4BE: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-05-12 21:00:08.707044 :963DC4C6: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-05-12 21:00:08.707283 :963DC4CE: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-05-12 21:00:08.707503 :963DC4D6: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-05-12 21:00:08.709321 :963DC4DE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 21:32:11.366764 :964039C9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 21:32:11.354 2025-05-12 21:32:11.366766 :964039CA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=4640 pso_num=28 pso_serial#=28 2025-05-12 21:32:11.366899 :964039CB:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=4640 osp_idx=26 osp_ver=109677 osp_pg=0 (spawn #175758) 2025-05-12 21:32:11.366899 :964039CC:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:32:11.366900 :964039CD:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=222 time=61341235 2025-05-12 21:32:11.366900 :964039CE:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:32:11.366900 :964039CF:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:32:11.366901 :964039D0:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:32:11.366901 :964039D1:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:32:11.366901 :964039D2:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:32:11.367401 :964039D3: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-05-12 21:32:11.367444 :964039D9: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-05-12 21:32:11.371540 :964039F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-12 21:32:11.371594 :964039F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-12 21:32:11.371846 :964039F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-12 21:32:11.371862 :964039F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-12 21:32:11.372072 :964039F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-12 21:32:11.372085 :964039F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-12 21:32:11.372297 :964039F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-12 21:32:11.372310 :964039F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-12 21:32:11.372516 :964039FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-12 21:32:11.372529 :964039FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-12 21:32:11.372730 :964039FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-12 21:32:11.372745 :964039FD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-12 21:32:11.375162 :964039FE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-12 22:00:02.048450 :96424F76:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-12 22:00:02.048 2025-05-12 22:00:02.048452 :96424F77:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12424 pso_num=28 pso_serial#=29 2025-05-12 22:00:02.048592 :96424F78:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12424 osp_idx=26 osp_ver=109678 osp_pg=0 (spawn #175822) 2025-05-12 22:00:02.048594 :96424F79:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 22:00:02.048594 :96424F7A:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=223 time=63011907 2025-05-12 22:00:02.048595 :96424F7B:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 22:00:02.048595 :96424F7C:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 22:00:02.048595 :96424F7D:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 22:00:02.048596 :96424F7E:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 22:00:02.048596 :96424F7F:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 22:00:02.049143 :96424F89: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 2025-05-12 22:00:02.054205 :96424FA8: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-05-12 22:00:02.054249 :96424FAB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-05-12 22:00:02.072279 :96425064:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-05-12 22:00:02.072896 :96425065:db_trace:kcc.c@13684:kccwbp(): [10021:28:225:1] 0115000000000000 0115000000000000 04c6110000000000 2025-05-12 22:00:02.074585 :96425066:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-12 22:00:02.074660 :96425074:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-12 22:00:02.074675 :9642507A:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-05-12 22:00:02.074683 :9642507B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 22:00:02.074714 :96425089:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-12 22:00:02.075485 :96425093:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 225111814) 2025-05-12 22:00:02.075489 :96425094:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 22:00:02.075511 :964250A0:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-12 22:00:02.075520 :964250AC:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65564 1) 2025-05-12 22:00:02.075522 :964250AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 22:00:02.075550 :964250BA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-12 22:00:02.075553 :964250C3:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65564) 2025-05-12 22:00:02.075555 :964250C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 22:00:02.075609 :964250E7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-12 22:00:02.076343 :96425198:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-12 22:00:02.076357 :96425199:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-05-12 22:00:02.077179 :964251A0:db_trace:kcc.c@13684:kccwbp(): [10021:28:225:1] 0115000000000000 0115000000000000 05c6110000000000 2025-05-12 22:00:02.077543 :964251A1:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 225111814) 2025-05-12 22:00:02.077547 :964251A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 22:00:02.077571 :964251AE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-12 22:00:02.086144 :96425282:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:jslv.c:9203 2025-05-12 22:00:02.087961 :96425283: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-05-12 22:00:02.088019 :96425286:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-12 22:00:02.088123 :96425293: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-05-12 22:00:02.173636 :964252B8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-12 22:00:04.193595 :9642576B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 28 (osid: 12424, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de