Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250730220006\orcl_w000_14932_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250730220006\orcl_w000_14932_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:35867M/63366M, Ph+PgF:41532M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 14932, image: ORACLE.EXE (W000) *** 2025-07-30 22:00:06.972 *** SESSION ID:(177.65461) 2025-07-30 22:00:06.972 *** 2025-07-30 22:00:06.972 Process diagnostic dump for ORACLE.EXE (W000), OS id=14932, pid: 22, proc_ser: 182, sid: 177, sess_ser: 65461 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=83 seq_num=84 snap_id=1 wait times: snap=3.266220 sec, exc=3.266220 sec, total=3.266220 sec wait times: max=5.000000 sec, heur=2 min 38 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.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=82 seq_num=83 snap_id=1 wait times: snap=5.004626 sec, exc=5.004626 sec, total=5.004626 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=81 seq_num=82 snap_id=1 wait times: snap=5.004622 sec, exc=5.004622 sec, total=5.004622 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: 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.002797 sec, exc=5.002797 sec, total=5.002797 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=79 seq_num=80 snap_id=1 wait times: snap=5.001294 sec, exc=5.001294 sec, total=5.001294 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 4: 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.009324 sec, exc=5.009324 sec, total=5.009324 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 5: 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.004616 sec, exc=5.004616 sec, total=5.004616 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: 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.004616 sec, exc=5.004616 sec, total=5.004616 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=75 seq_num=76 snap_id=1 wait times: snap=5.015246 sec, exc=5.015246 sec, total=5.015246 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: 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.003340 sec, exc=5.003340 sec, total=5.003340 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=73 seq_num=74 snap_id=1 wait times: snap=5.000151 sec, exc=5.000151 sec, total=5.000151 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time Sampled Session History of session 177 serial 65461 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 21:58:06 - 22:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-30 22:00:06.972 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 14932, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-30 19:23:00.471448 :B91DD5FB: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-07-30 19:23:00.471501 :B91DD605: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-07-30 19:33:01.211077 :B91EA3D5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 19:33:01.637914 :B91EA405:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 19:33:01.632 2025-07-30 19:33:01.637917 :B91EA406:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19620 pso_num=22 pso_serial#=169 2025-07-30 19:33:01.638052 :B91EA407:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19620 osp_idx=0 osp_ver=468676 osp_pg=0 (spawn #438817) 2025-07-30 19:33:01.638053 :B91EA408:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 19:33:01.638053 :B91EA409:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=116 time=2584757533 2025-07-30 19:33:01.638053 :B91EA40A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 19:33:01.638054 :B91EA40B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 19:33:01.638054 :B91EA40C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 19:33:01.638054 :B91EA40D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 19:33:01.638055 :B91EA40E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 19:33:01.638598 :B91EA40F: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-07-30 19:33:01.638651 :B91EA418: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-07-30 19:43:02.497459 :B91F7146:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 19:43:02.893049 :B91F7169:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 19:43:02.888 2025-07-30 19:43:02.893051 :B91F716A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5196 pso_num=22 pso_serial#=170 2025-07-30 19:43:02.893197 :B91F716B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=5196 osp_idx=0 osp_ver=468677 osp_pg=0 (spawn #438840) 2025-07-30 19:43:02.893199 :B91F716C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 19:43:02.893199 :B91F716D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=117 time=2585358783 2025-07-30 19:43:02.893199 :B91F716E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 19:43:02.893200 :B91F716F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 19:43:02.893200 :B91F7170:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 19:43:02.893200 :B91F7171:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 19:43:02.893201 :B91F7172:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 19:43:02.893712 :B91F7173: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-07-30 19:43:02.893761 :B91F717A: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-07-30 19:53:03.804217 :B9203E21:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 19:53:04.199697 :B9203E4F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 19:53:04.195 2025-07-30 19:53:04.199700 :B9203E50:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5020 pso_num=22 pso_serial#=171 2025-07-30 19:53:04.199840 :B9203E51:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=5020 osp_idx=0 osp_ver=468678 osp_pg=0 (spawn #438863) 2025-07-30 19:53:04.199841 :B9203E52:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 19:53:04.199842 :B9203E53:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=118 time=2585960095 2025-07-30 19:53:04.199842 :B9203E54:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 19:53:04.199842 :B9203E55:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 19:53:04.199843 :B9203E56:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 19:53:04.199843 :B9203E57:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 19:53:04.199843 :B9203E58:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 19:53:04.200328 :B9203E59: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-07-30 19:53:04.200384 :B9203E62: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-07-30 19:57:27.820846 :B9209780: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-07-30 20:03:05.384714 :B9210CF1: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-07-30 20:13:06.270172 :B921E363:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 20:13:06.571933 :B921E393:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 20:13:06.566 2025-07-30 20:13:06.571935 :B921E394:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19720 pso_num=22 pso_serial#=172 2025-07-30 20:13:06.572076 :B921E395:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19720 osp_idx=0 osp_ver=468679 osp_pg=0 (spawn #438911) 2025-07-30 20:13:06.572077 :B921E396:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 20:13:06.572077 :B921E397:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=119 time=2587162470 2025-07-30 20:13:06.572078 :B921E398:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 20:13:06.572078 :B921E399:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 20:13:06.572079 :B921E39A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 20:13:06.572079 :B921E39B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 20:13:06.572079 :B921E39C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 20:13:06.572587 :B921E39D: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-07-30 20:13:06.572633 :B921E3A4: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-07-30 20:23:07.407930 :B922B04A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 20:23:07.694189 :B922B06E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 20:23:07.689 2025-07-30 20:23:07.694191 :B922B06F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19584 pso_num=22 pso_serial#=173 2025-07-30 20:23:07.694332 :B922B070:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19584 osp_idx=0 osp_ver=468680 osp_pg=0 (spawn #438934) 2025-07-30 20:23:07.694333 :B922B071:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 20:23:07.694333 :B922B072:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=120 time=2587763595 2025-07-30 20:23:07.694333 :B922B073:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 20:23:07.694334 :B922B074:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 20:23:07.694334 :B922B075:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 20:23:07.694334 :B922B076:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 20:23:07.694334 :B922B077:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 20:23:07.694845 :B922B078: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-07-30 20:23:07.694887 :B922B080: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-07-30 20:33:08.542834 :B9237D09:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 20:33:08.859986 :B9237D62:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 20:33:08.855 2025-07-30 20:33:08.859988 :B9237D63:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5020 pso_num=22 pso_serial#=174 2025-07-30 20:33:08.860128 :B9237D64:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=5020 osp_idx=0 osp_ver=468681 osp_pg=0 (spawn #438956) 2025-07-30 20:33:08.860128 :B9237D65:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 20:33:08.860129 :B9237D66:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=121 time=2588364751 2025-07-30 20:33:08.860129 :B9237D67:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 20:33:08.860129 :B9237D68:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 20:33:08.860130 :B9237D69:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 20:33:08.860130 :B9237D6A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 20:33:08.860130 :B9237D6B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 20:33:08.860615 :B9237D6C: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-07-30 20:33:08.860661 :B9237D73: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-07-30 20:43:09.815639 :B92449F5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 20:43:10.070435 :B9244A42:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 20:43:10.066 2025-07-30 20:43:10.070437 :B9244A43:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18696 pso_num=22 pso_serial#=175 2025-07-30 20:43:10.070578 :B9244A44:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18696 osp_idx=0 osp_ver=468682 osp_pg=0 (spawn #438979) 2025-07-30 20:43:10.070578 :B9244A45:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 20:43:10.070579 :B9244A46:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=122 time=2588965970 2025-07-30 20:43:10.070579 :B9244A47:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 20:43:10.070579 :B9244A48:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 20:43:10.070580 :B9244A49:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 20:43:10.070580 :B9244A4A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 20:43:10.070581 :B9244A4B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 20:43:10.071066 :B9244A4C: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-07-30 20:43:10.071112 :B9244A54: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-07-30 20:53:10.951906 :B925177E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 20:53:11.347512 :B92517AF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 20:53:11.342 2025-07-30 20:53:11.347514 :B92517B0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19616 pso_num=22 pso_serial#=176 2025-07-30 20:53:11.347665 :B92517B1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19616 osp_idx=0 osp_ver=468683 osp_pg=0 (spawn #439002) 2025-07-30 20:53:11.347666 :B92517B2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 20:53:11.347666 :B92517B3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=123 time=2589567236 2025-07-30 20:53:11.347666 :B92517B4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 20:53:11.347666 :B92517B5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 20:53:11.347666 :B92517B6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 20:53:11.347667 :B92517B7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 20:53:11.347667 :B92517B8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 20:53:11.348172 :B92517B9: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-07-30 20:53:11.348223 :B92517C0: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-07-30 21:03:12.113944 :B925E5FE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 21:03:12.525113 :B925E624:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 21:03:12.520 2025-07-30 21:03:12.525115 :B925E625:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12604 pso_num=22 pso_serial#=177 2025-07-30 21:03:12.525258 :B925E626:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12604 osp_idx=0 osp_ver=468684 osp_pg=0 (spawn #439027) 2025-07-30 21:03:12.525259 :B925E627:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 21:03:12.525259 :B925E628:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=124 time=2590168423 2025-07-30 21:03:12.525259 :B925E629:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 21:03:12.525260 :B925E62A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 21:03:12.525260 :B925E62B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 21:03:12.525260 :B925E62C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 21:03:12.525261 :B925E62D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 21:03:12.525754 :B925E62E: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-07-30 21:03:12.525801 :B925E635: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-07-30 21:13:13.389963 :B926BBA7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 21:13:13.644845 :B926BBD8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 21:13:13.639 2025-07-30 21:13:13.644848 :B926BBD9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6792 pso_num=22 pso_serial#=178 2025-07-30 21:13:13.644990 :B926BBDA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6792 osp_idx=0 osp_ver=468685 osp_pg=0 (spawn #439050) 2025-07-30 21:13:13.644990 :B926BBDB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 21:13:13.644991 :B926BBDC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=125 time=2590769533 2025-07-30 21:13:13.644991 :B926BBDD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 21:13:13.644991 :B926BBDE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 21:13:13.644992 :B926BBDF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 21:13:13.644992 :B926BBE0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 21:13:13.644992 :B926BBE1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 21:13:13.645498 :B926BBE2: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-07-30 21:13:13.645553 :B926BBEC: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-07-30 21:23:14.465407 :B927870B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 21:23:14.860845 :B927873C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 21:23:14.855 2025-07-30 21:23:14.860847 :B927873D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14024 pso_num=22 pso_serial#=179 2025-07-30 21:23:14.860990 :B927873E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14024 osp_idx=0 osp_ver=468686 osp_pg=0 (spawn #439073) 2025-07-30 21:23:14.860991 :B927873F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 21:23:14.860991 :B9278740:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=126 time=2591370751 2025-07-30 21:23:14.860992 :B9278741:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 21:23:14.860992 :B9278742:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 21:23:14.860992 :B9278743:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 21:23:14.860993 :B9278744:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 21:23:14.860993 :B9278745:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 21:23:14.861497 :B9278746: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-07-30 21:23:14.861543 :B927874E: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-07-30 21:33:15.677739 :B92850F4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 21:33:16.042108 :B928512D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 21:33:16.037 2025-07-30 21:33:16.042110 :B928512E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20068 pso_num=22 pso_serial#=180 2025-07-30 21:33:16.042251 :B928512F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20068 osp_idx=0 osp_ver=468687 osp_pg=0 (spawn #439095) 2025-07-30 21:33:16.042252 :B9285130:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 21:33:16.042252 :B9285131:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=127 time=2591971939 2025-07-30 21:33:16.042253 :B9285132:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 21:33:16.042253 :B9285133:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 21:33:16.042253 :B9285134:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 21:33:16.042254 :B9285135:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 21:33:16.042254 :B9285136:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 21:33:16.042768 :B9285137: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-07-30 21:33:16.042812 :B928513F: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-07-30 21:43:16.812754 :B9291A49:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 21:43:17.255075 :B9291A77:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 21:43:17.250 2025-07-30 21:43:17.255077 :B9291A78:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17124 pso_num=22 pso_serial#=181 2025-07-30 21:43:17.255220 :B9291A79:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17124 osp_idx=0 osp_ver=468688 osp_pg=0 (spawn #439118) 2025-07-30 21:43:17.255221 :B9291A7A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 21:43:17.255221 :B9291A7B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=128 time=2592573142 2025-07-30 21:43:17.255221 :B9291A7C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 21:43:17.255222 :B9291A7D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 21:43:17.255222 :B9291A7E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 21:43:17.255222 :B9291A7F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 21:43:17.255223 :B9291A80:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 21:43:17.255723 :B9291A81: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-07-30 21:43:17.255770 :B9291A88: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-07-30 21:53:18.056983 :B929E306:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-30 21:53:18.468073 :B929E331:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-30 21:53:18.463 2025-07-30 21:53:18.468075 :B929E332:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14932 pso_num=22 pso_serial#=182 2025-07-30 21:53:18.468211 :B929E333:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14932 osp_idx=0 osp_ver=468689 osp_pg=0 (spawn #439141) 2025-07-30 21:53:18.468212 :B929E334:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 21:53:18.468212 :B929E335:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=129 time=2593174361 2025-07-30 21:53:18.468212 :B929E336:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 21:53:18.468213 :B929E337:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 21:53:18.468213 :B929E338:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 21:53:18.468213 :B929E339:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 21:53:18.468214 :B929E33A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 21:53:18.468709 :B929E33B: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-07-30 21:53:18.468753 :B929E343: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-07-30 21:57:28.509880 :B92A3689: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 14932, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de