Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250524081114\orcl_w000_4728_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250524081114\orcl_w000_4728_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:37306M/63366M, Ph+PgF:44327M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 4728, image: ORACLE.EXE (W000) *** 2025-05-24 08:11:14.280 *** SESSION ID:(177.7471) 2025-05-24 08:11:14.280 *** 2025-05-24 08:11:14.280 Process diagnostic dump for ORACLE.EXE (W000), OS id=4728, pid: 22, proc_ser: 67, sid: 177, sess_ser: 7471 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=3.545709 sec, exc=3.545709 sec, total=3.545709 sec wait times: max=5.000000 sec, heur=2 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.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.010144 sec, exc=5.010144 sec, total=5.010144 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=5.004196 sec, exc=5.004196 sec, total=5.004196 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.003202 sec, exc=5.003202 sec, total=5.003202 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.012830 sec, exc=5.012830 sec, total=5.012830 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=5.010746 sec, exc=5.010746 sec, total=5.010746 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=5.009255 sec, exc=5.009255 sec, total=5.009255 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.010146 sec, exc=5.010146 sec, total=5.010146 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=23 seq_num=24 snap_id=1 wait times: snap=5.001702 sec, exc=5.001702 sec, total=5.001702 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=5.001254 sec, exc=5.001254 sec, total=5.001254 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=5.013451 sec, exc=5.013451 sec, total=5.013451 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time Sampled Session History of session 177 serial 7471 --------------------------------------------------- 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, 08:09:14 - 08:11:14] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-24 08:11:14.280 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 4728, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-24 05:48:20.838159 :9B3D4682:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13080 pso_num=22 pso_serial#=53 2025-05-24 05:48:20.838296 :9B3D4683:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13080 osp_idx=0 osp_ver=228278 osp_pg=0 (spawn #213580) 2025-05-24 05:48:20.838297 :9B3D4684:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 05:48:20.838297 :9B3D4685:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=1041473094 2025-05-24 05:48:20.838298 :9B3D4686:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 05:48:20.838298 :9B3D4687:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 05:48:20.838298 :9B3D4688:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 05:48:20.838299 :9B3D4689:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 05:48:20.838299 :9B3D468A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 05:48:20.838798 :9B3D468B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 05:48:20.838842 :9B3D4693:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 05:48:33.488619 :9B3D4A87:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 05:58:22.175773 :9B3E0B45:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 06:08:23.074282 :9B3EE6C2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 06:08:23.485125 :9B3EE6FF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 06:08:23.480 2025-05-24 06:08:23.485127 :9B3EE700:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11216 pso_num=22 pso_serial#=54 2025-05-24 06:08:23.485264 :9B3EE701:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11216 osp_idx=0 osp_ver=228279 osp_pg=0 (spawn #213633) 2025-05-24 06:08:23.485265 :9B3EE702:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 06:08:23.485266 :9B3EE703:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=7 time=1042675735 2025-05-24 06:08:23.485266 :9B3EE704:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 06:08:23.485267 :9B3EE705:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 06:08:23.485267 :9B3EE706:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 06:08:23.485268 :9B3EE707:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 06:08:23.485268 :9B3EE708:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 06:08:23.485779 :9B3EE709:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 06:08:23.485831 :9B3EE710:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 06:18:24.397073 :9B3FAF78:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 06:18:24.808208 :9B3FAFDB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 06:18:24.803 2025-05-24 06:18:24.808210 :9B3FAFDC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13408 pso_num=22 pso_serial#=55 2025-05-24 06:18:24.808349 :9B3FAFDD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13408 osp_idx=0 osp_ver=228280 osp_pg=0 (spawn #213656) 2025-05-24 06:18:24.808350 :9B3FAFDE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 06:18:24.808350 :9B3FAFDF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=8 time=1043277063 2025-05-24 06:18:24.808351 :9B3FAFE0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 06:18:24.808351 :9B3FAFE1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 06:18:24.808352 :9B3FAFE2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 06:18:24.808352 :9B3FAFE3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 06:18:24.808352 :9B3FAFE4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 06:18:24.808866 :9B3FAFE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 06:18:24.808910 :9B3FAFED:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 06:18:54.863385 :9B3FBABD:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-05-24 06:18:54.863419 :9B3FBAC8:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-05-24 06:18:54.863421 :9B3FBAC9:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=33, exc=33, tot=33 2025-05-24 06:28:25.808869 :9B407443:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 06:28:26.204245 :9B4074A5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 06:28:26.200 2025-05-24 06:28:26.204247 :9B4074A6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10596 pso_num=22 pso_serial#=56 2025-05-24 06:28:26.204390 :9B4074A7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10596 osp_idx=0 osp_ver=228281 osp_pg=0 (spawn #213679) 2025-05-24 06:28:26.204391 :9B4074A8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 06:28:26.204391 :9B4074A9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=9 time=1043878454 2025-05-24 06:28:26.204392 :9B4074AA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 06:28:26.204392 :9B4074AB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 06:28:26.204392 :9B4074AC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 06:28:26.204393 :9B4074AD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 06:28:26.204393 :9B4074AE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 06:28:26.204900 :9B4074AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 06:28:26.204949 :9B4074B9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 06:38:27.219446 :9B41397F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 06:38:27.646327 :9B4139E4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 06:38:27.641 2025-05-24 06:38:27.646329 :9B4139E5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10280 pso_num=22 pso_serial#=57 2025-05-24 06:38:27.646464 :9B4139E6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10280 osp_idx=0 osp_ver=228282 osp_pg=0 (spawn #213701) 2025-05-24 06:38:27.646465 :9B4139E7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 06:38:27.646466 :9B4139E8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=10 time=1044479907 2025-05-24 06:38:27.646466 :9B4139E9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 06:38:27.646466 :9B4139EA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 06:38:27.646467 :9B4139EB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 06:38:27.646467 :9B4139EC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 06:38:27.646467 :9B4139ED:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 06:38:27.646972 :9B4139EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 06:38:27.647019 :9B4139F5:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 06:48:28.584059 :9B41FF64:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 06:48:29.073159 :9B41FFEF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 06:48:29.068 2025-05-24 06:48:29.073161 :9B41FFF0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12684 pso_num=22 pso_serial#=58 2025-05-24 06:48:29.073294 :9B41FFF1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12684 osp_idx=0 osp_ver=228283 osp_pg=0 (spawn #213725) 2025-05-24 06:48:29.073295 :9B41FFF2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 06:48:29.073295 :9B41FFF3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=11 time=1045081329 2025-05-24 06:48:29.073296 :9B41FFF4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 06:48:29.073296 :9B41FFF5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 06:48:29.073296 :9B41FFF6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 06:48:29.073297 :9B41FFF7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 06:48:29.073297 :9B41FFF8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 06:48:29.073790 :9B41FFF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 06:48:29.073840 :9B420003:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 06:58:30.023624 :9B42C49A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 06:58:30.528329 :9B42C4F6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 06:58:30.523 2025-05-24 06:58:30.528331 :9B42C4F7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12872 pso_num=22 pso_serial#=59 2025-05-24 06:58:30.528469 :9B42C4F8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12872 osp_idx=0 osp_ver=228284 osp_pg=0 (spawn #213748) 2025-05-24 06:58:30.528470 :9B42C4F9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 06:58:30.528470 :9B42C4FA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=12 time=1045682782 2025-05-24 06:58:30.528471 :9B42C4FB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 06:58:30.528471 :9B42C4FC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 06:58:30.528471 :9B42C4FD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 06:58:30.528471 :9B42C4FE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 06:58:30.528472 :9B42C4FF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 06:58:30.528963 :9B42C500:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 06:58:30.529008 :9B42C508:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 07:08:31.474965 :9B4395A5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 07:08:32.047127 :9B439635:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 07:08:32.042 2025-05-24 07:08:32.047129 :9B439636:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13980 pso_num=22 pso_serial#=60 2025-05-24 07:08:32.047266 :9B439637:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13980 osp_idx=0 osp_ver=228285 osp_pg=0 (spawn #213773) 2025-05-24 07:08:32.047266 :9B439638:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 07:08:32.047267 :9B439639:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=13 time=1046284297 2025-05-24 07:08:32.047267 :9B43963A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 07:08:32.047267 :9B43963B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 07:08:32.047268 :9B43963C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 07:08:32.047268 :9B43963D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 07:08:32.047268 :9B43963E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 07:08:32.047762 :9B43963F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 07:08:32.047809 :9B439648:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 07:18:32.955172 :9B445C65:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 07:18:33.538261 :9B445CE1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 07:18:33.533 2025-05-24 07:18:33.538263 :9B445CE2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12972 pso_num=22 pso_serial#=61 2025-05-24 07:18:33.538400 :9B445CE3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12972 osp_idx=0 osp_ver=228286 osp_pg=0 (spawn #213796) 2025-05-24 07:18:33.538400 :9B445CE4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 07:18:33.538401 :9B445CE5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=14 time=1046885797 2025-05-24 07:18:33.538401 :9B445CE6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 07:18:33.538402 :9B445CE7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 07:18:33.538402 :9B445CE8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 07:18:33.538403 :9B445CE9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 07:18:33.538403 :9B445CEA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 07:18:33.538892 :9B445CEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 07:18:33.538936 :9B445CF1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 07:28:34.508309 :9B452332:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 07:28:34.950919 :9B4523AF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 07:28:34.946 2025-05-24 07:28:34.950921 :9B4523B0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11620 pso_num=22 pso_serial#=62 2025-05-24 07:28:34.951061 :9B4523B1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11620 osp_idx=0 osp_ver=228287 osp_pg=0 (spawn #213819) 2025-05-24 07:28:34.951062 :9B4523B2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 07:28:34.951062 :9B4523B3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=15 time=1047487204 2025-05-24 07:28:34.951062 :9B4523B4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 07:28:34.951063 :9B4523B5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 07:28:34.951063 :9B4523B6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 07:28:34.951063 :9B4523B7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 07:28:34.951064 :9B4523B8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 07:28:34.951544 :9B4523B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 07:28:34.951592 :9B4523C3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 07:38:35.942427 :9B45EA08:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 07:38:36.200665 :9B45EA18:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 07:38:36.192 2025-05-24 07:38:36.200668 :9B45EA19:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13016 pso_num=22 pso_serial#=63 2025-05-24 07:38:36.200803 :9B45EA1A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13016 (legacy spawn) 2025-05-24 07:38:36.207378 :9B45EA1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 07:38:36.207554 :9B45EA21:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-24 07:38:36.208393 :9B45EA25:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 07:38:36.322664 :9B45EA7F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 07:38:36.317 2025-05-24 07:38:36.322665 :9B45EA80:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14224 pso_num=22 pso_serial#=64 2025-05-24 07:38:36.322804 :9B45EA81:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14224 osp_idx=0 osp_ver=228289 osp_pg=0 (spawn #213841) 2025-05-24 07:38:36.322806 :9B45EA82:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 07:38:36.322807 :9B45EA83:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=16 time=1048088579 2025-05-24 07:38:36.322807 :9B45EA84:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 07:38:36.322808 :9B45EA85:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 07:38:36.322809 :9B45EA86:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 07:38:36.322809 :9B45EA87:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 07:38:36.322810 :9B45EA88:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 07:38:36.323368 :9B45EA89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 07:38:36.323434 :9B45EA93:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 07:48:37.353587 :9B46B0E1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 07:48:37.748962 :9B46B153:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 07:48:37.744 2025-05-24 07:48:37.748964 :9B46B154:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12132 pso_num=22 pso_serial#=65 2025-05-24 07:48:37.749106 :9B46B155:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12132 osp_idx=0 osp_ver=228290 osp_pg=0 (spawn #213864) 2025-05-24 07:48:37.749107 :9B46B156:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 07:48:37.749107 :9B46B157:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=17 time=1048690000 2025-05-24 07:48:37.749108 :9B46B158:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 07:48:37.749108 :9B46B159:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 07:48:37.749109 :9B46B15A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 07:48:37.749109 :9B46B15B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 07:48:37.749109 :9B46B15C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 07:48:37.749611 :9B46B15D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 07:48:37.749657 :9B46B165:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 07:53:34.076952 :9B47132F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 07:58:39.076613 :9B477796:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-24 08:08:40.019682 :9B484814:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 08:08:40.431109 :9B48483B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 08:08:40.423 2025-05-24 08:08:40.431111 :9B48483C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14056 pso_num=22 pso_serial#=66 2025-05-24 08:08:40.431251 :9B48483D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14056 (legacy spawn) 2025-05-24 08:08:40.437678 :9B48483E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 08:08:40.437849 :9B484843:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-24 08:08:40.438647 :9B484848:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 08:08:40.521279 :9B48489B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 08:08:40.517 2025-05-24 08:08:40.521281 :9B48489C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4728 pso_num=22 pso_serial#=67 2025-05-24 08:08:40.521414 :9B48489D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4728 osp_idx=0 osp_ver=228292 osp_pg=0 (spawn #213911) 2025-05-24 08:08:40.521415 :9B48489E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:08:40.521415 :9B48489F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=18 time=1049892782 2025-05-24 08:08:40.521416 :9B4848A0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:08:40.521416 :9B4848A1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:08:40.521416 :9B4848A2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:08:40.521417 :9B4848A3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:08:40.521417 :9B4848A4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:08:40.521903 :9B4848A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 08:08:40.521958 :9B4848AB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 4728, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de