Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511100330\orcl_w000_12596_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511100330\orcl_w000_12596_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:37500M/63366M, Ph+PgF:44896M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12596, image: ORACLE.EXE (W000) *** 2025-05-11 10:03:31.011 *** SESSION ID:(177.21655) 2025-05-11 10:03:31.011 *** 2025-05-11 10:03:31.011 Process diagnostic dump for ORACLE.EXE (W000), OS id=12596, pid: 22, proc_ser: 148, sid: 177, sess_ser: 21655 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=81 seq_num=82 snap_id=1 wait times: snap=0.678544 sec, exc=0.678544 sec, total=0.678544 sec wait times: max=5.000000 sec, heur=6 min 41 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.000024 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=80 seq_num=81 snap_id=1 wait times: snap=5.001131 sec, exc=5.001131 sec, total=5.001131 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=79 seq_num=80 snap_id=1 wait times: snap=5.009129 sec, exc=5.009129 sec, total=5.009129 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=78 seq_num=79 snap_id=1 wait times: snap=5.015239 sec, exc=5.015239 sec, total=5.015239 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=77 seq_num=78 snap_id=1 wait times: snap=5.004607 sec, exc=5.004607 sec, total=5.004607 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=76 seq_num=77 snap_id=1 wait times: snap=5.004630 sec, exc=5.004630 sec, total=5.004630 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=75 seq_num=76 snap_id=1 wait times: snap=5.009244 sec, exc=5.009244 sec, total=5.009244 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=74 seq_num=75 snap_id=1 wait times: snap=5.005141 sec, exc=5.005141 sec, total=5.005141 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=73 seq_num=74 snap_id=1 wait times: snap=5.013467 sec, exc=5.013467 sec, total=5.013467 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=72 seq_num=73 snap_id=1 wait times: snap=5.008149 sec, exc=5.008149 sec, total=5.008149 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=71 seq_num=72 snap_id=1 wait times: snap=5.014725 sec, exc=5.014725 sec, total=5.014725 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time Sampled Session History of session 177 serial 21655 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 10:01:31 - 10:03:31] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-11 10:03:31.011 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12596, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-11 07:46:32.890613 :9590A177:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12968 pso_num=22 pso_serial#=134 2025-05-11 07:46:32.890759 :9590A178:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12968 osp_idx=0 osp_ver=178379 osp_pg=0 (spawn #170453) 2025-05-11 07:46:32.890760 :9590A179: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-11 07:46:32.890760 :9590A17A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=62 time=4220370062 2025-05-11 07:46:32.890761 :9590A17B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 07:46:32.890761 :9590A17C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 07:46:32.890761 :9590A17D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 07:46:32.890762 :9590A17E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 07:46:32.890762 :9590A17F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 07:46:32.891248 :9590A180: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-11 07:46:32.891291 :9590A187: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-11 07:56:33.770659 :95916498:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 07:56:34.197454 :959164F0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 07:56:34.192 2025-05-11 07:56:34.197456 :959164F1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9932 pso_num=22 pso_serial#=135 2025-05-11 07:56:34.197589 :959164F2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9932 osp_idx=0 osp_ver=178380 osp_pg=0 (spawn #170476) 2025-05-11 07:56:34.197590 :959164F3: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-11 07:56:34.197590 :959164F4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=63 time=4220971359 2025-05-11 07:56:34.197591 :959164F5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 07:56:34.197591 :959164F6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 07:56:34.197591 :959164F7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 07:56:34.197592 :959164F8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 07:56:34.197592 :959164F9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 07:56:34.198075 :959164FA: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-11 07:56:34.198120 :95916501: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-11 08:06:35.217206 :95922FFD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 08:06:35.565783 :95923048:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 08:06:35.561 2025-05-11 08:06:35.565785 :95923049:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12396 pso_num=22 pso_serial#=136 2025-05-11 08:06:35.565922 :9592304A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12396 osp_idx=0 osp_ver=178381 osp_pg=0 (spawn #170503) 2025-05-11 08:06:35.565923 :9592304B: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-11 08:06:35.565923 :9592304C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=64 time=4221572734 2025-05-11 08:06:35.565924 :9592304D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:06:35.565924 :9592304E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:06:35.565924 :9592304F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:06:35.565925 :95923050:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:06:35.565925 :95923051:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:06:35.566420 :95923052: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-11 08:06:35.566466 :95923059: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-11 08:16:36.472221 :9592F4F1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 08:16:36.773758 :9592F53E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 08:16:36.769 2025-05-11 08:16:36.773760 :9592F53F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10564 pso_num=22 pso_serial#=137 2025-05-11 08:16:36.773905 :9592F540:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10564 osp_idx=0 osp_ver=178382 osp_pg=0 (spawn #170526) 2025-05-11 08:16:36.773906 :9592F541: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-11 08:16:36.773907 :9592F542:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=65 time=4222173937 2025-05-11 08:16:36.773907 :9592F543:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:16:36.773908 :9592F544:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:16:36.773908 :9592F545:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:16:36.773909 :9592F546:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:16:36.773909 :9592F547:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:16:36.774454 :9592F548: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-11 08:16:36.774500 :9592F552: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-11 08:18:38.088153 :95931C65: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-11 08:26:37.983145 :95940853: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-11 08:36:38.837265 :9594E8C9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 08:36:39.310528 :9594E8FF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 08:36:39.305 2025-05-11 08:36:39.310530 :9594E900:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12768 pso_num=22 pso_serial#=138 2025-05-11 08:36:39.310668 :9594E901:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12768 osp_idx=0 osp_ver=178383 osp_pg=0 (spawn #170577) 2025-05-11 08:36:39.310668 :9594E902: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-11 08:36:39.310669 :9594E903:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=66 time=4223376484 2025-05-11 08:36:39.310669 :9594E904:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:36:39.310669 :9594E905:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:36:39.310670 :9594E906:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:36:39.310670 :9594E907:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:36:39.310670 :9594E908:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:36:39.311162 :9594E909: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-11 08:36:39.311212 :9594E913: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-11 08:46:40.245050 :9595B0DE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 08:46:40.515347 :9595B117:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 08:46:40.510 2025-05-11 08:46:40.515349 :9595B118:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12864 pso_num=22 pso_serial#=139 2025-05-11 08:46:40.515499 :9595B119:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12864 osp_idx=0 osp_ver=178384 osp_pg=0 (spawn #170600) 2025-05-11 08:46:40.515499 :9595B11A: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-11 08:46:40.515500 :9595B11B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=67 time=4223977687 2025-05-11 08:46:40.515500 :9595B11C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:46:40.515501 :9595B11D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:46:40.515501 :9595B11E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:46:40.515501 :9595B11F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:46:40.515502 :9595B120:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:46:40.515995 :9595B121: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-11 08:46:40.516041 :9595B12A: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-11 08:56:41.385905 :9596752F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 08:56:41.874860 :95967566:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 08:56:41.870 2025-05-11 08:56:41.874862 :95967567:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12352 pso_num=22 pso_serial#=140 2025-05-11 08:56:41.875004 :95967568:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12352 osp_idx=0 osp_ver=178385 osp_pg=0 (spawn #170623) 2025-05-11 08:56:41.875004 :95967569: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-11 08:56:41.875005 :9596756A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=68 time=4224579046 2025-05-11 08:56:41.875005 :9596756B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:56:41.875005 :9596756C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:56:41.875006 :9596756D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:56:41.875006 :9596756E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:56:41.875006 :9596756F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:56:41.875507 :95967570: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-11 08:56:41.875552 :95967577: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-11 08:56:56.909461 :95967B7C:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-05-11 08:56:56.909466 :95967B7D:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-05-11 08:56:56.909467 :95967B7E:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=7, exc=7, tot=7 2025-05-11 08:56:56.909478 :95967B83: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-11 09:06:42.853886 :9597468F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 09:06:43.149966 :959746D4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 09:06:43.145 2025-05-11 09:06:43.149968 :959746D5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9452 pso_num=22 pso_serial#=141 2025-05-11 09:06:43.150109 :959746D6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9452 osp_idx=0 osp_ver=178386 osp_pg=0 (spawn #170648) 2025-05-11 09:06:43.150110 :959746D7: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-11 09:06:43.150111 :959746D8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=69 time=4225180312 2025-05-11 09:06:43.150111 :959746D9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 09:06:43.150112 :959746DA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 09:06:43.150112 :959746DB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 09:06:43.150113 :959746DC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 09:06:43.150113 :959746DD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 09:06:43.150621 :959746DE: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-11 09:06:43.150665 :959746E7: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-11 09:16:44.057376 :95980AF3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 09:16:44.499481 :95980B37:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 09:16:44.495 2025-05-11 09:16:44.499483 :95980B38:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12508 pso_num=22 pso_serial#=142 2025-05-11 09:16:44.499618 :95980B39:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12508 osp_idx=0 osp_ver=178387 osp_pg=0 (spawn #170671) 2025-05-11 09:16:44.499619 :95980B3A: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-11 09:16:44.499619 :95980B3B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=70 time=4225781671 2025-05-11 09:16:44.499619 :95980B3C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 09:16:44.499620 :95980B3D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 09:16:44.499620 :95980B3E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 09:16:44.499620 :95980B3F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 09:16:44.499621 :95980B40:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 09:16:44.500117 :95980B41: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-11 09:16:44.500166 :95980B4B: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-11 09:26:45.355227 :9598D038:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 09:26:45.750757 :9598D07E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 09:26:45.746 2025-05-11 09:26:45.750758 :9598D07F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10752 pso_num=22 pso_serial#=143 2025-05-11 09:26:45.750902 :9598D080:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10752 osp_idx=0 osp_ver=178388 osp_pg=0 (spawn #170696) 2025-05-11 09:26:45.750903 :9598D081: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-11 09:26:45.750903 :9598D082:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=71 time=4226382921 2025-05-11 09:26:45.750904 :9598D083:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 09:26:45.750904 :9598D084:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 09:26:45.750904 :9598D085:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 09:26:45.750905 :9598D086:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 09:26:45.750905 :9598D087:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 09:26:45.751494 :9598D088: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-11 09:26:45.751540 :9598D090: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-11 09:36:46.653342 :95999498:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 09:36:46.823777 :9599949B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 09:36:46.809 2025-05-11 09:36:46.823779 :9599949C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11856 pso_num=22 pso_serial#=144 2025-05-11 09:36:46.823911 :9599949D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11856 (legacy spawn) 2025-05-11 09:36:46.830338 :9599949E: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-11 09:36:46.830512 :959994A3: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-11 09:36:46.831296 :959994A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 09:36:46.839968 :959994AB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 09:36:46.825 2025-05-11 09:36:46.839970 :959994AC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12956 pso_num=22 pso_serial#=145 2025-05-11 09:36:46.840108 :959994AD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12956 (legacy spawn) 2025-05-11 09:36:46.861165 :959994AE: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-11 09:36:46.861333 :959994B6: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-11 09:36:46.862312 :959994B8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 09:36:47.095400 :959994F2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 09:36:47.090 2025-05-11 09:36:47.095402 :959994F3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11896 pso_num=22 pso_serial#=146 2025-05-11 09:36:47.095539 :959994F4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11896 osp_idx=0 osp_ver=178391 osp_pg=0 (spawn #170716) 2025-05-11 09:36:47.095539 :959994F5: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-11 09:36:47.095540 :959994F6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=72 time=4226984265 2025-05-11 09:36:47.095540 :959994F7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 09:36:47.095541 :959994F8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 09:36:47.095541 :959994F9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 09:36:47.095541 :959994FA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 09:36:47.095542 :959994FB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 09:36:47.096038 :959994FC: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-11 09:36:47.096085 :95999503: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-11 09:46:48.080331 :959A59A3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 09:46:48.428800 :959A59E7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 09:46:48.424 2025-05-11 09:46:48.428803 :959A59E8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13304 pso_num=22 pso_serial#=147 2025-05-11 09:46:48.428935 :959A59E9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13304 osp_idx=0 osp_ver=178392 osp_pg=0 (spawn #170739) 2025-05-11 09:46:48.428936 :959A59EA: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-11 09:46:48.428937 :959A59EB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=73 time=4227585593 2025-05-11 09:46:48.428937 :959A59EC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 09:46:48.428938 :959A59ED:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 09:46:48.428939 :959A59EE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 09:46:48.428939 :959A59EF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 09:46:48.428939 :959A59F0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 09:46:48.429436 :959A59F1: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-11 09:46:48.429480 :959A59F8: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-11 09:56:49.276331 :959B1EC7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 09:56:49.734222 :959B1F24:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 09:56:49.729 2025-05-11 09:56:49.734224 :959B1F25:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12596 pso_num=22 pso_serial#=148 2025-05-11 09:56:49.734363 :959B1F26:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12596 osp_idx=0 osp_ver=178393 osp_pg=0 (spawn #170762) 2025-05-11 09:56:49.734364 :959B1F27: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-11 09:56:49.734365 :959B1F28:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=74 time=4228186906 2025-05-11 09:56:49.734365 :959B1F29:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 09:56:49.734366 :959B1F2A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 09:56:49.734366 :959B1F2B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 09:56:49.734366 :959B1F2C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 09:56:49.734367 :959B1F2D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 09:56:49.734882 :959B1F2E: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-11 09:56:49.734939 :959B1F38: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: 12596, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de