Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511082603\orcl_w000_10564_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511082603\orcl_w000_10564_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:37480M/63366M, Ph+PgF:44816M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 10564, image: ORACLE.EXE (W000) *** 2025-05-11 08:26:03.144 *** SESSION ID:(177.21633) 2025-05-11 08:26:03.144 *** 2025-05-11 08:26:03.144 Process diagnostic dump for ORACLE.EXE (W000), OS id=10564, pid: 22, proc_ser: 137, sid: 177, sess_ser: 21633 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=115 seq_num=116 snap_id=1 wait times: snap=4.439062 sec, exc=4.439062 sec, total=4.439062 sec wait times: max=5.000000 sec, heur=7 min 25 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=114 seq_num=115 snap_id=1 wait times: snap=5.013778 sec, exc=5.013778 sec, total=5.013778 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=113 seq_num=114 snap_id=1 wait times: snap=5.004629 sec, exc=5.004629 sec, total=5.004629 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=112 seq_num=113 snap_id=1 wait times: snap=5.009723 sec, exc=5.009723 sec, total=5.009723 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=111 seq_num=112 snap_id=1 wait times: snap=5.003207 sec, exc=5.003207 sec, total=5.003207 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=110 seq_num=111 snap_id=1 wait times: snap=5.011018 sec, exc=5.011018 sec, total=5.011018 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=109 seq_num=110 snap_id=1 wait times: snap=5.012704 sec, exc=5.012704 sec, total=5.012704 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=108 seq_num=109 snap_id=1 wait times: snap=5.009194 sec, exc=5.009194 sec, total=5.009194 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=107 seq_num=108 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 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=106 seq_num=107 snap_id=1 wait times: snap=5.015256 sec, exc=5.015256 sec, total=5.015256 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=105 seq_num=106 snap_id=1 wait times: snap=5.003223 sec, exc=5.003223 sec, total=5.003223 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 177 serial 21633 --------------------------------------------------- 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, 08:24:03 - 08:26:03] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-11 08:26:03.144 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 10564, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-11 05:56:17.717375 :9587FA8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 05:56:17.717425 :9587FA96:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 06:06:18.640424 :9588D864:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 06:06:18.973236 :9588D894:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 06:06:18.968 2025-05-11 06:06:18.973238 :9588D895:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10288 pso_num=22 pso_serial#=123 2025-05-11 06:06:18.973379 :9588D896:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10288 osp_idx=0 osp_ver=178368 osp_pg=0 (spawn #170222) 2025-05-11 06:06:18.973380 :9588D897:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 06:06:18.973381 :9588D898:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=53 time=4214356140 2025-05-11 06:06:18.973381 :9588D899:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 06:06:18.973381 :9588D89A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 06:06:18.973382 :9588D89B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 06:06:18.973382 :9588D89C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 06:06:18.973383 :9588D89D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 06:06:18.973861 :9588D89E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 06:06:18.973907 :9588D8A6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 06:16:19.866219 :9589A2D9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 06:16:20.308355 :9589A306:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 06:16:20.303 2025-05-11 06:16:20.308357 :9589A307:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10948 pso_num=22 pso_serial#=124 2025-05-11 06:16:20.308488 :9589A308:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10948 osp_idx=0 osp_ver=178369 osp_pg=0 (spawn #170245) 2025-05-11 06:16:20.308489 :9589A309:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 06:16:20.308489 :9589A30A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=54 time=4214957468 2025-05-11 06:16:20.308490 :9589A30B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 06:16:20.308490 :9589A30C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 06:16:20.308490 :9589A30D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 06:16:20.308491 :9589A30E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 06:16:20.308491 :9589A30F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 06:16:20.309030 :9589A310:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 06:16:20.309079 :9589A316:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 06:18:28.803321 :9589CD4B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-11 06:26:21.701741 :958A6A6E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-11 06:36:22.590083 :958B317D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 06:36:23.141621 :958B31C8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 06:36:23.137 2025-05-11 06:36:23.141623 :958B31C9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11408 pso_num=22 pso_serial#=125 2025-05-11 06:36:23.141761 :958B31CA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11408 osp_idx=0 osp_ver=178370 osp_pg=0 (spawn #170289) 2025-05-11 06:36:23.141762 :958B31CB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 06:36:23.141763 :958B31CC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=55 time=4216160312 2025-05-11 06:36:23.141763 :958B31CD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 06:36:23.141764 :958B31CE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 06:36:23.141764 :958B31CF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 06:36:23.141765 :958B31D0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 06:36:23.141765 :958B31D1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 06:36:23.142256 :958B31D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 06:36:23.142298 :958B31D9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 06:46:24.072307 :958BF821:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 06:46:24.576923 :958BF878:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 06:46:24.572 2025-05-11 06:46:24.576925 :958BF879:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11300 pso_num=22 pso_serial#=126 2025-05-11 06:46:24.577058 :958BF87A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11300 osp_idx=0 osp_ver=178371 osp_pg=0 (spawn #170312) 2025-05-11 06:46:24.577059 :958BF87B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 06:46:24.577060 :958BF87C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=56 time=4216761750 2025-05-11 06:46:24.577060 :958BF87D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 06:46:24.577060 :958BF87E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 06:46:24.577061 :958BF87F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 06:46:24.577061 :958BF880:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 06:46:24.577061 :958BF881:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 06:46:24.577599 :958BF882:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 06:46:24.577653 :958BF88B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 06:56:25.482630 :958CBFEB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 06:56:25.987368 :958CC037:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 06:56:25.983 2025-05-11 06:56:25.987370 :958CC038:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11512 pso_num=22 pso_serial#=127 2025-05-11 06:56:25.987506 :958CC039:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11512 osp_idx=0 osp_ver=178372 osp_pg=0 (spawn #170335) 2025-05-11 06:56:25.987506 :958CC03A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 06:56:25.987507 :958CC03B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=57 time=4217363156 2025-05-11 06:56:25.987507 :958CC03C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 06:56:25.987508 :958CC03D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 06:56:25.987508 :958CC03E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 06:56:25.987508 :958CC03F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 06:56:25.987509 :958CC040:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 06:56:25.988002 :958CC041:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 06:56:25.988051 :958CC049:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 07:06:26.882960 :958D8F5F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 07:06:27.356416 :958D8F83:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 07:06:27.351 2025-05-11 07:06:27.356418 :958D8F84:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12448 pso_num=22 pso_serial#=128 2025-05-11 07:06:27.356602 :958D8F85:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12448 osp_idx=0 osp_ver=178373 osp_pg=0 (spawn #170362) 2025-05-11 07:06:27.356603 :958D8F86:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 07:06:27.356604 :958D8F87:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=58 time=4217964515 2025-05-11 07:06:27.356604 :958D8F88:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 07:06:27.356605 :958D8F89:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 07:06:27.356606 :958D8F8A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 07:06:27.356606 :958D8F8B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 07:06:27.356606 :958D8F8C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 07:06:27.357104 :958D8F8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 07:06:27.357149 :958D8F95:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 07:16:28.262723 :958E57AA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 07:16:28.829895 :958E57F8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 07:16:28.825 2025-05-11 07:16:28.829897 :958E57F9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12876 pso_num=22 pso_serial#=129 2025-05-11 07:16:28.830031 :958E57FA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12876 osp_idx=0 osp_ver=178374 osp_pg=0 (spawn #170385) 2025-05-11 07:16:28.830032 :958E57FB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 07:16:28.830032 :958E57FC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=59 time=4218566000 2025-05-11 07:16:28.830033 :958E57FD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 07:16:28.830033 :958E57FE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 07:16:28.830034 :958E57FF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 07:16:28.830034 :958E5800:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 07:16:28.830034 :958E5801:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 07:16:28.830587 :958E5802:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 07:16:28.830638 :958E5809:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 07:26:29.769363 :958F19B6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 07:26:30.242774 :958F19FA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 07:26:30.238 2025-05-11 07:26:30.242776 :958F19FB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13048 pso_num=22 pso_serial#=130 2025-05-11 07:26:30.242917 :958F19FC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13048 osp_idx=0 osp_ver=178375 osp_pg=0 (spawn #170408) 2025-05-11 07:26:30.242918 :958F19FD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 07:26:30.242918 :958F19FE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=60 time=4219167406 2025-05-11 07:26:30.242919 :958F19FF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 07:26:30.242919 :958F1A00:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 07:26:30.242920 :958F1A01:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 07:26:30.242920 :958F1A02:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 07:26:30.242920 :958F1A03:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 07:26:30.243423 :958F1A04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 07:26:30.243469 :958F1A0A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 07:36:31.115340 :958FDD7D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 07:36:31.620000 :958FDDD0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 07:36:31.615 2025-05-11 07:36:31.620002 :958FDDD1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12012 pso_num=22 pso_serial#=131 2025-05-11 07:36:31.620135 :958FDDD2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12012 osp_idx=0 osp_ver=178376 osp_pg=0 (spawn #170430) 2025-05-11 07:36:31.620135 :958FDDD3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 07:36:31.620136 :958FDDD4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=61 time=4219768781 2025-05-11 07:36:31.620136 :958FDDD5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 07:36:31.620137 :958FDDD6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 07:36:31.620137 :958FDDD7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 07:36:31.620138 :958FDDD8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 07:36:31.620138 :958FDDD9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 07:36:31.620649 :958FDDDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 07:36:31.620694 :958FDDE4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 07:46:32.479693 :9590A113:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 07:46:32.728331 :9590A116:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 07:46:32.714 2025-05-11 07:46:32.728333 :9590A117:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8784 pso_num=22 pso_serial#=132 2025-05-11 07:46:32.728475 :9590A118:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8784 (legacy spawn) 2025-05-11 07:46:32.734916 :9590A119:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-11 07:46:32.735090 :9590A11E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-11 07:46:32.735880 :9590A123:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 07:46:32.753771 :9590A126:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 07:46:32.745 2025-05-11 07:46:32.753773 :9590A127:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10120 pso_num=22 pso_serial#=133 2025-05-11 07:46:32.753907 :9590A128:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10120 (legacy spawn) 2025-05-11 07:46:32.760361 :9590A129:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-11 07:46:32.760531 :9590A130:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-11 07:46:32.761349 :9590A133:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 07:46:32.890611 :9590A176:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 07:46:32.886 2025-05-11 07:46:32.890613 :9590A177:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12968 pso_num=22 pso_serial#=134 2025-05-11 07:46:32.890759 :9590A178:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12968 osp_idx=0 osp_ver=178379 osp_pg=0 (spawn #170453) 2025-05-11 07:46:32.890760 :9590A179:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 07:46:32.890760 :9590A17A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=62 time=4220370062 2025-05-11 07:46:32.890761 :9590A17B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 07:46:32.890761 :9590A17C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 07:46:32.890761 :9590A17D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 07:46:32.890762 :9590A17E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 07:46:32.890762 :9590A17F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 07:46:32.891248 :9590A180:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 07:46:32.891291 :9590A187:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 07:56:33.770659 :95916498:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 07:56:34.197454 :959164F0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 07:56:34.192 2025-05-11 07:56:34.197456 :959164F1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9932 pso_num=22 pso_serial#=135 2025-05-11 07:56:34.197589 :959164F2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9932 osp_idx=0 osp_ver=178380 osp_pg=0 (spawn #170476) 2025-05-11 07:56:34.197590 :959164F3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 07:56:34.197590 :959164F4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=63 time=4220971359 2025-05-11 07:56:34.197591 :959164F5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 07:56:34.197591 :959164F6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 07:56:34.197591 :959164F7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 07:56:34.197592 :959164F8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 07:56:34.197592 :959164F9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 07:56:34.198075 :959164FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 07:56:34.198120 :95916501:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 08:06:35.217206 :95922FFD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 08:06:35.565783 :95923048:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 08:06:35.561 2025-05-11 08:06:35.565785 :95923049:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12396 pso_num=22 pso_serial#=136 2025-05-11 08:06:35.565922 :9592304A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12396 osp_idx=0 osp_ver=178381 osp_pg=0 (spawn #170503) 2025-05-11 08:06:35.565923 :9592304B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:06:35.565923 :9592304C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=64 time=4221572734 2025-05-11 08:06:35.565924 :9592304D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:06:35.565924 :9592304E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:06:35.565924 :9592304F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:06:35.565925 :95923050:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:06:35.565925 :95923051:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:06:35.566420 :95923052:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 08:06:35.566466 :95923059:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 08:16:36.472221 :9592F4F1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 08:16:36.773758 :9592F53E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 08:16:36.769 2025-05-11 08:16:36.773760 :9592F53F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10564 pso_num=22 pso_serial#=137 2025-05-11 08:16:36.773905 :9592F540:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10564 osp_idx=0 osp_ver=178382 osp_pg=0 (spawn #170526) 2025-05-11 08:16:36.773906 :9592F541:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:16:36.773907 :9592F542:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=65 time=4222173937 2025-05-11 08:16:36.773907 :9592F543:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:16:36.773908 :9592F544:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:16:36.773908 :9592F545:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:16:36.773909 :9592F546:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:16:36.773909 :9592F547:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:16:36.774454 :9592F548:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 08:16:36.774500 :9592F552:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-11 08:18:38.088153 :95931C65:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 10564, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de