Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250512220010\orcl_w000_12152_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250512220010\orcl_w000_12152_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:37434M/63366M, Ph+PgF:44763M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12152, image: ORACLE.EXE (W000) *** 2025-05-12 22:00:10.437 *** SESSION ID:(201.10333) 2025-05-12 22:00:10.437 *** 2025-05-12 22:00:10.437 Process diagnostic dump for ORACLE.EXE (W000), OS id=12152, pid: 25, proc_ser: 3, sid: 201, sess_ser: 10333 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=103 seq_num=104 snap_id=1 wait times: snap=2.314798 sec, exc=2.314798 sec, total=2.314798 sec wait times: max=5.000000 sec, heur=8 min 33 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.000025 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=102 seq_num=103 snap_id=1 wait times: snap=5.014740 sec, exc=5.014740 sec, total=5.014740 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=101 seq_num=102 snap_id=1 wait times: snap=5.004630 sec, exc=5.004630 sec, total=5.004630 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=100 seq_num=101 snap_id=1 wait times: snap=5.004621 sec, exc=5.004621 sec, total=5.004621 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=99 seq_num=100 snap_id=1 wait times: snap=5.001728 sec, exc=5.001728 sec, total=5.001728 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=98 seq_num=99 snap_id=1 wait times: snap=5.001260 sec, exc=5.001260 sec, total=5.001260 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=97 seq_num=98 snap_id=1 wait times: snap=5.000504 sec, exc=5.000504 sec, total=5.000504 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=5.009554 sec, exc=5.009554 sec, total=5.009554 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=95 seq_num=96 snap_id=1 wait times: snap=5.004625 sec, exc=5.004625 sec, total=5.004625 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=5.014732 sec, exc=5.014732 sec, total=5.014732 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=93 seq_num=94 snap_id=1 wait times: snap=5.014712 sec, exc=5.014712 sec, total=5.014712 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 201 serial 10333 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 21:58:10 - 22:00:10] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-12 22:00:10.437 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12152, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-12 19:41:20.030472 :9637B39C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3024 pso_num=25 pso_serial#=243 2025-05-12 19:41:20.030605 :9637B39D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3024 osp_idx=23 osp_ver=92807 osp_pg=0 (spawn #175500) 2025-05-12 19:41:20.030606 :9637B39E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 19:41:20.030606 :9637B39F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=155 time=54689907 2025-05-12 19:41:20.030607 :9637B3A0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 19:41:20.030607 :9637B3A1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 19:41:20.030607 :9637B3A2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 19:41:20.030608 :9637B3A3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 19:41:20.030608 :9637B3A4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 19:41:20.031090 :9637B3A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 19:41:20.031135 :9637B3AD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 19:51:20.905967 :96387656:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 19:51:21.348125 :963876B0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 19:51:21.343 2025-05-12 19:51:21.348127 :963876B1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10716 pso_num=25 pso_serial#=244 2025-05-12 19:51:21.348261 :963876B2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10716 osp_idx=23 osp_ver=92808 osp_pg=0 (spawn #175523) 2025-05-12 19:51:21.348262 :963876B3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 19:51:21.348263 :963876B4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=156 time=55291219 2025-05-12 19:51:21.348263 :963876B5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 19:51:21.348264 :963876B6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 19:51:21.348264 :963876B7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 19:51:21.348264 :963876B8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 19:51:21.348265 :963876B9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 19:51:21.348751 :963876BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 19:51:21.348797 :963876C2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 20:01:22.263370 :96393C07:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 20:01:22.752349 :96393C69:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 20:01:22.747 2025-05-12 20:01:22.752351 :96393C6A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12084 pso_num=25 pso_serial#=245 2025-05-12 20:01:22.752487 :96393C6B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12084 osp_idx=23 osp_ver=92809 osp_pg=0 (spawn #175547) 2025-05-12 20:01:22.752488 :96393C6C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 20:01:22.752489 :96393C6D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=157 time=55892625 2025-05-12 20:01:22.752489 :96393C6E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 20:01:22.752489 :96393C6F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 20:01:22.752490 :96393C70:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 20:01:22.752490 :96393C71:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 20:01:22.752490 :96393C72:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 20:01:22.752988 :96393C73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 20:01:22.753036 :96393C7A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 20:11:23.651970 :963A0976:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 20:11:24.166819 :963A09DC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 20:11:24.161 2025-05-12 20:11:24.166822 :963A09DD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13140 pso_num=25 pso_serial#=246 2025-05-12 20:11:24.166958 :963A09DE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13140 osp_idx=23 osp_ver=92810 osp_pg=0 (spawn #175571) 2025-05-12 20:11:24.166959 :963A09DF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 20:11:24.166959 :963A09E0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=158 time=56494032 2025-05-12 20:11:24.166960 :963A09E1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 20:11:24.166960 :963A09E2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 20:11:24.166961 :963A09E3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 20:11:24.166961 :963A09E4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 20:11:24.166961 :963A09E5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 20:11:24.167465 :963A09E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 20:11:24.167508 :963A09EE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 20:21:25.112024 :963ACDC9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 20:21:25.522987 :963ACE22:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 20:21:25.518 2025-05-12 20:21:25.522990 :963ACE23:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4876 pso_num=25 pso_serial#=247 2025-05-12 20:21:25.523126 :963ACE24:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4876 osp_idx=23 osp_ver=92811 osp_pg=0 (spawn #175594) 2025-05-12 20:21:25.523127 :963ACE25:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 20:21:25.523127 :963ACE26:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=159 time=57095391 2025-05-12 20:21:25.523127 :963ACE27:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 20:21:25.523128 :963ACE28:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 20:21:25.523128 :963ACE29:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 20:21:25.523129 :963ACE2A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 20:21:25.523129 :963ACE2B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 20:21:25.523619 :963ACE2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 20:21:25.523665 :963ACE34:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 20:31:26.417084 :963B91FE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 20:31:26.550372 :963B9201:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 20:31:26.541 2025-05-12 20:31:26.550374 :963B9202:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11784 pso_num=25 pso_serial#=248 2025-05-12 20:31:26.550510 :963B9203:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11784 (legacy spawn) 2025-05-12 20:31:26.556980 :963B9204:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 20:31:26.557149 :963B920A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-12 20:31:26.557993 :963B920E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 20:31:26.921541 :963B926C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 20:31:26.916 2025-05-12 20:31:26.921544 :963B926D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8396 pso_num=25 pso_serial#=249 2025-05-12 20:31:26.921679 :963B926E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8396 osp_idx=23 osp_ver=92813 osp_pg=0 (spawn #175616) 2025-05-12 20:31:26.921679 :963B926F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 20:31:26.921680 :963B9270:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=160 time=57696797 2025-05-12 20:31:26.921680 :963B9271:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 20:31:26.921681 :963B9272:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 20:31:26.921681 :963B9273:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 20:31:26.921681 :963B9274:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 20:31:26.921681 :963B9275:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 20:31:26.922171 :963B9276:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 20:31:26.922216 :963B927F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 20:41:27.789112 :963C5606:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 20:41:28.225794 :963C5658:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 20:41:28.221 2025-05-12 20:41:28.225796 :963C5659:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2840 pso_num=25 pso_serial#=250 2025-05-12 20:41:28.225933 :963C565A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=2840 osp_idx=23 osp_ver=92814 osp_pg=0 (spawn #175639) 2025-05-12 20:41:28.225933 :963C565B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 20:41:28.225934 :963C565C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=161 time=58298094 2025-05-12 20:41:28.225934 :963C565D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 20:41:28.225935 :963C565E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 20:41:28.225935 :963C565F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 20:41:28.225935 :963C5660:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 20:41:28.225936 :963C5661:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 20:41:28.226423 :963C5662:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 20:41:28.226467 :963C566B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 20:51:29.199432 :963D19DB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 20:51:29.657195 :963D1A3D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 20:51:29.652 2025-05-12 20:51:29.657197 :963D1A3E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12364 pso_num=25 pso_serial#=251 2025-05-12 20:51:29.657335 :963D1A3F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12364 osp_idx=23 osp_ver=92815 osp_pg=0 (spawn #175662) 2025-05-12 20:51:29.657336 :963D1A40:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 20:51:29.657336 :963D1A41:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=162 time=58899532 2025-05-12 20:51:29.657337 :963D1A42:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 20:51:29.657337 :963D1A43:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 20:51:29.657338 :963D1A44:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 20:51:29.657338 :963D1A45:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 20:51:29.657338 :963D1A46:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 20:51:29.657880 :963D1A47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 20:51:29.657924 :963D1A50:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 20:59:26.465762 :963DB543:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-12 21:01:30.871313 :963DDFE8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-12 21:11:31.845820 :963EAAD4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 21:11:32.209883 :963EAB56:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 21:11:32.205 2025-05-12 21:11:32.209885 :963EAB57:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12964 pso_num=25 pso_serial#=252 2025-05-12 21:11:32.210025 :963EAB58:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12964 osp_idx=23 osp_ver=92816 osp_pg=0 (spawn #175709) 2025-05-12 21:11:32.210025 :963EAB59:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:11:32.210026 :963EAB5A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=163 time=60102079 2025-05-12 21:11:32.210026 :963EAB5B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:11:32.210027 :963EAB5C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:11:32.210027 :963EAB5D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:11:32.210027 :963EAB5E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:11:32.210028 :963EAB5F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:11:32.210519 :963EAB60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 21:11:32.210564 :963EAB69:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 21:21:33.134025 :963F6C05:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 21:21:33.451231 :963F6C89:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 21:21:33.446 2025-05-12 21:21:33.451233 :963F6C8A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11308 pso_num=25 pso_serial#=253 2025-05-12 21:21:33.451367 :963F6C8B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11308 osp_idx=23 osp_ver=92817 osp_pg=0 (spawn #175732) 2025-05-12 21:21:33.451368 :963F6C8C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:21:33.451368 :963F6C8D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=164 time=60703329 2025-05-12 21:21:33.451369 :963F6C8E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:21:33.451369 :963F6C8F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:21:33.451369 :963F6C90:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:21:33.451370 :963F6C91:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:21:33.451370 :963F6C92:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:21:33.451938 :963F6C93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 21:21:33.451981 :963F6C9C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 21:31:34.395813 :96402D37:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 21:31:34.728753 :96402DC6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 21:31:34.724 2025-05-12 21:31:34.728755 :96402DC7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8444 pso_num=25 pso_serial#=254 2025-05-12 21:31:34.728893 :96402DC8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8444 osp_idx=23 osp_ver=92818 osp_pg=0 (spawn #175754) 2025-05-12 21:31:34.728894 :96402DC9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:31:34.728894 :96402DCA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=165 time=61304594 2025-05-12 21:31:34.728895 :96402DCB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:31:34.728895 :96402DCC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:31:34.728895 :96402DCD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:31:34.728895 :96402DCE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:31:34.728896 :96402DCF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:31:34.729393 :96402DD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 21:31:34.729445 :96402DD7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 21:41:35.711619 :9640EDF1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 21:41:35.998638 :9640EE83:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 21:41:35.992 2025-05-12 21:41:35.998640 :9640EE84:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12308 pso_num=25 pso_serial#=255 2025-05-12 21:41:35.998805 :9640EE85:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12308 osp_idx=23 osp_ver=92819 osp_pg=0 (spawn #175777) 2025-05-12 21:41:35.998805 :9640EE86:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:41:35.998806 :9640EE87:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=166 time=61905860 2025-05-12 21:41:35.998806 :9640EE88:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:41:35.998806 :9640EE89:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:41:35.998807 :9640EE8A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:41:35.998807 :9640EE8B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:41:35.998808 :9640EE8C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:41:35.999392 :9640EE8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 21:41:35.999465 :9640EE97:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-12 21:51:37.009135 :9641AD27:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 21:51:37.142349 :9641AD2A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 21:51:37.134 2025-05-12 21:51:37.142350 :9641AD2B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12056 pso_num=25 pso_serial#=1 2025-05-12 21:51:37.142493 :9641AD2C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12056 (legacy spawn) 2025-05-12 21:51:37.149003 :9641AD2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 21:51:37.149177 :9641AD36:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-12 21:51:37.150011 :9641AD37:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 21:51:37.158655 :9641AD3A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 21:51:37.150 2025-05-12 21:51:37.158657 :9641AD3B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13132 pso_num=25 pso_serial#=2 2025-05-12 21:51:37.158797 :9641AD3C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13132 (legacy spawn) 2025-05-12 21:51:37.165248 :9641AD3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 21:51:37.165404 :9641AD46:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-12 21:51:37.166205 :9641AD47:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 21:51:37.263864 :9641ADCD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 21:51:37.259 2025-05-12 21:51:37.263866 :9641ADCE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12152 pso_num=25 pso_serial#=3 2025-05-12 21:51:37.264002 :9641ADCF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12152 osp_idx=23 osp_ver=92822 osp_pg=0 (spawn #175800) 2025-05-12 21:51:37.264003 :9641ADD0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:51:37.264003 :9641ADD1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=167 time=62507141 2025-05-12 21:51:37.264004 :9641ADD2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:51:37.264004 :9641ADD3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:51:37.264004 :9641ADD4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:51:37.264005 :9641ADD5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:51:37.264005 :9641ADD6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:51:37.264486 :9641ADD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 21:51:37.264529 :9641ADDF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 12152, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de