Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250524140108\orcl_w000_10976_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250524140108\orcl_w000_10976_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:37307M/63366M, Ph+PgF:44372M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 10976, image: ORACLE.EXE (W000) *** 2025-05-24 14:01:08.193 *** SESSION ID:(177.7537) 2025-05-24 14:01:08.193 *** 2025-05-24 14:01:08.193 Process diagnostic dump for ORACLE.EXE (W000), OS id=10976, pid: 22, proc_ser: 100, sid: 177, sess_ser: 7537 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=1.003997 sec, exc=1.003997 sec, total=1.003997 sec wait times: max=5.000000 sec, heur=36.050710 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=21 seq_num=22 snap_id=1 wait times: snap=5.004627 sec, exc=5.004627 sec, total=5.004627 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=5.014733 sec, exc=5.014733 sec, total=5.014733 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=19 seq_num=20 snap_id=1 wait times: snap=5.004608 sec, exc=5.004608 sec, total=5.004608 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=18 seq_num=19 snap_id=1 wait times: snap=5.009732 sec, exc=5.009732 sec, total=5.009732 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=17 seq_num=18 snap_id=1 wait times: snap=5.002128 sec, exc=5.002128 sec, total=5.002128 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=5.001588 sec, exc=5.001588 sec, total=5.001588 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=5.009120 sec, exc=5.009120 sec, total=5.009120 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.001851 sec of elapsed time 7: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x1, filetype=0x2 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.000154 sec, exc=0.000154 sec, total=0.000154 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000164 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=4.692324 sec, exc=4.692324 sec, total=4.692324 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=5.004619 sec, exc=5.004619 sec, total=5.004619 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 177 serial 7537 --------------------------------------------------- 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 --------------------------------------------------- [101 samples, 13:59:27 - 14:01:08] idle wait at each sample [session created at: 13:59:27] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-24 14:01:08.193 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 10976, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-24 11:39:08.722873 :9B5901BE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11632 osp_idx=0 osp_ver=228312 osp_pg=0 (spawn #214401) 2025-05-24 11:39:08.722874 :9B5901BF: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 11:39:08.722874 :9B5901C0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=38 time=1062520969 2025-05-24 11:39:08.722875 :9B5901C1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 11:39:08.722875 :9B5901C2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 11:39:08.722876 :9B5901C3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 11:39:08.722876 :9B5901C4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 11:39:08.722877 :9B5901C5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 11:39:08.723448 :9B5901C6: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 11:39:08.723518 :9B5901D0: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 11:49:09.695911 :9B59C50F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 11:49:10.091092 :9B59C568:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 11:49:10.086 2025-05-24 11:49:10.091094 :9B59C569:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8352 pso_num=22 pso_serial#=88 2025-05-24 11:49:10.091235 :9B59C56A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8352 osp_idx=0 osp_ver=228313 osp_pg=0 (spawn #214424) 2025-05-24 11:49:10.091235 :9B59C56B: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 11:49:10.091235 :9B59C56C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=39 time=1063122344 2025-05-24 11:49:10.091236 :9B59C56D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 11:49:10.091236 :9B59C56E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 11:49:10.091237 :9B59C56F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 11:49:10.091237 :9B59C570:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 11:49:10.091237 :9B59C571:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 11:49:10.091721 :9B59C572: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 11:49:10.091767 :9B59C57A: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 11:59:10.980211 :9B5A8873:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 11:59:11.469267 :9B5A88B9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 11:59:11.464 2025-05-24 11:59:11.469269 :9B5A88BA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12972 pso_num=22 pso_serial#=89 2025-05-24 11:59:11.469401 :9B5A88BB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12972 osp_idx=0 osp_ver=228314 osp_pg=0 (spawn #214447) 2025-05-24 11:59:11.469402 :9B5A88BC: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 11:59:11.469403 :9B5A88BD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=40 time=1063723719 2025-05-24 11:59:11.469403 :9B5A88BE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 11:59:11.469404 :9B5A88BF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 11:59:11.469404 :9B5A88C0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 11:59:11.469404 :9B5A88C1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 11:59:11.469404 :9B5A88C2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 11:59:11.469881 :9B5A88C3: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 11:59:11.469934 :9B5A88C9: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 12:00:20.382471 :9B5A9EC7: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 12:09:12.810415 :9B5B5404: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 12:19:13.802251 :9B5C175C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 12:19:14.150422 :9B5C17A4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 12:19:14.146 2025-05-24 12:19:14.150424 :9B5C17A5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9092 pso_num=22 pso_serial#=90 2025-05-24 12:19:14.150554 :9B5C17A6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9092 osp_idx=0 osp_ver=228315 osp_pg=0 (spawn #214494) 2025-05-24 12:19:14.150554 :9B5C17A7: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 12:19:14.150555 :9B5C17A8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=41 time=1064926407 2025-05-24 12:19:14.150555 :9B5C17A9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 12:19:14.150556 :9B5C17AA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 12:19:14.150556 :9B5C17AB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 12:19:14.150556 :9B5C17AC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 12:19:14.150557 :9B5C17AD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 12:19:14.151048 :9B5C17AE: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 12:19:14.151092 :9B5C17B6: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 12:29:15.095093 :9B5CDD4A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 12:29:15.443716 :9B5CDDBA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 12:29:15.438 2025-05-24 12:29:15.443718 :9B5CDDBB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10948 pso_num=22 pso_serial#=91 2025-05-24 12:29:15.443862 :9B5CDDBC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10948 osp_idx=0 osp_ver=228316 osp_pg=0 (spawn #214517) 2025-05-24 12:29:15.443862 :9B5CDDBD: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 12:29:15.443863 :9B5CDDBE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=42 time=1065527704 2025-05-24 12:29:15.443863 :9B5CDDBF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 12:29:15.443863 :9B5CDDC0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 12:29:15.443864 :9B5CDDC1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 12:29:15.443864 :9B5CDDC2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 12:29:15.443864 :9B5CDDC3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 12:29:15.444360 :9B5CDDC4: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 12:29:15.444412 :9B5CDDCD: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 12:39:16.447432 :9B5DA080:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 12:39:16.827453 :9B5DA0DD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 12:39:16.823 2025-05-24 12:39:16.827455 :9B5DA0DE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8780 pso_num=22 pso_serial#=92 2025-05-24 12:39:16.827589 :9B5DA0DF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8780 osp_idx=0 osp_ver=228317 osp_pg=0 (spawn #214539) 2025-05-24 12:39:16.827590 :9B5DA0E0: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 12:39:16.827590 :9B5DA0E1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=43 time=1066129079 2025-05-24 12:39:16.827590 :9B5DA0E2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 12:39:16.827591 :9B5DA0E3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 12:39:16.827591 :9B5DA0E4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 12:39:16.827592 :9B5DA0E5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 12:39:16.827592 :9B5DA0E6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 12:39:16.828076 :9B5DA0E7: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 12:39:16.828124 :9B5DA0EE: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 12:49:17.773231 :9B5E63A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 12:49:18.090571 :9B5E6411:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 12:49:18.086 2025-05-24 12:49:18.090573 :9B5E6412:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13792 pso_num=22 pso_serial#=93 2025-05-24 12:49:18.090705 :9B5E6413:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13792 osp_idx=0 osp_ver=228318 osp_pg=0 (spawn #214562) 2025-05-24 12:49:18.090706 :9B5E6414: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 12:49:18.090706 :9B5E6415:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=44 time=1066730344 2025-05-24 12:49:18.090707 :9B5E6416:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 12:49:18.090707 :9B5E6417:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 12:49:18.090708 :9B5E6418:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 12:49:18.090708 :9B5E6419:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 12:49:18.090708 :9B5E641A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 12:49:18.091210 :9B5E641B: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 12:49:18.091256 :9B5E6423: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 12:59:19.084448 :9B5F26C0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 12:59:19.323595 :9B5F2718:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 12:59:19.319 2025-05-24 12:59:19.323597 :9B5F2719:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10332 pso_num=22 pso_serial#=94 2025-05-24 12:59:19.323731 :9B5F271A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10332 osp_idx=0 osp_ver=228319 osp_pg=0 (spawn #214585) 2025-05-24 12:59:19.323732 :9B5F271B: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 12:59:19.323732 :9B5F271C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=45 time=1067331579 2025-05-24 12:59:19.323733 :9B5F271D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 12:59:19.323733 :9B5F271E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 12:59:19.323734 :9B5F271F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 12:59:19.323734 :9B5F2720:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 12:59:19.323734 :9B5F2721:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 12:59:19.324268 :9B5F2722: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 12:59:19.324314 :9B5F272A: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 13:09:20.334720 :9B5FF2F8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 13:09:20.589640 :9B5FF343:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 13:09:20.585 2025-05-24 13:09:20.589642 :9B5FF344:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11340 pso_num=22 pso_serial#=95 2025-05-24 13:09:20.589784 :9B5FF345:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11340 osp_idx=0 osp_ver=228320 osp_pg=0 (spawn #214610) 2025-05-24 13:09:20.589784 :9B5FF346: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 13:09:20.589785 :9B5FF347:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=46 time=1067932844 2025-05-24 13:09:20.589785 :9B5FF348:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 13:09:20.589786 :9B5FF349:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 13:09:20.589786 :9B5FF34A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 13:09:20.589786 :9B5FF34B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 13:09:20.589786 :9B5FF34C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 13:09:20.590272 :9B5FF34D: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 13:09:20.590319 :9B5FF354: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 13:09:50.641160 :9B5FFD3E: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 13:09:50.641180 :9B5FFD43: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 13:09:50.641182 :9B5FFD44:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=19, exc=19, tot=19 2025-05-24 13:10:35.716560 :9B600CAA: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 13:19:21.550234 :9B60B91F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 13:19:21.965188 :9B60B982:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 13:19:21.960 2025-05-24 13:19:21.965190 :9B60B983:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13600 pso_num=22 pso_serial#=96 2025-05-24 13:19:21.965330 :9B60B984:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13600 osp_idx=0 osp_ver=228321 osp_pg=0 (spawn #214633) 2025-05-24 13:19:21.965330 :9B60B985: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 13:19:21.965330 :9B60B986:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=47 time=1068534219 2025-05-24 13:19:21.965331 :9B60B987:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 13:19:21.965331 :9B60B988:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 13:19:21.965332 :9B60B989:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 13:19:21.965332 :9B60B98A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 13:19:21.965332 :9B60B98B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 13:19:21.965827 :9B60B98C: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 13:19:21.965873 :9B60B994: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 13:29:22.951454 :9B617D2C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 13:29:23.268802 :9B617D84:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 13:29:23.264 2025-05-24 13:29:23.268804 :9B617D85:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14012 pso_num=22 pso_serial#=97 2025-05-24 13:29:23.268950 :9B617D86:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14012 osp_idx=0 osp_ver=228322 osp_pg=0 (spawn #214654) 2025-05-24 13:29:23.268950 :9B617D87: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 13:29:23.268951 :9B617D88:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=48 time=1069135516 2025-05-24 13:29:23.268952 :9B617D89:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 13:29:23.268952 :9B617D8A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 13:29:23.268953 :9B617D8B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 13:29:23.268953 :9B617D8C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 13:29:23.268954 :9B617D8D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 13:29:23.269436 :9B617D8E: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 13:29:23.269479 :9B617D96: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 13:39:24.212577 :9B6240C8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 13:39:24.654682 :9B62412F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 13:39:24.650 2025-05-24 13:39:24.654684 :9B624130:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11212 pso_num=22 pso_serial#=98 2025-05-24 13:39:24.654822 :9B624131:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11212 osp_idx=0 osp_ver=228323 osp_pg=0 (spawn #214676) 2025-05-24 13:39:24.654823 :9B624132: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 13:39:24.654824 :9B624133:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=49 time=1069736907 2025-05-24 13:39:24.654824 :9B624134:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 13:39:24.654824 :9B624135:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 13:39:24.654825 :9B624136:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 13:39:24.654825 :9B624137:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 13:39:24.654826 :9B624138:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 13:39:24.655366 :9B624139: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 13:39:24.655419 :9B624143: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 13:49:25.598628 :9B63041E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 13:49:25.978307 :9B63046B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 13:49:25.973 2025-05-24 13:49:25.978309 :9B63046C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9992 pso_num=22 pso_serial#=99 2025-05-24 13:49:25.978444 :9B63046D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9992 osp_idx=0 osp_ver=228324 osp_pg=0 (spawn #214699) 2025-05-24 13:49:25.978445 :9B63046E: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 13:49:25.978445 :9B63046F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=50 time=1070338235 2025-05-24 13:49:25.978445 :9B630470:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 13:49:25.978446 :9B630471:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 13:49:25.978446 :9B630472:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 13:49:25.978446 :9B630473:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 13:49:25.978447 :9B630474:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 13:49:25.978927 :9B630475: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 13:49:25.978970 :9B63047C: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 13:59:26.935625 :9B63C828:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 13:59:27.362153 :9B63C878:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 13:59:27.357 2025-05-24 13:59:27.362156 :9B63C879:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10976 pso_num=22 pso_serial#=100 2025-05-24 13:59:27.362313 :9B63C87A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10976 osp_idx=0 osp_ver=228325 osp_pg=0 (spawn #214722) 2025-05-24 13:59:27.362314 :9B63C87B: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 13:59:27.362314 :9B63C87C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=51 time=1070939610 2025-05-24 13:59:27.362315 :9B63C87D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 13:59:27.362315 :9B63C87E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 13:59:27.362315 :9B63C87F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 13:59:27.362316 :9B63C880:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 13:59:27.362316 :9B63C881:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 13:59:27.362817 :9B63C882: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 13:59:27.362864 :9B63C88A: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 14:00:32.143774 :9B63DE73: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 10976, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de