Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250916220006\orcl_j005_24156_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250916220006\orcl_j005_24156_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:35980M/63366M, Ph+PgF:40300M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 24156, image: ORACLE.EXE (J005) *** 2025-09-16 22:00:06.752 *** SESSION ID:(249.2033) 2025-09-16 22:00:06.752 *** 2025-09-16 22:00:06.752 Process diagnostic dump for ORACLE.EXE (J005), OS id=24156, pid: 31, proc_ser: 225, sid: 249, sess_ser: 2033 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 24156 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.051278 sec, exc=0.051278 sec, total=0.051278 sec wait times: max=0.500000 sec, heur=4.652300 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500123 sec, exc=0.500123 sec, total=0.500123 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 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=3 seq_num=4 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500110 sec, exc=0.500110 sec, total=0.500110 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499246 sec, exc=0.499246 sec, total=0.499246 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.101455 sec, exc=0.101455 sec, total=0.101455 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 249 serial 2033 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 22:00:02 - 22:00:06] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-16 22:00:06.752 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 24156, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-10 23:59:10.299002 :CBBD6937:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-10 23:59:10.295 2025-09-10 23:59:10.299004 :CBBD6938:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14932 pso_num=31 pso_serial#=205 2025-09-10 23:59:10.299150 :CBBD6939:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14932 (legacy spawn) 2025-09-10 23:59:10.313579 :CBBD693A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-10 23:59:43.797255 :CBBD74ED:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-10 23:59:44.946020 :CBBD7560:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-10 23:59:44.939 2025-09-10 23:59:44.946022 :CBBD7561:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21708 pso_num=31 pso_serial#=206 2025-09-10 23:59:44.946178 :CBBD7562:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=21708 osp_idx=29 osp_ver=970 osp_pg=0 (spawn #579445) 2025-09-10 23:59:44.946179 :CBBD7563:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 23:59:44.946181 :CBBD7564:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=25 time=1934436252 2025-09-10 23:59:44.946181 :CBBD7565:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 23:59:44.946181 :CBBD7566:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 23:59:44.946181 :CBBD7567:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 23:59:44.946182 :CBBD7568:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 23:59:44.946182 :CBBD7569:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 23:59:44.946748 :CBBD756A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-10 23:59:44.947470 :CBBD757F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-10 23:59:44.955518 :CBBD759C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-09-10 23:59:44.955943 :CBBD759D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-10 23:59:44.955981 :CBBD75A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-09-10 23:59:44.956859 :CBBD75A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-10 23:59:44.956985 :CBBD75AF:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-11 00:00:15.026357 :CBBD7FEF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-11 22:00:00.436955 :CC24471B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-11 22:00:00.428 2025-09-11 22:00:00.436956 :CC24471C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=25496 pso_num=31 pso_serial#=207 2025-09-11 22:00:00.437094 :CC24471D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=25496 osp_idx=29 osp_ver=971 osp_pg=0 (spawn #582504) 2025-09-11 22:00:00.437095 :CC24471E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-11 22:00:00.437096 :CC24471F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=26 time=2013651737 2025-09-11 22:00:00.437097 :CC244720:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-11 22:00:00.437097 :CC244721:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-11 22:00:00.437098 :CC244722:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-11 22:00:00.437098 :CC244723:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-11 22:00:00.437099 :CC244724:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-11 22:00:00.437606 :CC24472D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-11 22:00:30.536786 :CC2457E9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-11 23:59:10.181356 :CC2D9F70:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-11 23:59:10.169 2025-09-11 23:59:10.181359 :CC2D9F71:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21740 pso_num=31 pso_serial#=208 2025-09-11 23:59:10.181526 :CC2D9F72:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21740 (legacy spawn) 2025-09-11 23:59:10.196766 :CC2D9F73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-11 23:59:43.366243 :CC2DAB56:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-11 23:59:44.439384 :CC2DAB79:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-11 23:59:44.426 2025-09-11 23:59:44.439386 :CC2DAB7A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23620 pso_num=31 pso_serial#=209 2025-09-11 23:59:44.439528 :CC2DAB7B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23620 (legacy spawn) 2025-09-11 23:59:44.454053 :CC2DAB7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-12 00:00:17.485927 :CC2DB887:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-12 22:00:01.110779 :CC94A16B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-12 22:00:01.103 2025-09-12 22:00:01.110780 :CC94A16C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17500 pso_num=31 pso_serial#=210 2025-09-12 22:00:01.110931 :CC94A16D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17500 osp_idx=29 osp_ver=974 osp_pg=0 (spawn #585835) 2025-09-12 22:00:01.110932 :CC94A16E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-12 22:00:01.110933 :CC94A16F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=27 time=2100052408 2025-09-12 22:00:01.110933 :CC94A170:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 22:00:01.110934 :CC94A171:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 22:00:01.110934 :CC94A172:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 22:00:01.110934 :CC94A173:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 22:00:01.110935 :CC94A174:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 22:00:01.111514 :CC94A17E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-12 22:00:31.178914 :CC94B27A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-12 23:59:10.244727 :CC9E18CA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-12 23:59:10.230 2025-09-12 23:59:10.244729 :CC9E18CB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24896 pso_num=31 pso_serial#=211 2025-09-12 23:59:10.244870 :CC9E18CC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24896 (legacy spawn) 2025-09-12 23:59:10.259065 :CC9E18CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-12 23:59:43.732609 :CC9E24B6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-12 23:59:43.915776 :CC9E24B9:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-12 23:59:43.901 2025-09-12 23:59:43.915778 :CC9E24BA:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19888 pso_num=31 pso_serial#=212 2025-09-12 23:59:43.915920 :CC9E24BB:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19888 (legacy spawn) 2025-09-12 23:59:43.929924 :CC9E24BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-13 00:00:16.962423 :CC9E3217:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-13 06:00:02.054117 :CCBAF6F7:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-13 06:00:02.051 2025-09-13 06:00:02.054118 :CCBAF6F8:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17248 pso_num=31 pso_serial#=213 2025-09-13 06:00:02.054249 :CCBAF6FD:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17248 osp_idx=29 osp_ver=977 osp_pg=0 (spawn #586943) 2025-09-13 06:00:02.054250 :CCBAF6FE:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 06:00:02.054250 :CCBAF6FF:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=28 time=2128853346 2025-09-13 06:00:02.054251 :CCBAF700:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 06:00:02.054252 :CCBAF701:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 06:00:02.054252 :CCBAF702:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 06:00:02.054253 :CCBAF703:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 06:00:02.054253 :CCBAF704:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 06:00:02.054753 :CCBAF705:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 06:00:32.147332 :CCBB09BC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-13 23:59:09.826317 :CD10BF60:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-13 23:59:09.817 2025-09-13 23:59:09.826319 :CD10BF61:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24556 pso_num=31 pso_serial#=214 2025-09-13 23:59:09.826460 :CD10BF62:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24556 (legacy spawn) 2025-09-13 23:59:09.840477 :CD10BF63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-13 23:59:43.109565 :CD10CB2C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-13 23:59:43.619673 :CD10CB74:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-13 23:59:43.606 2025-09-13 23:59:43.619677 :CD10CB75:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24848 pso_num=31 pso_serial#=215 2025-09-13 23:59:43.619912 :CD10CB76:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24848 (legacy spawn) 2025-09-13 23:59:43.634565 :CD10CB77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 00:00:16.684165 :CD10D87B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-14 06:00:02.080711 :CD2CC34E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-14 06:00:02.077 2025-09-14 06:00:02.080712 :CD2CC34F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14152 pso_num=31 pso_serial#=216 2025-09-14 06:00:02.080847 :CD2CC350:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=14152 osp_idx=29 osp_ver=980 osp_pg=0 (spawn #590287) 2025-09-14 06:00:02.080848 :CD2CC351:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 06:00:02.080848 :CD2CC352:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=29 time=2215253377 2025-09-14 06:00:02.080849 :CD2CC353:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 06:00:02.080849 :CD2CC354:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 06:00:02.080849 :CD2CC355:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 06:00:02.080850 :CD2CC356:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 06:00:02.080850 :CD2CC357:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 06:00:02.081424 :CD2CC359:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 06:00:32.178311 :CD2CD6E5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-14 23:59:09.668594 :CD8289A0:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-14 23:59:09.667 2025-09-14 23:59:09.668597 :CD8289A1:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21264 pso_num=31 pso_serial#=217 2025-09-14 23:59:09.668750 :CD8289A2:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21264 (legacy spawn) 2025-09-14 23:59:09.683710 :CD8289A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 23:59:42.993479 :CD8295C8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-14 23:59:47.214221 :CD82974C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-14 23:59:47.203 2025-09-14 23:59:47.214223 :CD82974D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=25076 pso_num=31 pso_serial#=218 2025-09-14 23:59:47.214375 :CD82974E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=25076 (legacy spawn) 2025-09-14 23:59:47.221486 :CD82974F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 23:59:47.221676 :CD829758:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-14 23:59:47.222591 :CD829759:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-14 23:59:50.509084 :CD829862:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-14 23:59:50.506 2025-09-14 23:59:50.509086 :CD829863:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11336 pso_num=31 pso_serial#=219 2025-09-14 23:59:50.509228 :CD829864:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11336 (legacy spawn) 2025-09-14 23:59:50.523899 :CD829865:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 00:00:23.916881 :CD82A749:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-15 16:00:16.993734 :CDCE9E01:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 16:00:16.983 2025-09-15 16:00:16.993735 :CDCE9E02:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8216 pso_num=31 pso_serial#=220 2025-09-15 16:00:16.993867 :CDCE9E03:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=8216 osp_idx=29 osp_ver=984 osp_pg=0 (spawn #595015) 2025-09-15 16:00:16.993868 :CDCE9E04:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 16:00:16.993869 :CDCE9E05:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=30 time=2337668299 2025-09-15 16:00:16.993870 :CDCE9E06:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 16:00:16.993870 :CDCE9E07:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 16:00:16.993871 :CDCE9E08:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 16:00:16.993871 :CDCE9E09:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 16:00:16.993871 :CDCE9E0A:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 16:00:16.995208 :CDCE9E0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 16:00:16.995272 :CDCE9E11:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-09-15 16:00:17.003966 :CDCE9E23:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-15 22:00:00.519793 :CDEB2659:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 22:00:00.516 2025-09-15 22:00:00.519794 :CDEB265A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23724 pso_num=31 pso_serial#=221 2025-09-15 22:00:00.519977 :CDEB268D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=23724 osp_idx=29 osp_ver=985 osp_pg=0 (spawn #595849) 2025-09-15 22:00:00.519978 :CDEB268F:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 22:00:00.519979 :CDEB2690:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=31 time=2359251815 2025-09-15 22:00:00.519979 :CDEB2691:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 22:00:00.519980 :CDEB2692:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 22:00:00.519981 :CDEB2693:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 22:00:00.519981 :CDEB2694:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 22:00:00.519982 :CDEB2696:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 22:00:00.520546 :CDEB26BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 22:00:30.612648 :CDEB3A31:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-15 23:59:16.309713 :CDF4B4FB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 23:59:16.304 2025-09-15 23:59:16.309715 :CDF4B4FC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18956 pso_num=31 pso_serial#=222 2025-09-15 23:59:16.309859 :CDF4B4FD:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=18956 osp_idx=29 osp_ver=986 osp_pg=0 (spawn #596123) 2025-09-15 23:59:16.309860 :CDF4B4FE:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 23:59:16.309861 :CDF4B4FF:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=32 time=2366407612 2025-09-15 23:59:16.309861 :CDF4B500:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 23:59:16.309862 :CDF4B501:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 23:59:16.309862 :CDF4B502:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 23:59:16.309862 :CDF4B503:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 23:59:16.309863 :CDF4B504:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 23:59:16.310388 :CDF4B505:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 23:59:16.311049 :CDF4B51A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 23:59:16.318973 :CDF4B537:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-09-15 23:59:16.319367 :CDF4B538:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 23:59:16.319401 :CDF4B53B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-09-15 23:59:16.320317 :CDF4B541:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 23:59:16.320448 :CDF4B54A:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-15 23:59:46.379308 :CDF4C076:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-15 23:59:54.888084 :CDF4C35B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 23:59:54.888 2025-09-15 23:59:54.888087 :CDF4C35C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24944 pso_num=31 pso_serial#=223 2025-09-15 23:59:54.888259 :CDF4C35D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24944 (legacy spawn) 2025-09-15 23:59:54.903489 :CDF4C35E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 23:59:58.227079 :CDF4C499:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-15 23:59:58.227246 :CDF4C49E:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-15 23:59:58.228438 :CDF4C4A3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-15 23:59:58.485222 :CDF4C4A6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 23:59:58.476 2025-09-15 23:59:58.485224 :CDF4C4A7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17404 pso_num=31 pso_serial#=224 2025-09-15 23:59:58.485407 :CDF4C4A8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17404 (legacy spawn) 2025-09-15 23:59:58.501429 :CDF4C4A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 00:06:18.460848 :CDF54F88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 00:06:18.460879 :CDF54F8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 00:06:18.461020 :CDF54F92:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-16 00:06:18.462056 :CDF54F9F:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-16 22:00:02.103880 :CE5D7F75:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-16 22:00:02.096 2025-09-16 22:00:02.103881 :CE5D7F76:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24156 pso_num=31 pso_serial#=225 2025-09-16 22:00:02.104033 :CE5D7F78:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=24156 osp_idx=29 osp_ver=989 osp_pg=0 (spawn #599188) 2025-09-16 22:00:02.104033 :CE5D7F79:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 22:00:02.104034 :CE5D7F7A:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=33 time=2445653408 2025-09-16 22:00:02.104035 :CE5D7F7B:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 22:00:02.104035 :CE5D7F7C:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 22:00:02.104036 :CE5D7F7D:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 22:00:02.104037 :CE5D7F7E:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 22:00:02.104037 :CE5D7F7F:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 22:00:02.104714 :CE5D7F80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.206177 :CE5D8237:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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 31 (osid: 24156, J005)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de