Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250830100316\orcl_w000_16984_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250830100316\orcl_w000_16984_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:35125M/63366M, Ph+PgF:40148M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16984, image: ORACLE.EXE (W000) *** 2025-08-30 10:03:16.312 *** SESSION ID:(201.62675) 2025-08-30 10:03:16.312 *** 2025-08-30 10:03:16.312 Process diagnostic dump for ORACLE.EXE (W000), OS id=16984, pid: 25, proc_ser: 139, sid: 201, sess_ser: 62675 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=90 seq_num=91 snap_id=1 wait times: snap=1.284366 sec, exc=1.284366 sec, total=1.284366 sec wait times: max=5.000000 sec, heur=7 min 27 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.000041 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=89 seq_num=90 snap_id=1 wait times: snap=5.015209 sec, exc=5.015209 sec, total=5.015209 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000075 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=88 seq_num=89 snap_id=1 wait times: snap=5.009682 sec, exc=5.009682 sec, total=5.009682 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=87 seq_num=88 snap_id=1 wait times: snap=5.011549 sec, exc=5.011549 sec, total=5.011549 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=86 seq_num=87 snap_id=1 wait times: snap=5.014710 sec, exc=5.014710 sec, total=5.014710 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000054 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=85 seq_num=86 snap_id=1 wait times: snap=5.004647 sec, exc=5.004647 sec, total=5.004647 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.014726 sec, exc=5.014726 sec, total=5.014726 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=83 seq_num=84 snap_id=1 wait times: snap=5.015257 sec, exc=5.015257 sec, total=5.015257 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=82 seq_num=83 snap_id=1 wait times: snap=5.003331 sec, exc=5.003331 sec, total=5.003331 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=81 seq_num=82 snap_id=1 wait times: snap=5.001377 sec, exc=5.001377 sec, total=5.001377 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=80 seq_num=81 snap_id=1 wait times: snap=5.003697 sec, exc=5.003697 sec, total=5.003697 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time Sampled Session History of session 201 serial 62675 --------------------------------------------------- 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, 10:01:16 - 10:03:16] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-30 10:03:16.312 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16984, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-30 07:35:32.129879 :C68DCEF7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12108 pso_num=25 pso_serial#=125 2025-08-30 07:35:32.130023 :C68DCEF8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12108 osp_idx=23 osp_ver=266457 osp_pg=0 (spawn #540482) 2025-08-30 07:35:32.130024 :C68DCEF9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 07:35:32.130024 :C68DCEFA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=34 time=924983440 2025-08-30 07:35:32.130025 :C68DCEFB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 07:35:32.130025 :C68DCEFC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 07:35:32.130026 :C68DCEFD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 07:35:32.130026 :C68DCEFE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 07:35:32.130026 :C68DCEFF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 07:35:32.130587 :C68DCF00: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-08-30 07:35:32.130638 :C68DCF06: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-08-30 07:45:32.896345 :C68E94FA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 07:45:33.276489 :C68E953E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 07:45:33.271 2025-08-30 07:45:33.276491 :C68E953F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20852 pso_num=25 pso_serial#=126 2025-08-30 07:45:33.276635 :C68E9540:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20852 osp_idx=23 osp_ver=266458 osp_pg=0 (spawn #540505) 2025-08-30 07:45:33.276636 :C68E9541:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 07:45:33.276636 :C68E9542:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=35 time=925584580 2025-08-30 07:45:33.276637 :C68E9543:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 07:45:33.276637 :C68E9544:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 07:45:33.276637 :C68E9545:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 07:45:33.276637 :C68E9546:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 07:45:33.276638 :C68E9547:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 07:45:33.277168 :C68E9548: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-08-30 07:45:33.277216 :C68E954E: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-08-30 07:55:34.026571 :C68F5C37:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 07:55:34.406187 :C68F5C6B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 07:55:34.401 2025-08-30 07:55:34.406189 :C68F5C6C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22760 pso_num=25 pso_serial#=127 2025-08-30 07:55:34.406326 :C68F5C6D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22760 osp_idx=23 osp_ver=266459 osp_pg=0 (spawn #540527) 2025-08-30 07:55:34.406327 :C68F5C6E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 07:55:34.406327 :C68F5C6F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=36 time=926185705 2025-08-30 07:55:34.406327 :C68F5C70:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 07:55:34.406327 :C68F5C71:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 07:55:34.406328 :C68F5C72:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 07:55:34.406328 :C68F5C73:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 07:55:34.406328 :C68F5C74:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 07:55:34.406819 :C68F5C75: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-08-30 07:55:34.406869 :C68F5C7D: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-08-30 08:05:35.200086 :C6902666:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 08:05:35.427558 :C6902675:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 08:05:35.419 2025-08-30 08:05:35.427560 :C6902676:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20692 pso_num=25 pso_serial#=128 2025-08-30 08:05:35.427695 :C6902677:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20692 (legacy spawn) 2025-08-30 08:05:35.434714 :C6902678: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-08-30 08:05:35.434911 :C690267D: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-08-30 08:05:35.435852 :C6902682:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 08:05:35.501874 :C69026BA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 08:05:35.497 2025-08-30 08:05:35.501876 :C69026BB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22648 pso_num=25 pso_serial#=129 2025-08-30 08:05:35.502026 :C69026BC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22648 osp_idx=23 osp_ver=266461 osp_pg=0 (spawn #540553) 2025-08-30 08:05:35.502026 :C69026BD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 08:05:35.502027 :C69026BE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=37 time=926786799 2025-08-30 08:05:35.502027 :C69026BF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 08:05:35.502028 :C69026C0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 08:05:35.502028 :C69026C1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 08:05:35.502028 :C69026C2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 08:05:35.502029 :C69026C3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 08:05:35.502567 :C69026C4: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-08-30 08:05:35.502621 :C69026CC: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-08-30 08:15:36.394717 :C690F192:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 08:15:36.711970 :C690F1C7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 08:15:36.707 2025-08-30 08:15:36.711972 :C690F1C8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22464 pso_num=25 pso_serial#=130 2025-08-30 08:15:36.712109 :C690F1C9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22464 osp_idx=23 osp_ver=266462 osp_pg=0 (spawn #540576) 2025-08-30 08:15:36.712110 :C690F1CA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 08:15:36.712110 :C690F1CB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=38 time=927388018 2025-08-30 08:15:36.712111 :C690F1CC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 08:15:36.712111 :C690F1CD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 08:15:36.712112 :C690F1CE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 08:15:36.712112 :C690F1CF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 08:15:36.712112 :C690F1D0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 08:15:36.712601 :C690F1D1: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-08-30 08:15:36.712654 :C690F1DB: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-08-30 08:25:37.622649 :C6922629:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 08:25:37.908619 :C692266B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 08:25:37.904 2025-08-30 08:25:37.908621 :C692266C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22912 pso_num=25 pso_serial#=131 2025-08-30 08:25:37.908761 :C692266D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22912 osp_idx=23 osp_ver=266463 osp_pg=0 (spawn #540603) 2025-08-30 08:25:37.908762 :C692266E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 08:25:37.908762 :C692266F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=39 time=927989205 2025-08-30 08:25:37.908763 :C6922670:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 08:25:37.908763 :C6922671:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 08:25:37.908763 :C6922672:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 08:25:37.908764 :C6922673:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 08:25:37.908764 :C6922674:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 08:25:37.909251 :C6922675: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-08-30 08:25:37.909294 :C692267C: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-08-30 08:35:38.788171 :C692EDB7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 08:35:39.168030 :C692EDEE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 08:35:39.162 2025-08-30 08:35:39.168033 :C692EDEF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19728 pso_num=25 pso_serial#=132 2025-08-30 08:35:39.168173 :C692EDF0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19728 osp_idx=23 osp_ver=266464 osp_pg=0 (spawn #540626) 2025-08-30 08:35:39.168173 :C692EDF1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 08:35:39.168174 :C692EDF2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=40 time=928590471 2025-08-30 08:35:39.168175 :C692EDF3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 08:35:39.168175 :C692EDF4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 08:35:39.168176 :C692EDF5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 08:35:39.168176 :C692EDF6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 08:35:39.168176 :C692EDF7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 08:35:39.168686 :C692EDF8: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-08-30 08:35:39.168731 :C692EDFF: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-08-30 08:45:39.954115 :C693B740:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 08:45:40.411873 :C693B782:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 08:45:40.407 2025-08-30 08:45:40.411875 :C693B783:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23220 pso_num=25 pso_serial#=133 2025-08-30 08:45:40.412015 :C693B784:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23220 osp_idx=23 osp_ver=266465 osp_pg=0 (spawn #540649) 2025-08-30 08:45:40.412015 :C693B785:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 08:45:40.412016 :C693B786:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=41 time=929191721 2025-08-30 08:45:40.412016 :C693B787:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 08:45:40.412017 :C693B788:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 08:45:40.412017 :C693B789:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 08:45:40.412017 :C693B78A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 08:45:40.412018 :C693B78B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 08:45:40.412520 :C693B78C: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-08-30 08:45:40.412570 :C693B792: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-08-30 08:55:41.243480 :C6947F27:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 08:55:41.638934 :C6947F60:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 08:55:41.634 2025-08-30 08:55:41.638936 :C6947F61:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19604 pso_num=25 pso_serial#=134 2025-08-30 08:55:41.639084 :C6947F62:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19604 osp_idx=23 osp_ver=266466 osp_pg=0 (spawn #540673) 2025-08-30 08:55:41.639085 :C6947F63:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 08:55:41.639085 :C6947F64:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=42 time=929792940 2025-08-30 08:55:41.639086 :C6947F65:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 08:55:41.639086 :C6947F66:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 08:55:41.639087 :C6947F67:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 08:55:41.639087 :C6947F68:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 08:55:41.639087 :C6947F69:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 08:55:41.639593 :C6947F6A: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-08-30 08:55:41.639643 :C6947F71: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-08-30 08:57:14.602988 :C6949D85: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-08-30 09:05:42.858889 :C6955290: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-08-30 09:15:43.726311 :C696213C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 09:15:44.074717 :C6962181:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 09:15:44.069 2025-08-30 09:15:44.074719 :C6962182:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21068 pso_num=25 pso_serial#=135 2025-08-30 09:15:44.074863 :C6962183:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21068 osp_idx=23 osp_ver=266467 osp_pg=0 (spawn #540721) 2025-08-30 09:15:44.074864 :C6962184:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 09:15:44.074865 :C6962185:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=43 time=930995377 2025-08-30 09:15:44.074865 :C6962186:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 09:15:44.074866 :C6962187:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 09:15:44.074866 :C6962188:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 09:15:44.074867 :C6962189:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 09:15:44.074868 :C696218A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 09:15:44.075362 :C696218B: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-08-30 09:15:44.075406 :C6962191: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-08-30 09:25:44.856947 :C696E765:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 09:25:45.330525 :C696E79F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 09:25:45.325 2025-08-30 09:25:45.330528 :C696E7A0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21876 pso_num=25 pso_serial#=136 2025-08-30 09:25:45.330674 :C696E7A1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21876 osp_idx=23 osp_ver=266468 osp_pg=0 (spawn #540743) 2025-08-30 09:25:45.330675 :C696E7A2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 09:25:45.330675 :C696E7A3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=44 time=931596627 2025-08-30 09:25:45.330675 :C696E7A4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 09:25:45.330676 :C696E7A5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 09:25:45.330676 :C696E7A6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 09:25:45.330676 :C696E7A7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 09:25:45.330677 :C696E7A8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 09:25:45.331199 :C696E7A9: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-08-30 09:25:45.331245 :C696E7B0: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-08-30 09:35:46.135716 :C697AF5D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 09:35:46.627318 :C697AFAF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 09:35:46.622 2025-08-30 09:35:46.627320 :C697AFB0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20104 pso_num=25 pso_serial#=137 2025-08-30 09:35:46.627461 :C697AFB1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20104 osp_idx=23 osp_ver=266469 osp_pg=0 (spawn #540766) 2025-08-30 09:35:46.627462 :C697AFB2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 09:35:46.627462 :C697AFB3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=45 time=932197924 2025-08-30 09:35:46.627463 :C697AFB4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 09:35:46.627463 :C697AFB5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 09:35:46.627464 :C697AFB6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 09:35:46.627464 :C697AFB7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 09:35:46.627464 :C697AFB8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 09:35:46.628021 :C697AFB9: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-08-30 09:35:46.628069 :C697AFC1: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-08-30 09:45:47.540140 :C6987638:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 09:45:47.976745 :C6987671:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 09:45:47.972 2025-08-30 09:45:47.976747 :C6987672:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21176 pso_num=25 pso_serial#=138 2025-08-30 09:45:47.976879 :C6987673:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21176 osp_idx=23 osp_ver=266470 osp_pg=0 (spawn #540789) 2025-08-30 09:45:47.976880 :C6987674:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 09:45:47.976880 :C6987675:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=46 time=932799283 2025-08-30 09:45:47.976881 :C6987676:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 09:45:47.976881 :C6987677:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 09:45:47.976881 :C6987678:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 09:45:47.976882 :C6987679:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 09:45:47.976882 :C698767A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 09:45:47.977377 :C698767B: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-08-30 09:45:47.977421 :C6987683: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-08-30 09:55:48.766245 :C6993D43:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 09:55:49.287773 :C6993D79:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 09:55:49.283 2025-08-30 09:55:49.287774 :C6993D7A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16984 pso_num=25 pso_serial#=139 2025-08-30 09:55:49.287917 :C6993D7B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16984 osp_idx=23 osp_ver=266471 osp_pg=0 (spawn #540811) 2025-08-30 09:55:49.287918 :C6993D7C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 09:55:49.287919 :C6993D7D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=47 time=933400596 2025-08-30 09:55:49.287920 :C6993D7E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 09:55:49.287921 :C6993D7F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 09:55:49.287921 :C6993D80:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 09:55:49.287922 :C6993D81:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 09:55:49.287922 :C6993D82:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 09:55:49.288408 :C6993D83: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-08-30 09:55:49.288457 :C6993D8B: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: 16984, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de