Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250526220008\orcl_w000_15040_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250526220008\orcl_w000_15040_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:37279M/63366M, Ph+PgF:44234M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15040, image: ORACLE.EXE (W000) *** 2025-05-26 22:00:08.907 *** SESSION ID:(177.25569) 2025-05-26 22:00:08.907 *** 2025-05-26 22:00:08.907 Process diagnostic dump for ORACLE.EXE (W000), OS id=15040, pid: 22, proc_ser: 61, sid: 177, sess_ser: 25569 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=39 seq_num=40 snap_id=1 wait times: snap=3.335136 sec, exc=3.335136 sec, total=3.335136 sec wait times: max=5.000000 sec, heur=3 min 13 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.000022 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=38 seq_num=39 snap_id=1 wait times: snap=5.004648 sec, exc=5.004648 sec, total=5.004648 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=37 seq_num=38 snap_id=1 wait times: snap=5.014747 sec, exc=5.014747 sec, total=5.014747 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=5.015243 sec, exc=5.015243 sec, total=5.015243 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=5.003210 sec, exc=5.003210 sec, total=5.003210 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=5.013574 sec, exc=5.013574 sec, total=5.013574 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=5.005513 sec, exc=5.005513 sec, total=5.005513 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=32 seq_num=33 snap_id=1 wait times: snap=5.010140 sec, exc=5.010140 sec, total=5.010140 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=31 seq_num=32 snap_id=1 wait times: snap=5.014717 sec, exc=5.014717 sec, total=5.014717 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.014735 sec, exc=5.014735 sec, total=5.014735 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=5.007218 sec, exc=5.007218 sec, total=5.007218 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 177 serial 25569 --------------------------------------------------- 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:08 - 22:00:08] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-26 22:00:08.907 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15040, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-26 19:36:36.135370 :9C5F3F9C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 19:36:36.135370 :9C5F3F9D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 19:36:36.135370 :9C5F3F9E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 19:36:36.135371 :9C5F3F9F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 19:36:36.135371 :9C5F3FA0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 19:36:36.135867 :9C5F3FA1: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-26 19:36:36.135920 :9C5F3FA7: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-26 19:46:37.111857 :9C600359:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 19:46:37.664135 :9C6003B8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 19:46:37.659 2025-05-26 19:46:37.664136 :9C6003B9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3624 pso_num=22 pso_serial#=48 2025-05-26 19:46:37.664271 :9C6003BA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=3624 osp_idx=0 osp_ver=235923 osp_pg=0 (spawn #222171) 2025-05-26 19:46:37.664272 :9C6003BB: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-26 19:46:37.664272 :9C6003BC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=197 time=1264569922 2025-05-26 19:46:37.664273 :9C6003BD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 19:46:37.664273 :9C6003BE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 19:46:37.664273 :9C6003BF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 19:46:37.664274 :9C6003C0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 19:46:37.664274 :9C6003C1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 19:46:37.664785 :9C6003C2: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-26 19:46:37.664828 :9C6003C9: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-26 19:56:38.618461 :9C60C8A2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 19:56:39.133172 :9C60C90F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 19:56:39.128 2025-05-26 19:56:39.133175 :9C60C910:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12932 pso_num=22 pso_serial#=49 2025-05-26 19:56:39.133308 :9C60C911:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12932 osp_idx=0 osp_ver=235924 osp_pg=0 (spawn #222194) 2025-05-26 19:56:39.133309 :9C60C912: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-26 19:56:39.133310 :9C60C913:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=198 time=1265171391 2025-05-26 19:56:39.133310 :9C60C914:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 19:56:39.133311 :9C60C915:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 19:56:39.133311 :9C60C916:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 19:56:39.133311 :9C60C917:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 19:56:39.133312 :9C60C918:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 19:56:39.133808 :9C60C919: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-26 19:56:39.133854 :9C60C923: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-26 20:06:40.063992 :9C61965C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 20:06:40.584207 :9C6196C2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 20:06:40.579 2025-05-26 20:06:40.584209 :9C6196C3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11732 pso_num=22 pso_serial#=50 2025-05-26 20:06:40.584354 :9C6196C4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11732 osp_idx=0 osp_ver=235925 osp_pg=0 (spawn #222220) 2025-05-26 20:06:40.584355 :9C6196C5: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-26 20:06:40.584357 :9C6196C6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=199 time=1265772844 2025-05-26 20:06:40.584357 :9C6196C7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 20:06:40.584358 :9C6196C8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 20:06:40.584358 :9C6196C9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 20:06:40.584358 :9C6196CA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 20:06:40.584359 :9C6196CB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 20:06:40.584866 :9C6196CC: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-26 20:06:40.584916 :9C6196D4: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-26 20:16:41.450836 :9C625E8C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 20:16:42.123804 :9C625EFB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 20:16:42.118 2025-05-26 20:16:42.123806 :9C625EFC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15152 pso_num=22 pso_serial#=51 2025-05-26 20:16:42.123945 :9C625EFD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15152 osp_idx=0 osp_ver=235926 osp_pg=0 (spawn #222242) 2025-05-26 20:16:42.123946 :9C625EFE: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-26 20:16:42.123947 :9C625EFF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=200 time=1266374375 2025-05-26 20:16:42.123947 :9C625F00:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 20:16:42.123947 :9C625F01:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 20:16:42.123948 :9C625F02:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 20:16:42.123948 :9C625F03:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 20:16:42.123949 :9C625F04:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 20:16:42.124474 :9C625F05: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-26 20:16:42.124522 :9C625F0F: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-26 20:26:43.060756 :9C632376:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 20:26:43.518913 :9C6323E9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 20:26:43.514 2025-05-26 20:26:43.518915 :9C6323EA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13748 pso_num=22 pso_serial#=52 2025-05-26 20:26:43.519059 :9C6323EB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13748 osp_idx=0 osp_ver=235927 osp_pg=0 (spawn #222265) 2025-05-26 20:26:43.519059 :9C6323EC: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-26 20:26:43.519060 :9C6323ED:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=201 time=1266975766 2025-05-26 20:26:43.519060 :9C6323EE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 20:26:43.519061 :9C6323EF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 20:26:43.519061 :9C6323F0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 20:26:43.519062 :9C6323F1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 20:26:43.519062 :9C6323F2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 20:26:43.519566 :9C6323F3: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-26 20:26:43.519617 :9C6323FB: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-26 20:36:44.353255 :9C63E9D0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 20:36:44.843161 :9C63EA40:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 20:36:44.839 2025-05-26 20:36:44.843163 :9C63EA41:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14400 pso_num=22 pso_serial#=53 2025-05-26 20:36:44.843300 :9C63EA42:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14400 osp_idx=0 osp_ver=235928 osp_pg=0 (spawn #222288) 2025-05-26 20:36:44.843301 :9C63EA43: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-26 20:36:44.843302 :9C63EA44:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=202 time=1267577094 2025-05-26 20:36:44.843302 :9C63EA45:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 20:36:44.843302 :9C63EA46:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 20:36:44.843303 :9C63EA47:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 20:36:44.843303 :9C63EA48:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 20:36:44.843304 :9C63EA49:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 20:36:44.843789 :9C63EA4A: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-26 20:36:44.843835 :9C63EA51: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-26 20:46:45.769844 :9C64AEE1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 20:46:46.242712 :9C64AF54:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 20:46:46.238 2025-05-26 20:46:46.242714 :9C64AF55:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14364 pso_num=22 pso_serial#=54 2025-05-26 20:46:46.242846 :9C64AF56:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14364 osp_idx=0 osp_ver=235929 osp_pg=0 (spawn #222310) 2025-05-26 20:46:46.242847 :9C64AF57: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-26 20:46:46.242847 :9C64AF58:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=203 time=1268178500 2025-05-26 20:46:46.242848 :9C64AF59:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 20:46:46.242848 :9C64AF5A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 20:46:46.242849 :9C64AF5B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 20:46:46.242849 :9C64AF5C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 20:46:46.242849 :9C64AF5D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 20:46:46.243325 :9C64AF5E: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-26 20:46:46.243375 :9C64AF68: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-26 20:55:24.281063 :9C6558EB: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-26 20:56:47.586371 :9C657418: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-26 21:06:48.497722 :9C664132:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 21:06:48.877344 :9C664194:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 21:06:48.872 2025-05-26 21:06:48.877347 :9C664195:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12104 pso_num=22 pso_serial#=55 2025-05-26 21:06:48.877496 :9C664196:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12104 osp_idx=0 osp_ver=235930 osp_pg=0 (spawn #222358) 2025-05-26 21:06:48.877496 :9C664197: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-26 21:06:48.877497 :9C664198:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=204 time=1269381125 2025-05-26 21:06:48.877497 :9C664199:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:06:48.877498 :9C66419A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:06:48.877498 :9C66419B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:06:48.877499 :9C66419C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:06:48.877499 :9C66419D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:06:48.878035 :9C66419E: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-26 21:06:48.878084 :9C6641A5: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-26 21:16:49.807551 :9C67084D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 21:16:50.155993 :9C6708C2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 21:16:50.151 2025-05-26 21:16:50.155996 :9C6708C3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13632 pso_num=22 pso_serial#=56 2025-05-26 21:16:50.156138 :9C6708C4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13632 osp_idx=0 osp_ver=235931 osp_pg=0 (spawn #222380) 2025-05-26 21:16:50.156138 :9C6708C5: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-26 21:16:50.156139 :9C6708C6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=205 time=1269982407 2025-05-26 21:16:50.156139 :9C6708C7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:16:50.156139 :9C6708C8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:16:50.156139 :9C6708C9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:16:50.156140 :9C6708CA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:16:50.156140 :9C6708CB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:16:50.156640 :9C6708CC: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-26 21:16:50.156687 :9C6708D4: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-26 21:26:50.999575 :9C67CCF5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 21:26:51.394790 :9C67CD5B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 21:26:51.390 2025-05-26 21:26:51.394792 :9C67CD5C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12296 pso_num=22 pso_serial#=57 2025-05-26 21:26:51.394928 :9C67CD5D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12296 osp_idx=0 osp_ver=235932 osp_pg=0 (spawn #222403) 2025-05-26 21:26:51.394928 :9C67CD5E: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-26 21:26:51.394929 :9C67CD5F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=206 time=1270583657 2025-05-26 21:26:51.394929 :9C67CD60:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:26:51.394930 :9C67CD61:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:26:51.394930 :9C67CD62:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:26:51.394930 :9C67CD63:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:26:51.394931 :9C67CD64:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:26:51.395415 :9C67CD65: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-26 21:26:51.395471 :9C67CD6B: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-26 21:36:52.411673 :9C68926C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 21:36:52.580619 :9C68926F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 21:36:52.568 2025-05-26 21:36:52.580621 :9C689270:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10684 pso_num=22 pso_serial#=58 2025-05-26 21:36:52.580756 :9C689271:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10684 (legacy spawn) 2025-05-26 21:36:52.587264 :9C689272:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-26 21:36:52.587441 :9C689277:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-26 21:36:52.588249 :9C68927C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 21:36:52.759797 :9C6892F0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 21:36:52.755 2025-05-26 21:36:52.759799 :9C6892F1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13960 pso_num=22 pso_serial#=59 2025-05-26 21:36:52.759934 :9C6892F2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13960 osp_idx=0 osp_ver=235934 osp_pg=0 (spawn #222426) 2025-05-26 21:36:52.759935 :9C6892F3: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-26 21:36:52.759935 :9C6892F4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=207 time=1271185016 2025-05-26 21:36:52.759936 :9C6892F5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:36:52.759936 :9C6892F6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:36:52.759937 :9C6892F7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:36:52.759937 :9C6892F8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:36:52.759937 :9C6892F9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:36:52.760437 :9C6892FA: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-26 21:36:52.760481 :9C689302: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-26 21:37:52.878861 :9C68A6BE:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=13 seq_num=14 snap_id=1 2025-05-26 21:37:52.878883 :9C68A6C3:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=13 seq_num=14 snap_id=1 2025-05-26 21:37:52.878884 :9C68A6C4:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=21, exc=21, tot=21 2025-05-26 21:46:53.746915 :9C69569D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 21:46:54.065172 :9C695701:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 21:46:54.060 2025-05-26 21:46:54.065174 :9C695702:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13296 pso_num=22 pso_serial#=60 2025-05-26 21:46:54.065310 :9C695703:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13296 osp_idx=0 osp_ver=235935 osp_pg=0 (spawn #222448) 2025-05-26 21:46:54.065311 :9C695704: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-26 21:46:54.065311 :9C695705:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=208 time=1271786313 2025-05-26 21:46:54.065312 :9C695706:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:46:54.065312 :9C695707:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:46:54.065313 :9C695708:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:46:54.065313 :9C695709:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:46:54.065314 :9C69570A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:46:54.065818 :9C69570B: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-26 21:46:54.065862 :9C695713: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-26 21:56:54.995991 :9C6A1A84:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-26 21:56:55.266321 :9C6A1AEB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-26 21:56:55.261 2025-05-26 21:56:55.266323 :9C6A1AEC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15040 pso_num=22 pso_serial#=61 2025-05-26 21:56:55.266474 :9C6A1AED:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15040 osp_idx=0 osp_ver=235936 osp_pg=0 (spawn #222471) 2025-05-26 21:56:55.266474 :9C6A1AEE: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-26 21:56:55.266475 :9C6A1AEF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=209 time=1272387516 2025-05-26 21:56:55.266475 :9C6A1AF0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:56:55.266475 :9C6A1AF1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:56:55.266476 :9C6A1AF2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:56:55.266476 :9C6A1AF3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:56:55.266476 :9C6A1AF4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:56:55.266988 :9C6A1AF5: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-26 21:56:55.267034 :9C6A1AFC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 15040, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de