Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525180457\orcl_w000_12500_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525180457\orcl_w000_12500_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:37319M/63366M, Ph+PgF:44369M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 12500, image: ORACLE.EXE (W000) *** 2025-05-25 18:04:57.495 *** SESSION ID:(193.18805) 2025-05-25 18:04:57.495 *** 2025-05-25 18:04:57.495 Process diagnostic dump for ORACLE.EXE (W000), OS id=12500, pid: 24, proc_ser: 228, sid: 193, sess_ser: 18805 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=1.796693 sec, exc=1.796693 sec, total=1.796693 sec wait times: max=5.000000 sec, heur=1 min 41 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000026 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=5.000163 sec, exc=5.000163 sec, total=5.000163 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=19 seq_num=20 snap_id=1 wait times: snap=5.004614 sec, exc=5.004614 sec, total=5.004614 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=18 seq_num=19 snap_id=1 wait times: snap=5.009223 sec, exc=5.009223 sec, total=5.009223 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=5.005151 sec, exc=5.005151 sec, total=5.005151 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=5.003338 sec, exc=5.003338 sec, total=5.003338 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=15 seq_num=16 snap_id=1 wait times: snap=5.000250 sec, exc=5.000250 sec, total=5.000250 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=5.013440 sec, exc=5.013440 sec, total=5.013440 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=5.012240 sec, exc=5.012240 sec, total=5.012240 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=5.014728 sec, exc=5.014728 sec, total=5.014728 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=5.015225 sec, exc=5.015225 sec, total=5.015225 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 193 serial 18805 --------------------------------------------------- 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 --------------------------------------------------- [102 samples, 18:03:15 - 18:04:57] idle wait at each sample [session created at: 18:03:15] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 18:04:57.495 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 12500, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 15:42:55.934041 :9BDC3AD9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 15:42:56.204310 :9BDC3B0E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 15:42:56.200 2025-05-25 15:42:56.204312 :9BDC3B0F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12156 pso_num=24 pso_serial#=215 2025-05-25 15:42:56.204447 :9BDC3B10:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12156 osp_idx=22 osp_ver=319474 osp_pg=0 (spawn #218288) 2025-05-25 15:42:56.204448 :9BDC3B11:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 15:42:56.204448 :9BDC3B12:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=57 time=1163548454 2025-05-25 15:42:56.204449 :9BDC3B13:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 15:42:56.204449 :9BDC3B14:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 15:42:56.204449 :9BDC3B15:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 15:42:56.204449 :9BDC3B16:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 15:42:56.204450 :9BDC3B17:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 15:42:56.204948 :9BDC3B18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 15:42:56.204994 :9BDC3B1F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 15:52:57.180296 :9BDD00B0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 15:52:57.606687 :9BDD00E9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 15:52:57.602 2025-05-25 15:52:57.606689 :9BDD00EA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12432 pso_num=24 pso_serial#=216 2025-05-25 15:52:57.606827 :9BDD00EB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12432 osp_idx=22 osp_ver=319475 osp_pg=0 (spawn #218311) 2025-05-25 15:52:57.606828 :9BDD00EC:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 15:52:57.606828 :9BDD00ED:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=58 time=1164149860 2025-05-25 15:52:57.606829 :9BDD00EE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 15:52:57.606829 :9BDD00EF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 15:52:57.606829 :9BDD00F0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 15:52:57.606830 :9BDD00F1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 15:52:57.606830 :9BDD00F2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 15:52:57.607315 :9BDD00F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 15:52:57.607366 :9BDD00FB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 16:02:58.577195 :9BDDC8E0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 16:02:58.878860 :9BDDC922:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 16:02:58.874 2025-05-25 16:02:58.878862 :9BDDC923:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9508 pso_num=24 pso_serial#=217 2025-05-25 16:02:58.879034 :9BDDC924:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9508 osp_idx=22 osp_ver=319476 osp_pg=0 (spawn #218337) 2025-05-25 16:02:58.879035 :9BDDC925:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 16:02:58.879036 :9BDDC926:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=59 time=1164751125 2025-05-25 16:02:58.879036 :9BDDC927:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 16:02:58.879036 :9BDDC928:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 16:02:58.879036 :9BDDC929:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 16:02:58.879036 :9BDDC92A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 16:02:58.879037 :9BDDC92B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 16:02:58.879533 :9BDDC92C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 16:02:58.879579 :9BDDC936:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 16:03:13.910478 :9BDDCE1C:db_trace:ksl2.c@2477:kslwtbctx(): [10005:24:193] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-05-25 16:03:13.910509 :9BDDCE21:db_trace:ksl2.c@2611:kslwtectx(): [10005:24:193] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-05-25 16:03:13.910510 :9BDDCE22:db_trace:ksl2.c@2619:kslwtectx(): [10005:24:193] KSL WAIT END wait times (usecs) - snap=30, exc=30, tot=30 2025-05-25 16:12:59.888925 :9BDE9939:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 16:13:00.206128 :9BDE9970:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 16:13:00.201 2025-05-25 16:13:00.206130 :9BDE9971:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14072 pso_num=24 pso_serial#=218 2025-05-25 16:13:00.206271 :9BDE9972:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14072 osp_idx=22 osp_ver=319477 osp_pg=0 (spawn #218360) 2025-05-25 16:13:00.206272 :9BDE9973:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 16:13:00.206273 :9BDE9974:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=60 time=1165352454 2025-05-25 16:13:00.206273 :9BDE9975:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 16:13:00.206274 :9BDE9976:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 16:13:00.206274 :9BDE9977:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 16:13:00.206274 :9BDE9978:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 16:13:00.206275 :9BDE9979:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 16:13:00.206760 :9BDE997A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 16:13:00.206809 :9BDE9981:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 16:23:01.173483 :9BDF5FD4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 16:23:01.522077 :9BDF600C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 16:23:01.517 2025-05-25 16:23:01.522079 :9BDF600D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13244 pso_num=24 pso_serial#=219 2025-05-25 16:23:01.522217 :9BDF600E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13244 osp_idx=22 osp_ver=319478 osp_pg=0 (spawn #218381) 2025-05-25 16:23:01.522218 :9BDF600F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 16:23:01.522218 :9BDF6010:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=61 time=1165953782 2025-05-25 16:23:01.522218 :9BDF6011:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 16:23:01.522219 :9BDF6012:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 16:23:01.522219 :9BDF6013:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 16:23:01.522219 :9BDF6014:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 16:23:01.522220 :9BDF6015:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 16:23:01.522717 :9BDF6016:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 16:23:01.522766 :9BDF601C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 16:29:47.480289 :9BDFE5D7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 16:33:02.867028 :9BE02686:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 16:43:03.790880 :9BE0EBC0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 16:43:04.279785 :9BE0EC2B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 16:43:04.274 2025-05-25 16:43:04.279787 :9BE0EC2C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13440 pso_num=24 pso_serial#=220 2025-05-25 16:43:04.279929 :9BE0EC2D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13440 osp_idx=22 osp_ver=319479 osp_pg=0 (spawn #218425) 2025-05-25 16:43:04.279930 :9BE0EC2E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 16:43:04.279930 :9BE0EC2F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=62 time=1167156532 2025-05-25 16:43:04.279931 :9BE0EC30:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 16:43:04.279931 :9BE0EC31:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 16:43:04.279931 :9BE0EC32:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 16:43:04.279932 :9BE0EC33:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 16:43:04.279932 :9BE0EC34:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 16:43:04.280421 :9BE0EC35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 16:43:04.280466 :9BE0EC3C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 16:53:05.232746 :9BE1B12F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 16:53:05.628028 :9BE1B164:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 16:53:05.623 2025-05-25 16:53:05.628030 :9BE1B165:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10584 pso_num=24 pso_serial#=221 2025-05-25 16:53:05.628162 :9BE1B166:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10584 osp_idx=22 osp_ver=319480 osp_pg=0 (spawn #218448) 2025-05-25 16:53:05.628162 :9BE1B167:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 16:53:05.628163 :9BE1B168:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=63 time=1167757875 2025-05-25 16:53:05.628163 :9BE1B169:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 16:53:05.628163 :9BE1B16A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 16:53:05.628164 :9BE1B16B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 16:53:05.628164 :9BE1B16C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 16:53:05.628164 :9BE1B16D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 16:53:05.628656 :9BE1B16E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 16:53:05.628702 :9BE1B175:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 17:03:06.582208 :9BE27E57:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 17:03:07.102555 :9BE27E8E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 17:03:07.098 2025-05-25 17:03:07.102558 :9BE27E8F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14136 pso_num=24 pso_serial#=222 2025-05-25 17:03:07.102692 :9BE27E90:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14136 osp_idx=22 osp_ver=319481 osp_pg=0 (spawn #218473) 2025-05-25 17:03:07.102693 :9BE27E91:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 17:03:07.102693 :9BE27E92:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=64 time=1168359360 2025-05-25 17:03:07.102694 :9BE27E93:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 17:03:07.102694 :9BE27E94:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 17:03:07.102695 :9BE27E95:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 17:03:07.102695 :9BE27E96:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 17:03:07.102695 :9BE27E97:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 17:03:07.103171 :9BE27E98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 17:03:07.103217 :9BE27E9F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 17:13:08.044582 :9BE34DE9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 17:13:08.518067 :9BE34E2F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 17:13:08.513 2025-05-25 17:13:08.518069 :9BE34E30:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13544 pso_num=24 pso_serial#=223 2025-05-25 17:13:08.518206 :9BE34E31:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13544 osp_idx=22 osp_ver=319482 osp_pg=0 (spawn #218496) 2025-05-25 17:13:08.518206 :9BE34E32:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 17:13:08.518207 :9BE34E33:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=65 time=1168960766 2025-05-25 17:13:08.518207 :9BE34E34:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 17:13:08.518208 :9BE34E35:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 17:13:08.518208 :9BE34E36:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 17:13:08.518208 :9BE34E37:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 17:13:08.518209 :9BE34E38:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 17:13:08.518781 :9BE34E39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 17:13:08.518825 :9BE34E41:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 17:23:09.398943 :9BE41379:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 17:23:09.966168 :9BE413B1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 17:23:09.961 2025-05-25 17:23:09.966171 :9BE413B2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10700 pso_num=24 pso_serial#=224 2025-05-25 17:23:09.966312 :9BE413B3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10700 osp_idx=22 osp_ver=319483 osp_pg=0 (spawn #218519) 2025-05-25 17:23:09.966313 :9BE413B4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 17:23:09.966313 :9BE413B5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=66 time=1169562219 2025-05-25 17:23:09.966314 :9BE413B6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 17:23:09.966314 :9BE413B7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 17:23:09.966314 :9BE413B8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 17:23:09.966315 :9BE413B9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 17:23:09.966316 :9BE413BA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 17:23:09.966808 :9BE413BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 17:23:09.966855 :9BE413C3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 17:33:10.866964 :9BE4DB2B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 17:33:11.449917 :9BE4DB72:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 17:33:11.445 2025-05-25 17:33:11.449919 :9BE4DB73:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12920 pso_num=24 pso_serial#=225 2025-05-25 17:33:11.450058 :9BE4DB74:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12920 osp_idx=22 osp_ver=319484 osp_pg=0 (spawn #218542) 2025-05-25 17:33:11.450058 :9BE4DB75:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 17:33:11.450059 :9BE4DB76:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=67 time=1170163704 2025-05-25 17:33:11.450059 :9BE4DB77:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 17:33:11.450059 :9BE4DB78:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 17:33:11.450060 :9BE4DB79:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 17:33:11.450060 :9BE4DB7A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 17:33:11.450060 :9BE4DB7B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 17:33:11.450555 :9BE4DB7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 17:33:11.450614 :9BE4DB86:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 17:43:12.442877 :9BE5A3C2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 17:43:12.863893 :9BE5A405:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 17:43:12.859 2025-05-25 17:43:12.863895 :9BE5A406:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14976 pso_num=24 pso_serial#=226 2025-05-25 17:43:12.864033 :9BE5A407:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14976 osp_idx=22 osp_ver=319485 osp_pg=0 (spawn #218564) 2025-05-25 17:43:12.864033 :9BE5A408:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 17:43:12.864034 :9BE5A409:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=68 time=1170765125 2025-05-25 17:43:12.864034 :9BE5A40A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 17:43:12.864035 :9BE5A40B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 17:43:12.864035 :9BE5A40C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 17:43:12.864035 :9BE5A40D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 17:43:12.864036 :9BE5A40E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 17:43:12.864516 :9BE5A40F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 17:43:12.864560 :9BE5A416:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 17:53:13.776825 :9BE66D8D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 17:53:14.156584 :9BE66DC5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 17:53:14.151 2025-05-25 17:53:14.156586 :9BE66DC6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9960 pso_num=24 pso_serial#=227 2025-05-25 17:53:14.156727 :9BE66DC7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9960 osp_idx=22 osp_ver=319486 osp_pg=0 (spawn #218587) 2025-05-25 17:53:14.156728 :9BE66DC8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 17:53:14.156728 :9BE66DC9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=69 time=1171366407 2025-05-25 17:53:14.156728 :9BE66DCA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 17:53:14.156729 :9BE66DCB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 17:53:14.156729 :9BE66DCC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 17:53:14.156729 :9BE66DCD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 17:53:14.156730 :9BE66DCE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 17:53:14.157257 :9BE66DCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 17:53:14.157313 :9BE66DD9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 18:03:15.089955 :9BE738B8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 18:03:15.547778 :9BE73909:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 18:03:15.542 2025-05-25 18:03:15.547780 :9BE7390A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12500 pso_num=24 pso_serial#=228 2025-05-25 18:03:15.547922 :9BE7390B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12500 osp_idx=22 osp_ver=319487 osp_pg=0 (spawn #218612) 2025-05-25 18:03:15.547922 :9BE7390C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 18:03:15.547923 :9BE7390D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=70 time=1171967797 2025-05-25 18:03:15.547923 :9BE7390E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 18:03:15.547924 :9BE7390F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 18:03:15.547924 :9BE73910:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 18:03:15.547924 :9BE73911:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 18:03:15.547924 :9BE73912:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 18:03:15.548446 :9BE73913:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 18:03:15.548501 :9BE7391D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 12500, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de