Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250621180641\orcl_w000_15624_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250621180641\orcl_w000_15624_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:36417M/63366M, Ph+PgF:42843M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 15624, image: ORACLE.EXE (W000) *** 2025-06-21 18:06:41.511 *** SESSION ID:(201.35957) 2025-06-21 18:06:41.511 *** 2025-06-21 18:06:41.511 Process diagnostic dump for ORACLE.EXE (W000), OS id=15624, pid: 25, proc_ser: 157, sid: 201, sess_ser: 35957 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=3.110613 sec, exc=3.110613 sec, total=3.110613 sec wait times: max=5.000000 sec, heur=2 min 48 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.000021 sec since current wait 0: 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.004623 sec, exc=5.004623 sec, total=5.004623 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=32 seq_num=33 snap_id=1 wait times: snap=5.002793 sec, exc=5.002793 sec, total=5.002793 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 2: 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.000157 sec, exc=5.000157 sec, total=5.000157 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=30 seq_num=31 snap_id=1 wait times: snap=5.007653 sec, exc=5.007653 sec, total=5.007653 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 4: 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.015409 sec, exc=5.015409 sec, total=5.015409 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.014742 sec, exc=5.014742 sec, total=5.014742 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=27 seq_num=28 snap_id=1 wait times: snap=5.004618 sec, exc=5.004618 sec, total=5.004618 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=26 seq_num=27 snap_id=1 wait times: snap=5.014732 sec, exc=5.014732 sec, total=5.014732 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=5.010146 sec, exc=5.010146 sec, total=5.010146 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.008494 sec, exc=5.008494 sec, total=5.008494 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 35957 --------------------------------------------------- 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, 18:04:41 - 18:06:41] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-21 18:06:41.511 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 15624, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-21 15:43:34.853952 :A7B88605:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 15:43:34.853952 :A7B88606:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 15:43:34.854444 :A7B88607: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-06-21 15:43:34.854488 :A7B88611: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-06-21 15:53:35.708839 :A7B94B93:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 15:53:36.025946 :A7B94C08:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 15:53:36.021 2025-06-21 15:53:36.025948 :A7B94C09:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16104 pso_num=25 pso_serial#=143 2025-06-21 15:53:36.026084 :A7B94C0A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16104 osp_idx=23 osp_ver=163710 osp_pg=0 (spawn #308311) 2025-06-21 15:53:36.026085 :A7B94C0B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 15:53:36.026085 :A7B94C0C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=198 time=3496959219 2025-06-21 15:53:36.026086 :A7B94C0D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 15:53:36.026086 :A7B94C0E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 15:53:36.026086 :A7B94C0F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 15:53:36.026087 :A7B94C10:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 15:53:36.026087 :A7B94C11:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 15:53:36.026580 :A7B94C12: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-06-21 15:53:36.026628 :A7B94C1C: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-06-21 16:03:36.848131 :A7BA1434:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 16:03:37.258891 :A7BA149B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 16:03:37.254 2025-06-21 16:03:37.258892 :A7BA149C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14644 pso_num=25 pso_serial#=144 2025-06-21 16:03:37.259028 :A7BA149D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14644 osp_idx=23 osp_ver=163711 osp_pg=0 (spawn #308337) 2025-06-21 16:03:37.259028 :A7BA149E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 16:03:37.259029 :A7BA149F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=199 time=3497560454 2025-06-21 16:03:37.259029 :A7BA14A0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 16:03:37.259030 :A7BA14A1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 16:03:37.259030 :A7BA14A2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 16:03:37.259030 :A7BA14A3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 16:03:37.259031 :A7BA14A4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 16:03:37.259514 :A7BA14A5: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-06-21 16:03:37.259561 :A7BA14AC: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-06-21 16:13:38.149468 :A7BAE415:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 16:13:38.404511 :A7BAE46E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 16:13:38.399 2025-06-21 16:13:38.404513 :A7BAE46F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17044 pso_num=25 pso_serial#=145 2025-06-21 16:13:38.404643 :A7BAE470:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17044 osp_idx=23 osp_ver=163712 osp_pg=0 (spawn #308360) 2025-06-21 16:13:38.404644 :A7BAE471:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 16:13:38.404644 :A7BAE472:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=200 time=3498161594 2025-06-21 16:13:38.404644 :A7BAE473:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 16:13:38.404645 :A7BAE474:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 16:13:38.404645 :A7BAE475:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 16:13:38.404646 :A7BAE476:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 16:13:38.404646 :A7BAE477:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 16:13:38.405185 :A7BAE478: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-06-21 16:13:38.405230 :A7BAE480: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-06-21 16:23:39.335342 :A7BBAA6D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 16:23:39.667609 :A7BBAACC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 16:23:39.663 2025-06-21 16:23:39.667611 :A7BBAACD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17776 pso_num=25 pso_serial#=146 2025-06-21 16:23:39.667752 :A7BBAACE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17776 osp_idx=23 osp_ver=163713 osp_pg=0 (spawn #308384) 2025-06-21 16:23:39.667752 :A7BBAACF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 16:23:39.667753 :A7BBAAD0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=201 time=3498762860 2025-06-21 16:23:39.667753 :A7BBAAD1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 16:23:39.667754 :A7BBAAD2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 16:23:39.667755 :A7BBAAD3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 16:23:39.667755 :A7BBAAD4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 16:23:39.667755 :A7BBAAD5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 16:23:39.668249 :A7BBAAD6: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-06-21 16:23:39.668296 :A7BBAADD: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-06-21 16:33:40.571922 :A7BC704D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 16:33:40.952018 :A7BC70A7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 16:33:40.947 2025-06-21 16:33:40.952020 :A7BC70A8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17520 pso_num=25 pso_serial#=147 2025-06-21 16:33:40.952155 :A7BC70A9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17520 osp_idx=23 osp_ver=163714 osp_pg=0 (spawn #308406) 2025-06-21 16:33:40.952156 :A7BC70AA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 16:33:40.952156 :A7BC70AB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=202 time=3499364141 2025-06-21 16:33:40.952156 :A7BC70AC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 16:33:40.952157 :A7BC70AD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 16:33:40.952157 :A7BC70AE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 16:33:40.952157 :A7BC70AF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 16:33:40.952158 :A7BC70B0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 16:33:40.952652 :A7BC70B1: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-06-21 16:33:40.952696 :A7BC70B8: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-06-21 16:43:41.847722 :A7BD3560:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 16:43:42.305548 :A7BD35A5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 16:43:42.300 2025-06-21 16:43:42.305551 :A7BD35A6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16688 pso_num=25 pso_serial#=148 2025-06-21 16:43:42.305691 :A7BD35A7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16688 osp_idx=23 osp_ver=163715 osp_pg=0 (spawn #308429) 2025-06-21 16:43:42.305691 :A7BD35A8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 16:43:42.305692 :A7BD35A9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=203 time=3499965500 2025-06-21 16:43:42.305692 :A7BD35AA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 16:43:42.305692 :A7BD35AB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 16:43:42.305693 :A7BD35AC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 16:43:42.305693 :A7BD35AD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 16:43:42.305693 :A7BD35AE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 16:43:42.306187 :A7BD35AF: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-06-21 16:43:42.306235 :A7BD35B7: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-06-21 16:45:03.612127 :A7BD5005: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-06-21 16:53:43.565004 :A7BDFBE2: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-06-21 17:03:44.460739 :A7BEC37E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 17:03:44.739356 :A7BEC392:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 17:03:44.726 2025-06-21 17:03:44.739358 :A7BEC393:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14804 pso_num=25 pso_serial#=149 2025-06-21 17:03:44.739493 :A7BEC394:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14804 (legacy spawn) 2025-06-21 17:03:44.745867 :A7BEC395:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 17:03:44.746042 :A7BEC39A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 17:03:44.746834 :A7BEC39F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 17:03:44.996587 :A7BEC3D6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 17:03:44.991 2025-06-21 17:03:44.996590 :A7BEC3D7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17888 pso_num=25 pso_serial#=150 2025-06-21 17:03:44.996727 :A7BEC3D8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17888 osp_idx=23 osp_ver=163717 osp_pg=0 (spawn #308476) 2025-06-21 17:03:44.996728 :A7BEC3D9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 17:03:44.996728 :A7BEC3DA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=204 time=3501168188 2025-06-21 17:03:44.996728 :A7BEC3DB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 17:03:44.996729 :A7BEC3DC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 17:03:44.996729 :A7BEC3DD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 17:03:44.996730 :A7BEC3DE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 17:03:44.996730 :A7BEC3DF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 17:03:44.997220 :A7BEC3E0: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-06-21 17:03:44.997262 :A7BEC3E8: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-06-21 17:13:45.923351 :A7BF8F8A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 17:13:46.396828 :A7BF8FC5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 17:13:46.391 2025-06-21 17:13:46.396831 :A7BF8FC6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13880 pso_num=25 pso_serial#=151 2025-06-21 17:13:46.396970 :A7BF8FC7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13880 osp_idx=23 osp_ver=163718 osp_pg=0 (spawn #308499) 2025-06-21 17:13:46.396971 :A7BF8FC8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 17:13:46.396971 :A7BF8FC9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=205 time=3501769594 2025-06-21 17:13:46.396972 :A7BF8FCA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 17:13:46.396972 :A7BF8FCB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 17:13:46.396972 :A7BF8FCC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 17:13:46.396972 :A7BF8FCD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 17:13:46.396973 :A7BF8FCE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 17:13:46.397460 :A7BF8FCF: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-06-21 17:13:46.397507 :A7BF8FD6: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-06-21 17:23:47.312428 :A7C05792:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 17:23:47.879555 :A7C057CD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 17:23:47.874 2025-06-21 17:23:47.879557 :A7C057CE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16624 pso_num=25 pso_serial#=152 2025-06-21 17:23:47.879697 :A7C057CF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16624 osp_idx=23 osp_ver=163719 osp_pg=0 (spawn #308522) 2025-06-21 17:23:47.879699 :A7C057D0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 17:23:47.879699 :A7C057D1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=206 time=3502371063 2025-06-21 17:23:47.879700 :A7C057D2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 17:23:47.879700 :A7C057D3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 17:23:47.879700 :A7C057D4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 17:23:47.879701 :A7C057D5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 17:23:47.879701 :A7C057D6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 17:23:47.880192 :A7C057D7: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-06-21 17:23:47.880238 :A7C057DE: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-06-21 17:33:48.711787 :A7C11D85:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 17:33:49.200657 :A7C11DCB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 17:33:49.196 2025-06-21 17:33:49.200659 :A7C11DCC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3624 pso_num=25 pso_serial#=153 2025-06-21 17:33:49.200794 :A7C11DCD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3624 osp_idx=23 osp_ver=163720 osp_pg=0 (spawn #308544) 2025-06-21 17:33:49.200795 :A7C11DCE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 17:33:49.200795 :A7C11DCF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=207 time=3502972391 2025-06-21 17:33:49.200796 :A7C11DD0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 17:33:49.200796 :A7C11DD1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 17:33:49.200796 :A7C11DD2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 17:33:49.200797 :A7C11DD3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 17:33:49.200797 :A7C11DD4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 17:33:49.201292 :A7C11DD5: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-06-21 17:33:49.201337 :A7C11DDF: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-06-21 17:43:50.052085 :A7C1E3B8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 17:43:50.478569 :A7C1E3F2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 17:43:50.474 2025-06-21 17:43:50.478572 :A7C1E3F3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14216 pso_num=25 pso_serial#=154 2025-06-21 17:43:50.478720 :A7C1E3F4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14216 osp_idx=23 osp_ver=163721 osp_pg=0 (spawn #308567) 2025-06-21 17:43:50.478721 :A7C1E3F5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 17:43:50.478721 :A7C1E3F6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=208 time=3503573672 2025-06-21 17:43:50.478722 :A7C1E3F7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 17:43:50.478722 :A7C1E3F8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 17:43:50.478722 :A7C1E3F9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 17:43:50.478723 :A7C1E3FA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 17:43:50.478723 :A7C1E3FB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 17:43:50.479233 :A7C1E3FC: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-06-21 17:43:50.479279 :A7C1E404: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-06-21 17:53:51.318089 :A7C2A8F2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 17:53:51.451128 :A7C2A901:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 17:53:51.442 2025-06-21 17:53:51.451130 :A7C2A902:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16700 pso_num=25 pso_serial#=155 2025-06-21 17:53:51.451276 :A7C2A903:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16700 (legacy spawn) 2025-06-21 17:53:51.457749 :A7C2A904:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 17:53:51.457922 :A7C2A90A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 17:53:51.458770 :A7C2A90E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 17:53:51.822620 :A7C2A94B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 17:53:51.817 2025-06-21 17:53:51.822622 :A7C2A94C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18092 pso_num=25 pso_serial#=156 2025-06-21 17:53:51.822765 :A7C2A94D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18092 osp_idx=23 osp_ver=163723 osp_pg=0 (spawn #308590) 2025-06-21 17:53:51.822765 :A7C2A94E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 17:53:51.822766 :A7C2A94F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=209 time=3504175016 2025-06-21 17:53:51.822766 :A7C2A950:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 17:53:51.822766 :A7C2A951:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 17:53:51.822767 :A7C2A952:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 17:53:51.822767 :A7C2A953:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 17:53:51.822767 :A7C2A954:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 17:53:51.823269 :A7C2A955: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-06-21 17:53:51.823315 :A7C2A95C: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-06-21 18:03:52.693174 :A7C370FF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 18:03:53.145382 :A7C37147:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 18:03:53.141 2025-06-21 18:03:53.145384 :A7C37148:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15624 pso_num=25 pso_serial#=157 2025-06-21 18:03:53.145528 :A7C37149:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15624 osp_idx=23 osp_ver=163724 osp_pg=0 (spawn #308615) 2025-06-21 18:03:53.145528 :A7C3714A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 18:03:53.145529 :A7C3714B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=210 time=3504776329 2025-06-21 18:03:53.145529 :A7C3714C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 18:03:53.145530 :A7C3714D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 18:03:53.145530 :A7C3714E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 18:03:53.145530 :A7C3714F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 18:03:53.145530 :A7C37150:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 18:03:53.146030 :A7C37151: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-06-21 18:03:53.146093 :A7C3715A: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: 15624, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de