Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250910220008\orcl_w000_23820_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250910220008\orcl_w000_23820_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:34846M/63366M, Ph+PgF:39532M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 23820, image: ORACLE.EXE (W000) *** 2025-09-10 22:00:08.736 *** SESSION ID:(201.57277) 2025-09-10 22:00:08.736 *** 2025-09-10 22:00:08.736 Process diagnostic dump for ORACLE.EXE (W000), OS id=23820, pid: 25, proc_ser: 79, sid: 201, sess_ser: 57277 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=3.896110 sec, exc=3.896110 sec, total=3.896110 sec wait times: max=5.000000 sec, heur=58.941845 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=19 seq_num=20 snap_id=1 wait times: snap=5.002148 sec, exc=5.002148 sec, total=5.002148 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=18 seq_num=19 snap_id=1 wait times: snap=5.001454 sec, exc=5.001454 sec, total=5.001454 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=5.002427 sec, exc=5.002427 sec, total=5.002427 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=5.013914 sec, exc=5.013914 sec, total=5.013914 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=5.004625 sec, exc=5.004625 sec, total=5.004625 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=5.009746 sec, exc=5.009746 sec, total=5.009746 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=13 seq_num=14 snap_id=1 wait times: snap=5.002157 sec, exc=5.002157 sec, total=5.002157 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=5.001559 sec, exc=5.001559 sec, total=5.001559 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=5.000326 sec, exc=5.000326 sec, total=5.000326 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=5.002925 sec, exc=5.002925 sec, total=5.002925 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 201 serial 57277 --------------------------------------------------- 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 --------------------------------------------------- [89 samples, 21:58:39 - 22:00:08] idle wait at each sample [session created at: 21:58:39] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-10 22:00:08.737 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 23820, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-10 19:38:23.468938 :CBA8D06B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 19:38:23.468938 :CBA8D06C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 19:38:23.468939 :CBA8D06D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 19:38:23.468939 :CBA8D06E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 19:38:23.468939 :CBA8D06F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 19:38:23.469475 :CBA8D070: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-09-10 19:38:23.469523 :CBA8D077: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-09-10 19:48:24.269819 :CBA9990A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 19:48:24.680794 :CBA99940:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 19:48:24.675 2025-09-10 19:48:24.680796 :CBA99941:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24236 pso_num=25 pso_serial#=67 2025-09-10 19:48:24.680939 :CBA99942:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24236 osp_idx=23 osp_ver=292565 osp_pg=0 (spawn #578861) 2025-09-10 19:48:24.680940 :CBA99943:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 19:48:24.680940 :CBA99944:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=205 time=1919355987 2025-09-10 19:48:24.680940 :CBA99945:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 19:48:24.680941 :CBA99946:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 19:48:24.680941 :CBA99947:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 19:48:24.680941 :CBA99948:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 19:48:24.680942 :CBA99949:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 19:48:24.681448 :CBA9994A: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-09-10 19:48:24.681498 :CBA99952: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-09-10 19:58:25.556335 :CBAA61B8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 19:58:25.858008 :CBAA61EE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 19:58:25.853 2025-09-10 19:58:25.858010 :CBAA61EF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20352 pso_num=25 pso_serial#=68 2025-09-10 19:58:25.858146 :CBAA61F0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20352 osp_idx=23 osp_ver=292566 osp_pg=0 (spawn #578883) 2025-09-10 19:58:25.858146 :CBAA61F1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 19:58:25.858147 :CBAA61F2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=206 time=1919957158 2025-09-10 19:58:25.858147 :CBAA61F3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 19:58:25.858148 :CBAA61F4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 19:58:25.858148 :CBAA61F5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 19:58:25.858148 :CBAA61F6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 19:58:25.858149 :CBAA61F7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 19:58:25.858651 :CBAA61F8: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-09-10 19:58:25.858696 :CBAA6201: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-09-10 20:08:26.732473 :CBAB329B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 20:08:26.987412 :CBAB32D5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 20:08:26.982 2025-09-10 20:08:26.987414 :CBAB32D6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23364 pso_num=25 pso_serial#=69 2025-09-10 20:08:26.987552 :CBAB32D7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23364 osp_idx=23 osp_ver=292567 osp_pg=0 (spawn #578909) 2025-09-10 20:08:26.987552 :CBAB32D8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 20:08:26.987553 :CBAB32D9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=207 time=1920558299 2025-09-10 20:08:26.987553 :CBAB32DA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 20:08:26.987554 :CBAB32DB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 20:08:26.987554 :CBAB32DC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 20:08:26.987554 :CBAB32DD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 20:08:26.987555 :CBAB32DE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 20:08:26.988084 :CBAB32DF: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-09-10 20:08:26.988130 :CBAB32E7: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-09-10 20:18:27.750056 :CBABFB02:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 20:18:28.114473 :CBABFB48:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 20:18:28.109 2025-09-10 20:18:28.114476 :CBABFB49:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21312 pso_num=25 pso_serial#=70 2025-09-10 20:18:28.114621 :CBABFB4A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21312 osp_idx=23 osp_ver=292568 osp_pg=0 (spawn #578932) 2025-09-10 20:18:28.114622 :CBABFB4B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 20:18:28.114622 :CBABFB4C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=208 time=1921159424 2025-09-10 20:18:28.114622 :CBABFB4D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 20:18:28.114623 :CBABFB4E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 20:18:28.114623 :CBABFB4F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 20:18:28.114623 :CBABFB50:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 20:18:28.114624 :CBABFB51:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 20:18:28.115155 :CBABFB52: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-09-10 20:18:28.115200 :CBABFB59: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-09-10 20:28:28.936047 :CBACC5FD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 20:28:29.222008 :CBACC637:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 20:28:29.217 2025-09-10 20:28:29.222010 :CBACC638:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23632 pso_num=25 pso_serial#=71 2025-09-10 20:28:29.222153 :CBACC639:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23632 osp_idx=23 osp_ver=292569 osp_pg=0 (spawn #578954) 2025-09-10 20:28:29.222154 :CBACC63A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 20:28:29.222154 :CBACC63B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=209 time=1921760533 2025-09-10 20:28:29.222155 :CBACC63C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 20:28:29.222155 :CBACC63D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 20:28:29.222156 :CBACC63E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 20:28:29.222156 :CBACC63F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 20:28:29.222156 :CBACC640:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 20:28:29.222699 :CBACC641: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-09-10 20:28:29.222744 :CBACC649: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-09-10 20:38:30.088602 :CBAD8F14:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 20:38:30.408454 :CBAD8F4C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 20:38:30.403 2025-09-10 20:38:30.408456 :CBAD8F4D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19144 pso_num=25 pso_serial#=72 2025-09-10 20:38:30.408594 :CBAD8F4E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19144 osp_idx=23 osp_ver=292570 osp_pg=0 (spawn #578977) 2025-09-10 20:38:30.408595 :CBAD8F4F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 20:38:30.408595 :CBAD8F50:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=210 time=1922361705 2025-09-10 20:38:30.408595 :CBAD8F51:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 20:38:30.408596 :CBAD8F52:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 20:38:30.408596 :CBAD8F53:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 20:38:30.408597 :CBAD8F54:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 20:38:30.408597 :CBAD8F55:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 20:38:30.409090 :CBAD8F56: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-09-10 20:38:30.409134 :CBAD8F5D: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-09-10 20:48:31.208841 :CBAE5787:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 20:48:31.510531 :CBAE57BF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 20:48:31.506 2025-09-10 20:48:31.510533 :CBAE57C0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21580 pso_num=25 pso_serial#=73 2025-09-10 20:48:31.510680 :CBAE57C1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21580 osp_idx=23 osp_ver=292571 osp_pg=0 (spawn #579000) 2025-09-10 20:48:31.510681 :CBAE57C2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 20:48:31.510681 :CBAE57C3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=211 time=1922962815 2025-09-10 20:48:31.510682 :CBAE57C4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 20:48:31.510682 :CBAE57C5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 20:48:31.510683 :CBAE57C6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 20:48:31.510683 :CBAE57C7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 20:48:31.510683 :CBAE57C8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 20:48:31.511181 :CBAE57C9: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-09-10 20:48:31.511226 :CBAE57D2: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-09-10 20:49:16.610373 :CBAE670A:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=10 seq_num=11 snap_id=1 2025-09-10 20:49:16.610377 :CBAE670B:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=10 seq_num=11 snap_id=1 2025-09-10 20:49:16.610378 :CBAE670C:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=5, exc=5, tot=5 2025-09-10 20:49:16.610386 :CBAE670F: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-09-10 20:58:32.293580 :CBAF20DE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 20:58:32.595000 :CBAF2125:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 20:58:32.590 2025-09-10 20:58:32.595002 :CBAF2126:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24348 pso_num=25 pso_serial#=74 2025-09-10 20:58:32.595138 :CBAF2127:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24348 osp_idx=23 osp_ver=292572 osp_pg=0 (spawn #579023) 2025-09-10 20:58:32.595138 :CBAF2128:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 20:58:32.595139 :CBAF2129:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=212 time=1923563893 2025-09-10 20:58:32.595139 :CBAF212A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 20:58:32.595139 :CBAF212B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 20:58:32.595139 :CBAF212C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 20:58:32.595140 :CBAF212D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 20:58:32.595140 :CBAF212E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 20:58:32.595627 :CBAF212F: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-09-10 20:58:32.595674 :CBAF2136: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-09-10 20:59:01.952807 :CBAF2A69: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-09-10 21:08:33.769637 :CBAFF181: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-09-10 21:18:34.559646 :CBB0BC42:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 21:18:34.908227 :CBB0BCAB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 21:18:34.903 2025-09-10 21:18:34.908228 :CBB0BCAC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23952 pso_num=25 pso_serial#=75 2025-09-10 21:18:34.908373 :CBB0BCAD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23952 osp_idx=23 osp_ver=292573 osp_pg=0 (spawn #579071) 2025-09-10 21:18:34.908374 :CBB0BCAE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 21:18:34.908375 :CBB0BCAF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=213 time=1924766205 2025-09-10 21:18:34.908375 :CBB0BCB0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 21:18:34.908376 :CBB0BCB1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 21:18:34.908376 :CBB0BCB2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 21:18:34.908376 :CBB0BCB3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 21:18:34.908376 :CBB0BCB4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 21:18:34.908887 :CBB0BCB5: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-09-10 21:18:34.908932 :CBB0BCBD: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-09-10 21:28:35.669422 :CBB18405:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 21:28:36.049371 :CBB1844A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 21:28:36.044 2025-09-10 21:28:36.049373 :CBB1844B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23624 pso_num=25 pso_serial#=76 2025-09-10 21:28:36.049512 :CBB1844C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23624 osp_idx=23 osp_ver=292574 osp_pg=0 (spawn #579093) 2025-09-10 21:28:36.049512 :CBB1844D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 21:28:36.049513 :CBB1844E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=1925367346 2025-09-10 21:28:36.049513 :CBB1844F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 21:28:36.049514 :CBB18450:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 21:28:36.049514 :CBB18451:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 21:28:36.049514 :CBB18452:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 21:28:36.049515 :CBB18453:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 21:28:36.050000 :CBB18454: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-09-10 21:28:36.050046 :CBB1845B: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-09-10 21:38:36.810672 :CBB24C3C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 21:38:37.262979 :CBB24C80:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 21:38:37.258 2025-09-10 21:38:37.262981 :CBB24C81:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23872 pso_num=25 pso_serial#=77 2025-09-10 21:38:37.263124 :CBB24C82:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23872 osp_idx=23 osp_ver=292575 osp_pg=0 (spawn #579116) 2025-09-10 21:38:37.263124 :CBB24C83:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 21:38:37.263124 :CBB24C84:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=1925968565 2025-09-10 21:38:37.263125 :CBB24C85:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 21:38:37.263125 :CBB24C86:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 21:38:37.263126 :CBB24C87:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 21:38:37.263126 :CBB24C88:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 21:38:37.263127 :CBB24C89:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 21:38:37.263618 :CBB24C8A: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-09-10 21:38:37.263663 :CBB24C93: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-09-10 21:48:38.062606 :CBB314E3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 21:48:38.520471 :CBB3151C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 21:48:38.515 2025-09-10 21:48:38.520473 :CBB3151D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14932 pso_num=25 pso_serial#=78 2025-09-10 21:48:38.520609 :CBB3151E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14932 osp_idx=23 osp_ver=292576 osp_pg=0 (spawn #579139) 2025-09-10 21:48:38.520610 :CBB3151F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 21:48:38.520610 :CBB31520:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=1926569830 2025-09-10 21:48:38.520610 :CBB31521:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 21:48:38.520611 :CBB31522:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 21:48:38.520611 :CBB31523:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 21:48:38.520611 :CBB31524:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 21:48:38.520612 :CBB31525:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 21:48:38.521096 :CBB31526: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-09-10 21:48:38.521152 :CBB31530: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-09-10 21:58:39.322886 :CBB3DD61:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-10 21:58:39.749512 :CBB3DDA5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-10 21:58:39.745 2025-09-10 21:58:39.749514 :CBB3DDA6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23820 pso_num=25 pso_serial#=79 2025-09-10 21:58:39.749656 :CBB3DDA7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23820 osp_idx=23 osp_ver=292577 osp_pg=0 (spawn #579161) 2025-09-10 21:58:39.749657 :CBB3DDA8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 21:58:39.749657 :CBB3DDA9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=1927171049 2025-09-10 21:58:39.749658 :CBB3DDAA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 21:58:39.749658 :CBB3DDAB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 21:58:39.749659 :CBB3DDAC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 21:58:39.749659 :CBB3DDAD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 21:58:39.749659 :CBB3DDAE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 21:58:39.750173 :CBB3DDAF: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-09-10 21:58:39.750218 :CBB3DDB6: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-09-10 21:59:09.794358 :CBB3E7B8: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-09-10 21:59:09.794364 :CBB3E7B9: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-09-10 21:59:09.794365 :CBB3E7BA:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=7, exc=7, tot=7 2025-09-10 21:59:09.794375 :CBB3E7BF: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: 23820, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de