Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250814220005\orcl_w000_21072_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250814220005\orcl_w000_21072_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:35494M/63366M, Ph+PgF:40844M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 21072, image: ORACLE.EXE (W000) *** 2025-08-14 22:00:06.011 *** SESSION ID:(177.43027) 2025-08-14 22:00:06.011 *** 2025-08-14 22:00:06.011 Process diagnostic dump for ORACLE.EXE (W000), OS id=21072, pid: 22, proc_ser: 182, sid: 177, sess_ser: 43027 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=156 seq_num=157 snap_id=1 wait times: snap=0.482463 sec, exc=0.482463 sec, total=0.482463 sec wait times: max=5.000000 sec, heur=2 min 40 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.000018 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=155 seq_num=156 snap_id=1 wait times: snap=5.001135 sec, exc=5.001135 sec, total=5.001135 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=154 seq_num=155 snap_id=1 wait times: snap=5.007721 sec, exc=5.007721 sec, total=5.007721 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000054 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=153 seq_num=154 snap_id=1 wait times: snap=5.007584 sec, exc=5.007584 sec, total=5.007584 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=152 seq_num=153 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.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=151 seq_num=152 snap_id=1 wait times: snap=5.004630 sec, exc=5.004630 sec, total=5.004630 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=150 seq_num=151 snap_id=1 wait times: snap=5.009235 sec, exc=5.009235 sec, total=5.009235 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=149 seq_num=150 snap_id=1 wait times: snap=5.010155 sec, exc=5.010155 sec, total=5.010155 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=148 seq_num=149 snap_id=1 wait times: snap=5.001729 sec, exc=5.001729 sec, total=5.001729 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=147 seq_num=148 snap_id=1 wait times: snap=5.001326 sec, exc=5.001326 sec, total=5.001326 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=146 seq_num=147 snap_id=1 wait times: snap=5.008825 sec, exc=5.008825 sec, total=5.008825 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time Sampled Session History of session 177 serial 43027 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 21:58:06 - 22:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-14 22:00:06.011 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 21072, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-14 19:27:05.769967 :BFBC546D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=159 time=3880295079 2025-08-14 19:27:05.769968 :BFBC546E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 19:27:05.769968 :BFBC546F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 19:27:05.769969 :BFBC5470:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 19:27:05.769969 :BFBC5471:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 19:27:05.769969 :BFBC5472:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 19:27:05.770466 :BFBC5473: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-08-14 19:27:05.770513 :BFBC547B: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-08-14 19:37:06.684705 :BFBD16A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 19:37:07.190456 :BFBD175D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 19:37:07.185 2025-08-14 19:37:07.190459 :BFBD175E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21156 pso_num=22 pso_serial#=170 2025-08-14 19:37:07.190604 :BFBD175F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21156 osp_idx=0 osp_ver=514832 osp_pg=0 (spawn #488813) 2025-08-14 19:37:07.190604 :BFBD1760:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 19:37:07.190605 :BFBD1761:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=160 time=3880896501 2025-08-14 19:37:07.190606 :BFBD1762:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 19:37:07.190606 :BFBD1763:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 19:37:07.190606 :BFBD1764:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 19:37:07.190607 :BFBD1765:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 19:37:07.190607 :BFBD1766:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 19:37:07.191114 :BFBD1767: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-08-14 19:37:07.191162 :BFBD176E: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-08-14 19:45:35.499466 :BFBDBC8B: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-08-14 19:47:08.476611 :BFBDDB04: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-08-14 19:57:09.342918 :BFBE9E41:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 19:57:09.817268 :BFBE9EF1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 19:57:09.812 2025-08-14 19:57:09.817270 :BFBE9EF2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21020 pso_num=22 pso_serial#=171 2025-08-14 19:57:09.817417 :BFBE9EF3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21020 osp_idx=0 osp_ver=514833 osp_pg=0 (spawn #488857) 2025-08-14 19:57:09.817418 :BFBE9EF4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 19:57:09.817418 :BFBE9EF5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=161 time=3882099126 2025-08-14 19:57:09.817419 :BFBE9EF6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 19:57:09.817419 :BFBE9EF7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 19:57:09.817419 :BFBE9EF8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 19:57:09.817420 :BFBE9EF9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 19:57:09.817420 :BFBE9EFA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 19:57:09.817928 :BFBE9EFB: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-08-14 19:57:09.817975 :BFBE9F02: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-08-14 20:07:10.752987 :BFBF6E41:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 20:07:11.101201 :BFBF6ECB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 20:07:11.096 2025-08-14 20:07:11.101203 :BFBF6ECC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21284 pso_num=22 pso_serial#=172 2025-08-14 20:07:11.101368 :BFBF6ECD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21284 osp_idx=0 osp_ver=514834 osp_pg=0 (spawn #488884) 2025-08-14 20:07:11.101369 :BFBF6ECE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 20:07:11.101369 :BFBF6ECF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=162 time=3882700408 2025-08-14 20:07:11.101370 :BFBF6ED0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 20:07:11.101370 :BFBF6ED1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 20:07:11.101371 :BFBF6ED2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 20:07:11.101371 :BFBF6ED3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 20:07:11.101371 :BFBF6ED4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 20:07:11.101897 :BFBF6ED5: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-08-14 20:07:11.101942 :BFBF6EDD: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-08-14 20:17:11.984185 :BFC030D6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 20:17:12.405144 :BFC0316C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 20:17:12.400 2025-08-14 20:17:12.405146 :BFC0316D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14400 pso_num=22 pso_serial#=173 2025-08-14 20:17:12.405302 :BFC0316E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14400 osp_idx=0 osp_ver=514835 osp_pg=0 (spawn #488906) 2025-08-14 20:17:12.405303 :BFC0316F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 20:17:12.405303 :BFC03170:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=163 time=3883301704 2025-08-14 20:17:12.405303 :BFC03171:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 20:17:12.405304 :BFC03172:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 20:17:12.405305 :BFC03173:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 20:17:12.405305 :BFC03174:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 20:17:12.405305 :BFC03175:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 20:17:12.405843 :BFC03176: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-08-14 20:17:12.405890 :BFC0317E: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-08-14 20:27:13.320937 :BFC0F48C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 20:27:13.684724 :BFC0F517:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 20:27:13.680 2025-08-14 20:27:13.684726 :BFC0F518:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19728 pso_num=22 pso_serial#=174 2025-08-14 20:27:13.684866 :BFC0F519:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19728 osp_idx=0 osp_ver=514836 osp_pg=0 (spawn #488929) 2025-08-14 20:27:13.684866 :BFC0F51A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 20:27:13.684867 :BFC0F51B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=164 time=3883902986 2025-08-14 20:27:13.684867 :BFC0F51C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 20:27:13.684867 :BFC0F51D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 20:27:13.684868 :BFC0F51E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 20:27:13.684868 :BFC0F51F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 20:27:13.684868 :BFC0F520:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 20:27:13.685374 :BFC0F521: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-08-14 20:27:13.685423 :BFC0F528: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-08-14 20:37:14.566354 :BFC1B7FD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 20:37:14.899303 :BFC1B87D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 20:37:14.895 2025-08-14 20:37:14.899305 :BFC1B87E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20680 pso_num=22 pso_serial#=175 2025-08-14 20:37:14.899447 :BFC1B87F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20680 osp_idx=0 osp_ver=514837 osp_pg=0 (spawn #488952) 2025-08-14 20:37:14.899448 :BFC1B880:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 20:37:14.899448 :BFC1B881:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=165 time=3884504204 2025-08-14 20:37:14.899450 :BFC1B882:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 20:37:14.899451 :BFC1B883:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 20:37:14.899451 :BFC1B884:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 20:37:14.899451 :BFC1B885:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 20:37:14.899452 :BFC1B886:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 20:37:14.899971 :BFC1B887: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-08-14 20:37:14.900015 :BFC1B890: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-08-14 20:47:15.864277 :BFC27C45:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 20:47:16.274888 :BFC27CE2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 20:47:16.270 2025-08-14 20:47:16.274890 :BFC27CE3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21384 pso_num=22 pso_serial#=176 2025-08-14 20:47:16.275020 :BFC27CE4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21384 osp_idx=0 osp_ver=514838 osp_pg=0 (spawn #488974) 2025-08-14 20:47:16.275020 :BFC27CE5:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 20:47:16.275021 :BFC27CE6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=166 time=3885105579 2025-08-14 20:47:16.275021 :BFC27CE7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 20:47:16.275022 :BFC27CE8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 20:47:16.275022 :BFC27CE9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 20:47:16.275023 :BFC27CEA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 20:47:16.275023 :BFC27CEB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 20:47:16.275510 :BFC27CEC: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-08-14 20:47:16.275555 :BFC27CF4: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-08-14 20:57:17.232381 :BFC3402C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 20:57:17.533930 :BFC340A6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 20:57:17.528 2025-08-14 20:57:17.533932 :BFC340A7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19836 pso_num=22 pso_serial#=177 2025-08-14 20:57:17.534118 :BFC340A8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19836 osp_idx=0 osp_ver=514839 osp_pg=0 (spawn #488997) 2025-08-14 20:57:17.534119 :BFC340A9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 20:57:17.534119 :BFC340AA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=167 time=3885706845 2025-08-14 20:57:17.534120 :BFC340AB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 20:57:17.534121 :BFC340AC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 20:57:17.534121 :BFC340AD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 20:57:17.534122 :BFC340AE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 20:57:17.534122 :BFC340AF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 20:57:17.534642 :BFC340B0: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-08-14 20:57:17.534694 :BFC340B7: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-08-14 21:07:18.412285 :BFC41041:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 21:07:18.871218 :BFC410B6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 21:07:18.866 2025-08-14 21:07:18.871220 :BFC410B7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21344 pso_num=22 pso_serial#=178 2025-08-14 21:07:18.871399 :BFC410B8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21344 osp_idx=0 osp_ver=514840 osp_pg=0 (spawn #489023) 2025-08-14 21:07:18.871399 :BFC410B9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:07:18.871400 :BFC410BA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=168 time=3886308173 2025-08-14 21:07:18.871400 :BFC410BB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:07:18.871400 :BFC410BC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:07:18.871401 :BFC410BD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:07:18.871401 :BFC410BE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:07:18.871402 :BFC410BF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:07:18.871909 :BFC410C0: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-08-14 21:07:18.871958 :BFC410C7: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-08-14 21:17:19.744673 :BFC4D3AF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 21:17:20.124436 :BFC4D42F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 21:17:20.119 2025-08-14 21:17:20.124438 :BFC4D430:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20896 pso_num=22 pso_serial#=179 2025-08-14 21:17:20.124575 :BFC4D431:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20896 osp_idx=0 osp_ver=514841 osp_pg=0 (spawn #489045) 2025-08-14 21:17:20.124576 :BFC4D432:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:17:20.124576 :BFC4D433:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=169 time=3886909423 2025-08-14 21:17:20.124577 :BFC4D434:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:17:20.124577 :BFC4D435:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:17:20.124578 :BFC4D436:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:17:20.124578 :BFC4D437:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:17:20.124578 :BFC4D438:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:17:20.125128 :BFC4D439: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-08-14 21:17:20.125177 :BFC4D440: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-08-14 21:17:35.142461 :BFC4D8C1:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-08-14 21:17:35.142480 :BFC4D8D5:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-08-14 21:17:35.142482 :BFC4D8D6:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=17, exc=17, tot=17 2025-08-14 21:27:21.097214 :BFC59842:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 21:27:21.383124 :BFC598BD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 21:27:21.378 2025-08-14 21:27:21.383126 :BFC598BE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21124 pso_num=22 pso_serial#=180 2025-08-14 21:27:21.383268 :BFC598BF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21124 osp_idx=0 osp_ver=514842 osp_pg=0 (spawn #489068) 2025-08-14 21:27:21.383269 :BFC598C0:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:27:21.383269 :BFC598C1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=170 time=3887510689 2025-08-14 21:27:21.383270 :BFC598C2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:27:21.383270 :BFC598C3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:27:21.383271 :BFC598C4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:27:21.383271 :BFC598C5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:27:21.383271 :BFC598C6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:27:21.383769 :BFC598C7: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-08-14 21:27:21.383813 :BFC598CE: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-08-14 21:37:22.293524 :BFC65D3F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 21:37:22.626273 :BFC65DC5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 21:37:22.621 2025-08-14 21:37:22.626275 :BFC65DC6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21012 pso_num=22 pso_serial#=181 2025-08-14 21:37:22.626417 :BFC65DC7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21012 osp_idx=0 osp_ver=514843 osp_pg=0 (spawn #489091) 2025-08-14 21:37:22.626418 :BFC65DC8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:37:22.626418 :BFC65DC9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=171 time=3888111939 2025-08-14 21:37:22.626419 :BFC65DCA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:37:22.626419 :BFC65DCB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:37:22.626419 :BFC65DCC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:37:22.626420 :BFC65DCD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:37:22.626420 :BFC65DCE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:37:22.626911 :BFC65DCF: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-08-14 21:37:22.626961 :BFC65DD6: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-08-14 21:47:23.474953 :BFC721DB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-14 21:47:23.897002 :BFC72257:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-14 21:47:23.892 2025-08-14 21:47:23.897004 :BFC72258:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21072 pso_num=22 pso_serial#=182 2025-08-14 21:47:23.897140 :BFC72259:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21072 osp_idx=0 osp_ver=514844 osp_pg=0 (spawn #489113) 2025-08-14 21:47:23.897140 :BFC7225A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:47:23.897141 :BFC7225B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=172 time=3888713204 2025-08-14 21:47:23.897141 :BFC7225C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:47:23.897141 :BFC7225D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:47:23.897142 :BFC7225E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:47:23.897142 :BFC7225F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:47:23.897142 :BFC72260:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:47:23.897616 :BFC72261: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-08-14 21:47:23.897666 :BFC7226B: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-08-14 21:48:46.840521 :BFC73CB6: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-08-14 21:57:25.304292 :BFC7E68B: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-08-14 21:57:25.304329 :BFC7E68C:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1 2025-08-14 21:57:25.304373 :BFC7E691:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1 2025-08-14 21:57:25.304374 :BFC7E692:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=44, exc=44, tot=44 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 21072, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de