Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250710220007\orcl_w000_16488_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250710220007\orcl_w000_16488_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:36438M/63366M, Ph+PgF:42488M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16488, image: ORACLE.EXE (W000) *** 2025-07-10 22:00:07.956 *** SESSION ID:(201.26521) 2025-07-10 22:00:07.956 *** 2025-07-10 22:00:07.956 Process diagnostic dump for ORACLE.EXE (W000), OS id=16488, pid: 25, proc_ser: 81, sid: 201, sess_ser: 26521 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=0.565959 sec, exc=0.565959 sec, total=0.565959 sec wait times: max=5.000000 sec, heur=7 min 56 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.000029 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=95 seq_num=96 snap_id=1 wait times: snap=5.008358 sec, exc=5.008358 sec, total=5.008358 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=5.000152 sec, exc=5.000152 sec, total=5.000152 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=93 seq_num=94 snap_id=1 wait times: snap=5.000430 sec, exc=5.000430 sec, total=5.000430 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=92 seq_num=93 snap_id=1 wait times: snap=5.014785 sec, exc=5.014785 sec, total=5.014785 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=91 seq_num=92 snap_id=1 wait times: snap=5.004078 sec, exc=5.004078 sec, total=5.004078 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=90 seq_num=91 snap_id=1 wait times: snap=5.004651 sec, exc=5.004651 sec, total=5.004651 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: 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.014727 sec, exc=5.014727 sec, total=5.014727 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 7: 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.004615 sec, exc=5.004615 sec, total=5.004615 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 8: 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.001730 sec, exc=5.001730 sec, total=5.001730 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=86 seq_num=87 snap_id=1 wait times: snap=5.001246 sec, exc=5.001246 sec, total=5.001246 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 26521 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 21:58:07 - 22:00:07] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-10 22:00:07.956 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16488, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-10 19:41:54.465966 :B03B0F55:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=5020 pso_num=25 pso_serial#=67 2025-07-10 19:41:54.466104 :B03B0F56:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=5020 osp_idx=23 osp_ver=189899 osp_pg=0 (spawn #372165) 2025-07-10 19:41:54.466105 :B03B0F57:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 19:41:54.466106 :B03B0F58:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=228 time=857290361 2025-07-10 19:41:54.466106 :B03B0F59:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 19:41:54.466106 :B03B0F5A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 19:41:54.466106 :B03B0F5B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 19:41:54.466107 :B03B0F5C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 19:41:54.466107 :B03B0F5D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 19:41:54.466612 :B03B0F5E: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-07-10 19:41:54.466658 :B03B0F66: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-07-10 19:51:55.435685 :B03BD399:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 19:51:55.847058 :B03BD3F2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 19:51:55.842 2025-07-10 19:51:55.847060 :B03BD3F3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14888 pso_num=25 pso_serial#=68 2025-07-10 19:51:55.847202 :B03BD3F4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14888 osp_idx=23 osp_ver=189900 osp_pg=0 (spawn #372188) 2025-07-10 19:51:55.847203 :B03BD3F5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 19:51:55.847203 :B03BD3F6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=229 time=857891736 2025-07-10 19:51:55.847203 :B03BD3F7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 19:51:55.847204 :B03BD3F8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 19:51:55.847204 :B03BD3F9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 19:51:55.847205 :B03BD3FA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 19:51:55.847205 :B03BD3FB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 19:51:55.847693 :B03BD3FC: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-07-10 19:51:55.847739 :B03BD404: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-07-10 20:01:56.719607 :B03C99C6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 20:01:57.146131 :B03C9A1C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 20:01:57.141 2025-07-10 20:01:57.146133 :B03C9A1D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16368 pso_num=25 pso_serial#=69 2025-07-10 20:01:57.146273 :B03C9A1E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16368 osp_idx=23 osp_ver=189901 osp_pg=0 (spawn #372212) 2025-07-10 20:01:57.146274 :B03C9A1F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 20:01:57.146274 :B03C9A20:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=230 time=858493033 2025-07-10 20:01:57.146275 :B03C9A21:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 20:01:57.146275 :B03C9A22:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 20:01:57.146275 :B03C9A23:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 20:01:57.146275 :B03C9A24:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 20:01:57.146276 :B03C9A25:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 20:01:57.146772 :B03C9A26: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-07-10 20:01:57.146818 :B03C9A2E: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-07-10 20:11:58.098823 :B03D65C2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 20:11:58.572331 :B03D6603:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 20:11:58.568 2025-07-10 20:11:58.572333 :B03D6604:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15704 pso_num=25 pso_serial#=70 2025-07-10 20:11:58.572474 :B03D6605:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15704 osp_idx=23 osp_ver=189902 osp_pg=0 (spawn #372236) 2025-07-10 20:11:58.572475 :B03D6606:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 20:11:58.572476 :B03D6607:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=231 time=859094470 2025-07-10 20:11:58.572476 :B03D6608:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 20:11:58.572476 :B03D6609:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 20:11:58.572477 :B03D660A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 20:11:58.572477 :B03D660B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 20:11:58.572477 :B03D660C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 20:11:58.572971 :B03D660D: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-07-10 20:11:58.573015 :B03D6615: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-07-10 20:21:59.530217 :B03E2D11:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 20:21:59.863144 :B03E2D4C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 20:21:59.858 2025-07-10 20:21:59.863146 :B03E2D4D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7280 pso_num=25 pso_serial#=71 2025-07-10 20:21:59.863294 :B03E2D4E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7280 osp_idx=23 osp_ver=189903 osp_pg=0 (spawn #372259) 2025-07-10 20:21:59.863295 :B03E2D4F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 20:21:59.863295 :B03E2D50:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=232 time=859695751 2025-07-10 20:21:59.863296 :B03E2D51:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 20:21:59.863296 :B03E2D52:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 20:21:59.863296 :B03E2D53:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 20:21:59.863296 :B03E2D54:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 20:21:59.863297 :B03E2D55:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 20:21:59.863785 :B03E2D56: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-07-10 20:21:59.863830 :B03E2D5E: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-07-10 20:32:00.725379 :B03EF24C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 20:32:01.318360 :B03EF284:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 20:32:01.313 2025-07-10 20:32:01.318362 :B03EF285:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8568 pso_num=25 pso_serial#=72 2025-07-10 20:32:01.318497 :B03EF286:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8568 osp_idx=23 osp_ver=189904 osp_pg=0 (spawn #372281) 2025-07-10 20:32:01.318498 :B03EF287:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 20:32:01.318499 :B03EF288:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=860297204 2025-07-10 20:32:01.318499 :B03EF289:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 20:32:01.318499 :B03EF28A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 20:32:01.318500 :B03EF28B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 20:32:01.318500 :B03EF28C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 20:32:01.318500 :B03EF28D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 20:32:01.318994 :B03EF28E: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-07-10 20:32:01.319039 :B03EF297: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-07-10 20:42:02.173485 :B03FB716:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 20:42:02.584713 :B03FB74C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 20:42:02.580 2025-07-10 20:42:02.584716 :B03FB74D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17636 pso_num=25 pso_serial#=73 2025-07-10 20:42:02.584858 :B03FB74E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17636 osp_idx=23 osp_ver=189905 osp_pg=0 (spawn #372304) 2025-07-10 20:42:02.584858 :B03FB74F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 20:42:02.584859 :B03FB750:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=860898486 2025-07-10 20:42:02.584859 :B03FB751:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 20:42:02.584860 :B03FB752:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 20:42:02.584860 :B03FB753:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 20:42:02.584860 :B03FB754:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 20:42:02.584861 :B03FB755:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 20:42:02.585407 :B03FB756: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-07-10 20:42:02.585452 :B03FB75D: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-07-10 20:52:03.526771 :B0407B94:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 20:52:03.906459 :B0407BCC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 20:52:03.902 2025-07-10 20:52:03.906462 :B0407BCD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8692 pso_num=25 pso_serial#=74 2025-07-10 20:52:03.906600 :B0407BCE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8692 osp_idx=23 osp_ver=189906 osp_pg=0 (spawn #372327) 2025-07-10 20:52:03.906601 :B0407BCF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 20:52:03.906601 :B0407BD0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=235 time=861499798 2025-07-10 20:52:03.906601 :B0407BD1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 20:52:03.906602 :B0407BD2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 20:52:03.906602 :B0407BD3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 20:52:03.906602 :B0407BD4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 20:52:03.906603 :B0407BD5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 20:52:03.907099 :B0407BD6: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-07-10 20:52:03.907150 :B0407BDD: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-07-10 20:52:33.968245 :B0408563:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-07-10 20:52:33.968250 :B0408568:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-07-10 20:52:33.968251 :B0408569:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=6, exc=6, tot=6 2025-07-10 20:52:33.968261 :B040856A: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-07-10 21:02:04.801216 :B04143DC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 21:02:05.087306 :B0414412:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 21:02:05.083 2025-07-10 21:02:05.087309 :B0414413:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18176 pso_num=25 pso_serial#=75 2025-07-10 21:02:05.087451 :B0414414:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18176 osp_idx=23 osp_ver=189907 osp_pg=0 (spawn #372351) 2025-07-10 21:02:05.087452 :B0414415:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 21:02:05.087452 :B0414416:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=236 time=862100986 2025-07-10 21:02:05.087452 :B0414417:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 21:02:05.087453 :B0414418:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 21:02:05.087453 :B0414419:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 21:02:05.087453 :B041441A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 21:02:05.087453 :B041441B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 21:02:05.087983 :B041441C: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-07-10 21:02:05.088026 :B0414424: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-07-10 21:05:09.313199 :B04181B3: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-07-10 21:12:06.403807 :B0420FAE: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-07-10 21:22:07.356791 :B042D6D7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 21:22:07.673857 :B042D71E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 21:22:07.669 2025-07-10 21:22:07.673859 :B042D71F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15416 pso_num=25 pso_serial#=76 2025-07-10 21:22:07.673999 :B042D720:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15416 osp_idx=23 osp_ver=189908 osp_pg=0 (spawn #372397) 2025-07-10 21:22:07.673999 :B042D721:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 21:22:07.674000 :B042D722:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=237 time=863303564 2025-07-10 21:22:07.674000 :B042D723:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 21:22:07.674001 :B042D724:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 21:22:07.674001 :B042D725:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 21:22:07.674001 :B042D726:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 21:22:07.674002 :B042D727:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 21:22:07.674512 :B042D728: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-07-10 21:22:07.674557 :B042D731: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-07-10 21:32:08.567934 :B0439BB9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 21:32:08.895358 :B0439BF0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 21:32:08.890 2025-07-10 21:32:08.895360 :B0439BF1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15076 pso_num=25 pso_serial#=77 2025-07-10 21:32:08.895499 :B0439BF2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15076 osp_idx=23 osp_ver=189909 osp_pg=0 (spawn #372419) 2025-07-10 21:32:08.895500 :B0439BF3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 21:32:08.895500 :B0439BF4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=238 time=863904783 2025-07-10 21:32:08.895501 :B0439BF5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 21:32:08.895501 :B0439BF6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 21:32:08.895501 :B0439BF7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 21:32:08.895502 :B0439BF8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 21:32:08.895502 :B0439BF9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 21:32:08.896008 :B0439BFA: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-07-10 21:32:08.896054 :B0439C03: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-07-10 21:42:09.902880 :B04460F9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 21:42:09.993903 :B044610F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 21:42:09.981 2025-07-10 21:42:09.993905 :B0446110:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10612 pso_num=25 pso_serial#=78 2025-07-10 21:42:09.994039 :B0446111:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10612 (legacy spawn) 2025-07-10 21:42:10.000599 :B0446112: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-07-10 21:42:10.000776 :B044611B: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-07-10 21:42:10.001601 :B044611C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 21:42:10.010429 :B044611F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 21:42:09.996 2025-07-10 21:42:10.010431 :B0446120:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9936 pso_num=25 pso_serial#=79 2025-07-10 21:42:10.010566 :B0446121:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9936 (legacy spawn) 2025-07-10 21:42:10.017046 :B0446122: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-07-10 21:42:10.017210 :B0446129: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-07-10 21:42:10.018024 :B044612C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 21:42:10.282619 :B044616C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 21:42:10.277 2025-07-10 21:42:10.282621 :B044616D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1168 pso_num=25 pso_serial#=80 2025-07-10 21:42:10.282758 :B044616E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1168 osp_idx=23 osp_ver=189912 osp_pg=0 (spawn #372442) 2025-07-10 21:42:10.282759 :B044616F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 21:42:10.282759 :B0446170:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=239 time=864506173 2025-07-10 21:42:10.282761 :B0446171:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 21:42:10.282761 :B0446172:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 21:42:10.282762 :B0446173:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 21:42:10.282762 :B0446174:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 21:42:10.282762 :B0446175:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 21:42:10.283333 :B0446176: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-07-10 21:42:10.283376 :B044617F: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-07-10 21:52:11.243348 :B04526CF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-10 21:52:11.544957 :B0452716:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-10 21:52:11.540 2025-07-10 21:52:11.544959 :B0452717:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16488 pso_num=25 pso_serial#=81 2025-07-10 21:52:11.545095 :B0452718:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16488 osp_idx=23 osp_ver=189913 osp_pg=0 (spawn #372465) 2025-07-10 21:52:11.545095 :B0452719:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 21:52:11.545096 :B045271A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=240 time=865107439 2025-07-10 21:52:11.545096 :B045271B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 21:52:11.545096 :B045271C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 21:52:11.545097 :B045271D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 21:52:11.545097 :B045271E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 21:52:11.545097 :B045271F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 21:52:11.545595 :B0452720: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-07-10 21:52:11.545640 :B0452728: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: 16488, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de