Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250520220730\orcl_w000_9492_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250520220730\orcl_w000_9492_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:37378M/63366M, Ph+PgF:44508M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 9492, image: ORACLE.EXE (W000) *** 2025-05-20 22:07:30.110 *** SESSION ID:(177.44167) 2025-05-20 22:07:30.110 *** 2025-05-20 22:07:30.110 Process diagnostic dump for ORACLE.EXE (W000), OS id=9492, pid: 22, proc_ser: 35, sid: 177, sess_ser: 44167 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=120 seq_num=121 snap_id=1 wait times: snap=3.067626 sec, exc=3.067626 sec, total=3.067626 sec wait times: max=5.000000 sec, heur=9 min 59 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000021 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=119 seq_num=120 snap_id=1 wait times: snap=5.000342 sec, exc=5.000342 sec, total=5.000342 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=118 seq_num=119 snap_id=1 wait times: snap=5.011378 sec, exc=5.011378 sec, total=5.011378 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=117 seq_num=118 snap_id=1 wait times: snap=5.004628 sec, exc=5.004628 sec, total=5.004628 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=116 seq_num=117 snap_id=1 wait times: snap=5.012775 sec, exc=5.012775 sec, total=5.012775 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=115 seq_num=116 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.000022 sec of elapsed time 5: 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.008705 sec, exc=5.008705 sec, total=5.008705 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=113 seq_num=114 snap_id=1 wait times: snap=5.000276 sec, exc=5.000276 sec, total=5.000276 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 7: 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.013348 sec, exc=5.013348 sec, total=5.013348 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: 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.003677 sec, exc=5.003677 sec, total=5.003677 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=110 seq_num=111 snap_id=1 wait times: snap=5.011784 sec, exc=5.011784 sec, total=5.011784 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time Sampled Session History of session 177 serial 44167 --------------------------------------------------- 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, 22:05:30 - 22:07:30] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-20 22:07:30.110 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 9492, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-20 19:47:13.593880 :99C24492:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13268 osp_idx=0 osp_ver=216005 osp_pg=0 (spawn #202197) 2025-05-20 19:47:13.593881 :99C24493: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-20 19:47:13.593881 :99C24494:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=172 time=746243469 2025-05-20 19:47:13.593881 :99C24495:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 19:47:13.593882 :99C24496:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 19:47:13.593882 :99C24497:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 19:47:13.593882 :99C24498:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 19:47:13.593883 :99C24499:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 19:47:13.594369 :99C2449A: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-20 19:47:13.594414 :99C244A1: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-20 19:57:14.511337 :99C30975:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 19:57:15.047318 :99C309E7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 19:57:15.043 2025-05-20 19:57:15.047320 :99C309E8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12756 pso_num=22 pso_serial#=21 2025-05-20 19:57:15.047455 :99C309E9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12756 osp_idx=0 osp_ver=216006 osp_pg=0 (spawn #202219) 2025-05-20 19:57:15.047456 :99C309EA: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-20 19:57:15.047456 :99C309EB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=173 time=746844922 2025-05-20 19:57:15.047457 :99C309EC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 19:57:15.047457 :99C309ED:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 19:57:15.047457 :99C309EE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 19:57:15.047458 :99C309EF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 19:57:15.047458 :99C309F0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 19:57:15.047959 :99C309F1: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-20 19:57:15.048006 :99C309F9: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-20 20:07:16.025842 :99C3DA4C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 20:07:16.439624 :99C3DA97:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 20:07:16.426 2025-05-20 20:07:16.439626 :99C3DA98:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12396 pso_num=22 pso_serial#=22 2025-05-20 20:07:16.439765 :99C3DA99:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12396 (legacy spawn) 2025-05-20 20:07:16.446145 :99C3DA9A: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-20 20:07:16.446316 :99C3DA9F: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-20 20:07:16.447119 :99C3DAA4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 20:07:16.455716 :99C3DAA7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 20:07:16.442 2025-05-20 20:07:16.455719 :99C3DAA8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3352 pso_num=22 pso_serial#=23 2025-05-20 20:07:16.455854 :99C3DAA9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=3352 (legacy spawn) 2025-05-20 20:07:16.462334 :99C3DAAA: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-20 20:07:16.462490 :99C3DAB3: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-20 20:07:16.463318 :99C3DAB4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 20:07:16.509296 :99C3DADA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 20:07:16.504 2025-05-20 20:07:16.509299 :99C3DADB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12468 pso_num=22 pso_serial#=24 2025-05-20 20:07:16.509440 :99C3DADC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12468 osp_idx=0 osp_ver=216009 osp_pg=0 (spawn #202245) 2025-05-20 20:07:16.509441 :99C3DADD: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-20 20:07:16.509441 :99C3DADE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=174 time=747446375 2025-05-20 20:07:16.509442 :99C3DADF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 20:07:16.509442 :99C3DAE0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 20:07:16.509442 :99C3DAE1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 20:07:16.509443 :99C3DAE2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 20:07:16.509443 :99C3DAE3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 20:07:16.509952 :99C3DAE4: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-20 20:07:16.509999 :99C3DAEC: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-20 20:17:17.428071 :99C49F6D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 20:17:17.932774 :99C49FAB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 20:17:17.928 2025-05-20 20:17:17.932776 :99C49FAC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5464 pso_num=22 pso_serial#=25 2025-05-20 20:17:17.932909 :99C49FAD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=5464 osp_idx=0 osp_ver=216010 osp_pg=0 (spawn #202268) 2025-05-20 20:17:17.932910 :99C49FAE: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-20 20:17:17.932910 :99C49FAF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=175 time=748047797 2025-05-20 20:17:17.932910 :99C49FB0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 20:17:17.932911 :99C49FB1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 20:17:17.932911 :99C49FB2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 20:17:17.932911 :99C49FB3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 20:17:17.932912 :99C49FB4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 20:17:17.933460 :99C49FB5: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-20 20:17:17.933509 :99C49FBC: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-20 20:25:39.086927 :99C54541: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-20 20:27:19.287887 :99C5659B: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-20 20:37:20.259836 :99C629F8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 20:37:20.623969 :99C62A32:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 20:37:20.619 2025-05-20 20:37:20.623971 :99C62A33:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11660 pso_num=22 pso_serial#=26 2025-05-20 20:37:20.624108 :99C62A34:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11660 osp_idx=0 osp_ver=216011 osp_pg=0 (spawn #202312) 2025-05-20 20:37:20.624109 :99C62A35: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-20 20:37:20.624109 :99C62A36:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=176 time=749250500 2025-05-20 20:37:20.624109 :99C62A37:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 20:37:20.624110 :99C62A38:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 20:37:20.624110 :99C62A39:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 20:37:20.624111 :99C62A3A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 20:37:20.624111 :99C62A3B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 20:37:20.624601 :99C62A3C: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-20 20:37:20.624648 :99C62A44: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-20 20:47:21.622838 :99C6EFB8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 20:47:21.924351 :99C6EFDE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 20:47:21.919 2025-05-20 20:47:21.924353 :99C6EFDF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14188 pso_num=22 pso_serial#=27 2025-05-20 20:47:21.924484 :99C6EFE0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14188 osp_idx=0 osp_ver=216012 osp_pg=0 (spawn #202335) 2025-05-20 20:47:21.924485 :99C6EFE1: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-20 20:47:21.924485 :99C6EFE2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=177 time=749851797 2025-05-20 20:47:21.924485 :99C6EFE3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 20:47:21.924486 :99C6EFE4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 20:47:21.924486 :99C6EFE5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 20:47:21.924486 :99C6EFE6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 20:47:21.924487 :99C6EFE7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 20:47:21.925018 :99C6EFE8: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-20 20:47:21.925061 :99C6EFF0: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-20 20:57:22.877103 :99C7B19C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 20:57:23.062345 :99C7B1BC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 20:57:23.048 2025-05-20 20:57:23.062347 :99C7B1BD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13264 pso_num=22 pso_serial#=28 2025-05-20 20:57:23.062490 :99C7B1BE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13264 (legacy spawn) 2025-05-20 20:57:23.068959 :99C7B1BF: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-20 20:57:23.069119 :99C7B1C4: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-20 20:57:23.069957 :99C7B1C9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 20:57:23.256881 :99C7B210:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 20:57:23.251 2025-05-20 20:57:23.256883 :99C7B211:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13616 pso_num=22 pso_serial#=29 2025-05-20 20:57:23.257022 :99C7B212:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13616 osp_idx=0 osp_ver=216014 osp_pg=0 (spawn #202357) 2025-05-20 20:57:23.257023 :99C7B213: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-20 20:57:23.257023 :99C7B214:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=178 time=750453125 2025-05-20 20:57:23.257023 :99C7B215:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 20:57:23.257024 :99C7B216:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 20:57:23.257024 :99C7B217:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 20:57:23.257024 :99C7B218:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 20:57:23.257025 :99C7B219:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 20:57:23.257547 :99C7B21A: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-20 20:57:23.257596 :99C7B224: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-20 21:07:24.274928 :99C88089:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 21:07:24.576505 :99C880D3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 21:07:24.572 2025-05-20 21:07:24.576507 :99C880D4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13496 pso_num=22 pso_serial#=30 2025-05-20 21:07:24.576649 :99C880D5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13496 osp_idx=0 osp_ver=216015 osp_pg=0 (spawn #202383) 2025-05-20 21:07:24.576650 :99C880D6: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-20 21:07:24.576650 :99C880D7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=179 time=751054454 2025-05-20 21:07:24.576651 :99C880D8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:07:24.576651 :99C880D9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:07:24.576652 :99C880DA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:07:24.576652 :99C880DB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:07:24.576652 :99C880DC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:07:24.577160 :99C880DD: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-20 21:07:24.577202 :99C880E5: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-20 21:17:25.603771 :99C9427E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 21:17:25.889706 :99C942BC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 21:17:25.884 2025-05-20 21:17:25.889709 :99C942BD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2312 pso_num=22 pso_serial#=31 2025-05-20 21:17:25.889841 :99C942BE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=2312 osp_idx=0 osp_ver=216016 osp_pg=0 (spawn #202406) 2025-05-20 21:17:25.889842 :99C942BF: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-20 21:17:25.889842 :99C942C0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=180 time=751655766 2025-05-20 21:17:25.889843 :99C942C1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:17:25.889843 :99C942C2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:17:25.889843 :99C942C3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:17:25.889844 :99C942C4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:17:25.889844 :99C942C5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:17:25.890331 :99C942C6: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-20 21:17:25.890388 :99C942CC: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-20 21:27:26.848072 :99CA061B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 21:27:27.102774 :99CA0665:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 21:27:27.098 2025-05-20 21:27:27.102775 :99CA0666:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11704 pso_num=22 pso_serial#=32 2025-05-20 21:27:27.102907 :99CA0667:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11704 osp_idx=0 osp_ver=216017 osp_pg=0 (spawn #202428) 2025-05-20 21:27:27.102908 :99CA0668: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-20 21:27:27.102908 :99CA0669:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=181 time=752256969 2025-05-20 21:27:27.102908 :99CA066A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:27:27.102909 :99CA066B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:27:27.102909 :99CA066C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:27:27.102909 :99CA066D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:27:27.102910 :99CA066E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:27:27.103397 :99CA066F: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-20 21:27:27.103467 :99CA0676: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-20 21:37:27.989244 :99CAC9A0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 21:37:28.332185 :99CACA1B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 21:37:28.327 2025-05-20 21:37:28.332187 :99CACA1C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12972 pso_num=22 pso_serial#=33 2025-05-20 21:37:28.332322 :99CACA1D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12972 osp_idx=0 osp_ver=216018 osp_pg=0 (spawn #202451) 2025-05-20 21:37:28.332323 :99CACA1E: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-20 21:37:28.332323 :99CACA1F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=182 time=752858204 2025-05-20 21:37:28.332323 :99CACA20:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:37:28.332324 :99CACA21:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:37:28.332324 :99CACA22:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:37:28.332324 :99CACA23:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:37:28.332324 :99CACA24:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:37:28.332793 :99CACA25: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-20 21:37:28.332840 :99CACA2E: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-20 21:47:29.350399 :99CB8DC2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 21:47:29.714480 :99CB8E1C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 21:47:29.710 2025-05-20 21:47:29.714482 :99CB8E1D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12400 pso_num=22 pso_serial#=34 2025-05-20 21:47:29.714618 :99CB8E1E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12400 osp_idx=0 osp_ver=216019 osp_pg=0 (spawn #202474) 2025-05-20 21:47:29.714619 :99CB8E1F: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-20 21:47:29.714620 :99CB8E20:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=183 time=753459579 2025-05-20 21:47:29.714620 :99CB8E21:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:47:29.714620 :99CB8E22:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:47:29.714620 :99CB8E23:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:47:29.714621 :99CB8E24:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:47:29.714621 :99CB8E25:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:47:29.715115 :99CB8E26: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-20 21:47:29.715163 :99CB8E2D: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-20 21:57:30.751376 :99CC50A9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-20 21:57:31.005912 :99CC50E9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-20 21:57:31.001 2025-05-20 21:57:31.005914 :99CC50EA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9492 pso_num=22 pso_serial#=35 2025-05-20 21:57:31.006052 :99CC50EB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9492 osp_idx=0 osp_ver=216020 osp_pg=0 (spawn #202496) 2025-05-20 21:57:31.006053 :99CC50EC: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-20 21:57:31.006054 :99CC50ED:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=184 time=754060875 2025-05-20 21:57:31.006054 :99CC50EE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-20 21:57:31.006055 :99CC50EF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-20 21:57:31.006055 :99CC50F0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-20 21:57:31.006055 :99CC50F1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-20 21:57:31.006055 :99CC50F2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-20 21:57:31.006555 :99CC50F3: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-20 21:57:31.006598 :99CC50FC: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 9492, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de