Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250715081437\orcl_w000_10112_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250715081437\orcl_w000_10112_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:36325M/63366M, Ph+PgF:42269M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 10112, image: ORACLE.EXE (W000) *** 2025-07-15 08:14:38.071 *** SESSION ID:(201.43761) 2025-07-15 08:14:38.071 *** 2025-07-15 08:14:38.071 Process diagnostic dump for ORACLE.EXE (W000), OS id=10112, pid: 25, proc_ser: 48, sid: 201, sess_ser: 43761 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=100 seq_num=101 snap_id=1 wait times: snap=0.819847 sec, exc=0.819847 sec, total=0.819847 sec wait times: max=5.000000 sec, heur=8 min 16 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.000034 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=99 seq_num=100 snap_id=1 wait times: snap=5.014745 sec, exc=5.014745 sec, total=5.014745 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=98 seq_num=99 snap_id=1 wait times: snap=5.004639 sec, exc=5.004639 sec, total=5.004639 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=97 seq_num=98 snap_id=1 wait times: snap=5.006780 sec, exc=5.006780 sec, total=5.006780 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=5.007222 sec, exc=5.007222 sec, total=5.007222 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000042 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=95 seq_num=96 snap_id=1 wait times: snap=5.001234 sec, exc=5.001234 sec, total=5.001234 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000041 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=5.014020 sec, exc=5.014020 sec, total=5.014020 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000039 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=93 seq_num=94 snap_id=1 wait times: snap=5.010101 sec, exc=5.010101 sec, total=5.010101 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000041 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=92 seq_num=93 snap_id=1 wait times: snap=5.010090 sec, exc=5.010090 sec, total=5.010090 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=91 seq_num=92 snap_id=1 wait times: snap=5.014743 sec, exc=5.014743 sec, total=5.014743 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=90 seq_num=91 snap_id=1 wait times: snap=5.014766 sec, exc=5.014766 sec, total=5.014766 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time Sampled Session History of session 201 serial 43761 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 08:12:38 - 08:14:38] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-15 08:14:38.071 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 10112, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-15 05:46:03.139863 :B230C5F0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 05:46:03.139864 :B230C5F1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 05:46:03.139864 :B230C5F2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 05:46:03.139864 :B230C5F3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 05:46:03.139865 :B230C5F4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 05:46:03.140367 :B230C5F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 05:46:03.140415 :B230C5FD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 05:56:04.023698 :B2319077:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 05:56:04.465700 :B23190D3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 05:56:04.461 2025-07-15 05:56:04.465702 :B23190D4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18088 pso_num=25 pso_serial#=36 2025-07-15 05:56:04.465846 :B23190D5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18088 osp_idx=23 osp_ver=197518 osp_pg=0 (spawn #386936) 2025-07-15 05:56:04.465847 :B23190D6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 05:56:04.465847 :B23190D7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=179 time=1239740361 2025-07-15 05:56:04.465848 :B23190D8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 05:56:04.465848 :B23190D9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 05:56:04.465848 :B23190DA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 05:56:04.465849 :B23190DB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 05:56:04.465849 :B23190DC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 05:56:04.466335 :B23190DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 05:56:04.466379 :B23190E5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 06:06:05.400002 :B23262E4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 06:06:05.773979 :B2326358:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 06:06:05.769 2025-07-15 06:06:05.773981 :B2326359:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9700 pso_num=25 pso_serial#=37 2025-07-15 06:06:05.774117 :B232635A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9700 osp_idx=23 osp_ver=197519 osp_pg=0 (spawn #386962) 2025-07-15 06:06:05.774118 :B232635B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 06:06:05.774119 :B232635C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=180 time=1240341673 2025-07-15 06:06:05.774119 :B232635D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 06:06:05.774119 :B232635E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 06:06:05.774120 :B232635F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 06:06:05.774120 :B2326360:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 06:06:05.774121 :B2326361:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 06:06:05.774616 :B2326362:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 06:06:05.774663 :B232636A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 06:16:06.730363 :B2332EC6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 06:16:07.142149 :B2332F1F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 06:16:07.137 2025-07-15 06:16:07.142151 :B2332F20:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10764 pso_num=25 pso_serial#=38 2025-07-15 06:16:07.142293 :B2332F21:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10764 osp_idx=23 osp_ver=197520 osp_pg=0 (spawn #386984) 2025-07-15 06:16:07.142294 :B2332F22:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 06:16:07.142294 :B2332F23:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=181 time=1240943033 2025-07-15 06:16:07.142296 :B2332F24:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 06:16:07.142297 :B2332F25:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 06:16:07.142297 :B2332F26:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 06:16:07.142297 :B2332F27:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 06:16:07.142298 :B2332F28:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 06:16:07.142795 :B2332F29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 06:16:07.142843 :B2332F31:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 06:26:08.089911 :B233F868:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 06:26:08.500027 :B233F8D2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 06:26:08.495 2025-07-15 06:26:08.500029 :B233F8D3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17072 pso_num=25 pso_serial#=39 2025-07-15 06:26:08.500164 :B233F8D4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17072 osp_idx=23 osp_ver=197521 osp_pg=0 (spawn #387007) 2025-07-15 06:26:08.500165 :B233F8D5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 06:26:08.500166 :B233F8D6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=182 time=1241544392 2025-07-15 06:26:08.500166 :B233F8D7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 06:26:08.500167 :B233F8D8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 06:26:08.500167 :B233F8D9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 06:26:08.500167 :B233F8DA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 06:26:08.500168 :B233F8DB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 06:26:08.500659 :B233F8DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 06:26:08.500703 :B233F8E3:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 06:36:09.396275 :B234C25C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 06:36:09.728991 :B234C2B8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 06:36:09.724 2025-07-15 06:36:09.728993 :B234C2B9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2848 pso_num=25 pso_serial#=40 2025-07-15 06:36:09.729126 :B234C2BA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=2848 osp_idx=23 osp_ver=197522 osp_pg=0 (spawn #387030) 2025-07-15 06:36:09.729126 :B234C2BB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 06:36:09.729127 :B234C2BC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=183 time=1242145626 2025-07-15 06:36:09.729127 :B234C2BD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 06:36:09.729127 :B234C2BE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 06:36:09.729128 :B234C2BF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 06:36:09.729128 :B234C2C0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 06:36:09.729128 :B234C2C1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 06:36:09.729599 :B234C2C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 06:36:09.729641 :B234C2CA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 06:46:10.687751 :B2358C55:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 06:46:10.989073 :B2358CBB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 06:46:10.984 2025-07-15 06:46:10.989075 :B2358CBC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16156 pso_num=25 pso_serial#=41 2025-07-15 06:46:10.989214 :B2358CBD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16156 osp_idx=23 osp_ver=197523 osp_pg=0 (spawn #387052) 2025-07-15 06:46:10.989215 :B2358CBE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 06:46:10.989215 :B2358CBF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=184 time=1242746876 2025-07-15 06:46:10.989215 :B2358CC0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 06:46:10.989216 :B2358CC1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 06:46:10.989216 :B2358CC2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 06:46:10.989217 :B2358CC3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 06:46:10.989217 :B2358CC4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 06:46:10.989766 :B2358CC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 06:46:10.989814 :B2358CCE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 06:56:11.909152 :B236559B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 06:56:12.320155 :B23655F7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 06:56:12.315 2025-07-15 06:56:12.320157 :B23655F8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17272 pso_num=25 pso_serial#=42 2025-07-15 06:56:12.320296 :B23655F9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17272 osp_idx=23 osp_ver=197524 osp_pg=0 (spawn #387075) 2025-07-15 06:56:12.320297 :B23655FA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 06:56:12.320298 :B23655FB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=185 time=1243348220 2025-07-15 06:56:12.320298 :B23655FC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 06:56:12.320299 :B23655FD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 06:56:12.320299 :B23655FE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 06:56:12.320299 :B23655FF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 06:56:12.320299 :B2365600:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 06:56:12.320800 :B2365601:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 06:56:12.320845 :B236560A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 07:02:27.536571 :B236D4A4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 07:06:13.628032 :B2372B5B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 07:06:13.628064 :B2372B5C:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1 2025-07-15 07:06:13.628089 :B2372B61:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1 2025-07-15 07:06:13.628090 :B2372B62:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=25, exc=25, tot=25 2025-07-15 07:06:28.663296 :B2373081:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=127 seq_num=128 snap_id=1 2025-07-15 07:06:28.663312 :B237308A:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=127 seq_num=128 snap_id=1 2025-07-15 07:06:28.663312 :B237308B:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=16, exc=16, tot=16 2025-07-15 07:16:14.638743 :B237F3BD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 07:16:14.940001 :B237F442:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 07:16:14.935 2025-07-15 07:16:14.940003 :B237F443:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16132 pso_num=25 pso_serial#=43 2025-07-15 07:16:14.940143 :B237F444:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16132 osp_idx=23 osp_ver=197525 osp_pg=0 (spawn #387122) 2025-07-15 07:16:14.940144 :B237F445:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 07:16:14.940144 :B237F446:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=186 time=1244550829 2025-07-15 07:16:14.940145 :B237F447:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 07:16:14.940145 :B237F448:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 07:16:14.940145 :B237F449:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 07:16:14.940146 :B237F44A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 07:16:14.940146 :B237F44B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 07:16:14.940635 :B237F44C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 07:16:14.940679 :B237F455:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 07:26:15.932467 :B238BD81:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 07:26:16.249986 :B238BE12:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 07:26:16.245 2025-07-15 07:26:16.249988 :B238BE13:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8692 pso_num=25 pso_serial#=44 2025-07-15 07:26:16.250120 :B238BE14:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8692 osp_idx=23 osp_ver=197526 osp_pg=0 (spawn #387145) 2025-07-15 07:26:16.250121 :B238BE15:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 07:26:16.250121 :B238BE16:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=187 time=1245152142 2025-07-15 07:26:16.250122 :B238BE17:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 07:26:16.250122 :B238BE18:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 07:26:16.250123 :B238BE19:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 07:26:16.250123 :B238BE1A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 07:26:16.250123 :B238BE1B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 07:26:16.250609 :B238BE1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 07:26:16.250655 :B238BE24:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 07:36:17.154935 :B2398746:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 07:36:17.533966 :B23987A0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 07:36:17.529 2025-07-15 07:36:17.533968 :B23987A1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4492 pso_num=25 pso_serial#=45 2025-07-15 07:36:17.534111 :B23987A2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4492 osp_idx=23 osp_ver=197527 osp_pg=0 (spawn #387168) 2025-07-15 07:36:17.534112 :B23987A3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 07:36:17.534112 :B23987A4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=188 time=1245753423 2025-07-15 07:36:17.534113 :B23987A5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 07:36:17.534113 :B23987A6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 07:36:17.534113 :B23987A7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 07:36:17.534114 :B23987A8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 07:36:17.534114 :B23987A9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 07:36:17.534606 :B23987AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 07:36:17.534651 :B23987B2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 07:46:18.429930 :B23A522D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 07:46:18.872055 :B23A528F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 07:46:18.867 2025-07-15 07:46:18.872057 :B23A5290:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16720 pso_num=25 pso_serial#=46 2025-07-15 07:46:18.872187 :B23A5291:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16720 osp_idx=23 osp_ver=197528 osp_pg=0 (spawn #387191) 2025-07-15 07:46:18.872188 :B23A5292:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 07:46:18.872188 :B23A5293:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=189 time=1246354767 2025-07-15 07:46:18.872188 :B23A5294:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 07:46:18.872189 :B23A5295:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 07:46:18.872189 :B23A5296:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 07:46:18.872189 :B23A5297:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 07:46:18.872190 :B23A5298:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 07:46:18.872750 :B23A5299:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 07:46:18.872795 :B23A52A2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 07:46:33.871138 :B23A576A:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-07-15 07:46:33.871155 :B23A5770:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-07-15 07:46:33.871157 :B23A5771:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=17, exc=17, tot=17 2025-07-15 07:56:19.732247 :B23B1B4C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 07:56:20.143155 :B23B1BA5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 07:56:20.138 2025-07-15 07:56:20.143157 :B23B1BA6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16824 pso_num=25 pso_serial#=47 2025-07-15 07:56:20.143296 :B23B1BA7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16824 osp_idx=23 osp_ver=197529 osp_pg=0 (spawn #387214) 2025-07-15 07:56:20.143297 :B23B1BA8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 07:56:20.143297 :B23B1BA9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=190 time=1246956033 2025-07-15 07:56:20.143297 :B23B1BAA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 07:56:20.143298 :B23B1BAB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 07:56:20.143298 :B23B1BAC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 07:56:20.143298 :B23B1BAD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 07:56:20.143299 :B23B1BAE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 07:56:20.143794 :B23B1BAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 07:56:20.143838 :B23B1BB7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 08:06:21.092350 :B23BEC78:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 08:06:21.425010 :B23BECD4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 08:06:21.420 2025-07-15 08:06:21.425012 :B23BECD5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10112 pso_num=25 pso_serial#=48 2025-07-15 08:06:21.425152 :B23BECD6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10112 osp_idx=23 osp_ver=197530 osp_pg=0 (spawn #387240) 2025-07-15 08:06:21.425153 :B23BECD7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 08:06:21.425153 :B23BECD8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=191 time=1247557314 2025-07-15 08:06:21.425153 :B23BECD9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 08:06:21.425154 :B23BECDA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 08:06:21.425154 :B23BECDB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 08:06:21.425154 :B23BECDC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 08:06:21.425155 :B23BECDD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 08:06:21.425643 :B23BECDE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 08:06:21.425692 :B23BECE8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 10112, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de