Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250808080018\orcl_w000_636_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250808080018\orcl_w000_636_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:35640M/63366M, Ph+PgF:41131M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 636, image: ORACLE.EXE (W000) *** 2025-08-08 08:00:18.547 *** SESSION ID:(177.58093) 2025-08-08 08:00:18.547 *** 2025-08-08 08:00:18.547 Process diagnostic dump for ORACLE.EXE (W000), OS id=636, pid: 22, proc_ser: 163, sid: 177, sess_ser: 58093 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=2.786981 sec, exc=2.786981 sec, total=2.786981 sec wait times: max=5.000000 sec, heur=2 min 48 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.000022 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=5.004548 sec, exc=5.004548 sec, total=5.004548 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=32 seq_num=33 snap_id=1 wait times: snap=5.004640 sec, exc=5.004640 sec, total=5.004640 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=5.004612 sec, exc=5.004612 sec, total=5.004612 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.009740 sec, exc=5.009740 sec, total=5.009740 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=5.003321 sec, exc=5.003321 sec, total=5.003321 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.000191 sec, exc=5.000191 sec, total=5.000191 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.003552 sec, exc=5.003552 sec, total=5.003552 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=5.014749 sec, exc=5.014749 sec, total=5.014749 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=5.004614 sec, exc=5.004614 sec, total=5.004614 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.004645 sec, exc=5.004645 sec, total=5.004645 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 177 serial 58093 --------------------------------------------------- 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, 07:58:18 - 08:00:18] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-08 08:00:18.547 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 636, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-08 05:47:14.779264 :BCDDB749:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16300 pso_num=22 pso_serial#=149 2025-08-08 05:47:14.779407 :BCDDB74A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16300 osp_idx=0 osp_ver=493391 osp_pg=0 (spawn #466894) 2025-08-08 05:47:14.779408 :BCDDB74B: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-08 05:47:14.779409 :BCDDB74C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=250 time=3312704079 2025-08-08 05:47:14.779409 :BCDDB74D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 05:47:14.779410 :BCDDB74E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 05:47:14.779410 :BCDDB74F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 05:47:14.779411 :BCDDB750:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 05:47:14.779411 :BCDDB751:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 05:47:14.779897 :BCDDB752: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-08 05:47:14.779941 :BCDDB75A: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-08 05:57:15.592898 :BCDE7C1B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 05:57:16.003814 :BCDE7C7A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 05:57:15.999 2025-08-08 05:57:16.003817 :BCDE7C7B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16544 pso_num=22 pso_serial#=150 2025-08-08 05:57:16.003953 :BCDE7C7C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16544 osp_idx=0 osp_ver=493392 osp_pg=0 (spawn #466916) 2025-08-08 05:57:16.003953 :BCDE7C7D: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-08 05:57:16.003954 :BCDE7C7E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=251 time=3313305314 2025-08-08 05:57:16.003954 :BCDE7C7F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 05:57:16.003955 :BCDE7C80:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 05:57:16.003955 :BCDE7C81:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 05:57:16.003956 :BCDE7C82:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 05:57:16.003956 :BCDE7C83:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 05:57:16.004451 :BCDE7C84: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-08 05:57:16.004495 :BCDE7C8C: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-08 06:07:16.804301 :BCDF4E09:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 06:07:17.262225 :BCDF4E7A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 06:07:17.258 2025-08-08 06:07:17.262227 :BCDF4E7B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19380 pso_num=22 pso_serial#=151 2025-08-08 06:07:17.262366 :BCDF4E7C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19380 osp_idx=0 osp_ver=493393 osp_pg=0 (spawn #466942) 2025-08-08 06:07:17.262367 :BCDF4E7D: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-08 06:07:17.262367 :BCDF4E7E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=252 time=3313906564 2025-08-08 06:07:17.262368 :BCDF4E7F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 06:07:17.262368 :BCDF4E80:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 06:07:17.262368 :BCDF4E81:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 06:07:17.262369 :BCDF4E82:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 06:07:17.262369 :BCDF4E83:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 06:07:17.262866 :BCDF4E84: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-08 06:07:17.262911 :BCDF4E8B: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-08 06:17:18.033487 :BCE016F4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 06:17:18.526699 :BCE0172C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 06:17:18.522 2025-08-08 06:17:18.526701 :BCE0172D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=580 pso_num=22 pso_serial#=152 2025-08-08 06:17:18.526842 :BCE0172E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=580 osp_idx=0 osp_ver=493394 osp_pg=0 (spawn #466965) 2025-08-08 06:17:18.526843 :BCE0172F: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-08 06:17:18.526843 :BCE01730:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=253 time=3314507829 2025-08-08 06:17:18.526844 :BCE01731:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 06:17:18.526844 :BCE01732:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 06:17:18.526844 :BCE01733:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 06:17:18.526845 :BCE01734:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 06:17:18.526845 :BCE01735:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 06:17:18.527332 :BCE01736: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-08 06:17:18.527376 :BCE0173D: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-08 06:27:19.341477 :BCE0E0BF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 06:27:19.684551 :BCE0E103:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 06:27:19.680 2025-08-08 06:27:19.684553 :BCE0E104:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19380 pso_num=22 pso_serial#=153 2025-08-08 06:27:19.684694 :BCE0E105:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19380 osp_idx=0 osp_ver=493395 osp_pg=0 (spawn #466987) 2025-08-08 06:27:19.684695 :BCE0E106: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-08 06:27:19.684695 :BCE0E107:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=254 time=3315108986 2025-08-08 06:27:19.684696 :BCE0E108:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 06:27:19.684696 :BCE0E109:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 06:27:19.684697 :BCE0E10A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 06:27:19.684697 :BCE0E10B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 06:27:19.684697 :BCE0E10C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 06:27:19.685198 :BCE0E10D: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-08 06:27:19.685245 :BCE0E115: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-08 06:37:20.445324 :BCE1AB09:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 06:37:20.840706 :BCE1AB42:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 06:37:20.836 2025-08-08 06:37:20.840709 :BCE1AB43:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19044 pso_num=22 pso_serial#=154 2025-08-08 06:37:20.840878 :BCE1AB44:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19044 osp_idx=0 osp_ver=493396 osp_pg=0 (spawn #467010) 2025-08-08 06:37:20.840879 :BCE1AB45: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-08 06:37:20.840880 :BCE1AB46:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=255 time=3315710142 2025-08-08 06:37:20.840880 :BCE1AB47:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 06:37:20.840880 :BCE1AB48:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 06:37:20.840881 :BCE1AB49:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 06:37:20.840881 :BCE1AB4A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 06:37:20.840881 :BCE1AB4B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 06:37:20.841381 :BCE1AB4C: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-08 06:37:20.841424 :BCE1AB54: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-08 06:47:21.681089 :BCE2757F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 06:47:22.045516 :BCE275C0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 06:47:22.040 2025-08-08 06:47:22.045518 :BCE275C1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20808 pso_num=22 pso_serial#=155 2025-08-08 06:47:22.045667 :BCE275C2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20808 osp_idx=0 osp_ver=493397 osp_pg=0 (spawn #467033) 2025-08-08 06:47:22.045668 :BCE275C3: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-08 06:47:22.045668 :BCE275C4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=3316311345 2025-08-08 06:47:22.045669 :BCE275C5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 06:47:22.045669 :BCE275C6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 06:47:22.045669 :BCE275C7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 06:47:22.045670 :BCE275C8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 06:47:22.045670 :BCE275C9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 06:47:22.046191 :BCE275CA: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-08 06:47:22.046238 :BCE275D1: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-08 06:57:22.916200 :BCE33EDA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 06:57:23.311604 :BCE33F21:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 06:57:23.306 2025-08-08 06:57:23.311606 :BCE33F22:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8372 pso_num=22 pso_serial#=156 2025-08-08 06:57:23.311750 :BCE33F23:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8372 osp_idx=0 osp_ver=493398 osp_pg=0 (spawn #467055) 2025-08-08 06:57:23.311751 :BCE33F24: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-08 06:57:23.311751 :BCE33F25:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=1 time=3316912611 2025-08-08 06:57:23.311751 :BCE33F26:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 06:57:23.311752 :BCE33F27:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 06:57:23.311752 :BCE33F28:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 06:57:23.311752 :BCE33F29:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 06:57:23.311753 :BCE33F2A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 06:57:23.312245 :BCE33F2B: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-08 06:57:23.312293 :BCE33F33: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-08 07:07:24.279073 :BCE413A1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 07:07:24.533870 :BCE413D9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 07:07:24.529 2025-08-08 07:07:24.533873 :BCE413DA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19388 pso_num=22 pso_serial#=157 2025-08-08 07:07:24.534016 :BCE413DB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19388 osp_idx=0 osp_ver=493399 osp_pg=0 (spawn #467081) 2025-08-08 07:07:24.534016 :BCE413DC: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-08 07:07:24.534017 :BCE413DD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=2 time=3317513845 2025-08-08 07:07:24.534017 :BCE413DE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 07:07:24.534017 :BCE413DF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 07:07:24.534018 :BCE413E0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 07:07:24.534018 :BCE413E1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 07:07:24.534019 :BCE413E2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 07:07:24.534523 :BCE413E3: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-08 07:07:24.534567 :BCE413EB: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-08 07:17:25.403228 :BCE4DD26:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 07:17:25.720493 :BCE4DD66:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 07:17:25.716 2025-08-08 07:17:25.720495 :BCE4DD67:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19692 pso_num=22 pso_serial#=158 2025-08-08 07:17:25.720631 :BCE4DD68:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19692 osp_idx=0 osp_ver=493400 osp_pg=0 (spawn #467104) 2025-08-08 07:17:25.720632 :BCE4DD69: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-08 07:17:25.720632 :BCE4DD6A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=3 time=3318115033 2025-08-08 07:17:25.720632 :BCE4DD6B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 07:17:25.720633 :BCE4DD6C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 07:17:25.720633 :BCE4DD6D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 07:17:25.720633 :BCE4DD6E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 07:17:25.720634 :BCE4DD6F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 07:17:25.721125 :BCE4DD70: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-08 07:17:25.721169 :BCE4DD78: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-08 07:21:15.729295 :BCE52A03: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-08 07:27:26.956490 :BCE5A6FF: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-08 07:37:27.801999 :BCE66FFE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 07:37:27.877805 :BCE67001:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 07:37:27.864 2025-08-08 07:37:27.877807 :BCE67002:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15216 pso_num=22 pso_serial#=159 2025-08-08 07:37:27.877945 :BCE67003:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15216 (legacy spawn) 2025-08-08 07:37:27.884534 :BCE67004: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-08-08 07:37:27.884710 :BCE6700C: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-08-08 07:37:27.885570 :BCE6700E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 07:37:27.894355 :BCE67011:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 07:37:27.880 2025-08-08 07:37:27.894357 :BCE67012:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21364 pso_num=22 pso_serial#=160 2025-08-08 07:37:27.894500 :BCE67013:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21364 (legacy spawn) 2025-08-08 07:37:27.900994 :BCE67014: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-08-08 07:37:27.901163 :BCE6701C: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-08-08 07:37:27.901972 :BCE6701D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 07:37:28.228371 :BCE67060:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 07:37:28.223 2025-08-08 07:37:28.228373 :BCE67061:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20912 pso_num=22 pso_serial#=161 2025-08-08 07:37:28.228509 :BCE67062:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20912 osp_idx=0 osp_ver=493403 osp_pg=0 (spawn #467148) 2025-08-08 07:37:28.228510 :BCE67063: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-08 07:37:28.228510 :BCE67064:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=4 time=3319317533 2025-08-08 07:37:28.228510 :BCE67065:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 07:37:28.228511 :BCE67066:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 07:37:28.228511 :BCE67067:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 07:37:28.228511 :BCE67068:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 07:37:28.228512 :BCE67069:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 07:37:28.229013 :BCE6706A: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-08 07:37:28.229061 :BCE67071: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-08 07:37:58.265880 :BCE67A4E:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-08-08 07:37:58.265888 :BCE67A53:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-08-08 07:37:58.265893 :BCE67A54:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=10, exc=10, tot=10 2025-08-08 07:37:58.265904 :BCE67A55: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-08 07:47:29.099241 :BCE73955:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 07:47:29.432257 :BCE73990:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 07:47:29.427 2025-08-08 07:47:29.432259 :BCE73991:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17936 pso_num=22 pso_serial#=162 2025-08-08 07:47:29.432403 :BCE73992:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17936 osp_idx=0 osp_ver=493404 osp_pg=0 (spawn #467171) 2025-08-08 07:47:29.432404 :BCE73993: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-08 07:47:29.432404 :BCE73994:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=5 time=3319918736 2025-08-08 07:47:29.432405 :BCE73995:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 07:47:29.432405 :BCE73996:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 07:47:29.432405 :BCE73997:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 07:47:29.432405 :BCE73998:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 07:47:29.432406 :BCE73999:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 07:47:29.432908 :BCE7399A: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-08 07:47:29.432960 :BCE739A1: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-08 07:57:30.190966 :BCE80113:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-08 07:57:30.539558 :BCE80150:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-08 07:57:30.534 2025-08-08 07:57:30.539560 :BCE80151:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=636 pso_num=22 pso_serial#=163 2025-08-08 07:57:30.539694 :BCE80152:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=636 osp_idx=0 osp_ver=493405 osp_pg=0 (spawn #467193) 2025-08-08 07:57:30.539695 :BCE80153: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-08 07:57:30.539695 :BCE80154:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=3320519845 2025-08-08 07:57:30.539696 :BCE80155:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 07:57:30.539696 :BCE80156:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 07:57:30.539697 :BCE80157:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 07:57:30.539697 :BCE80158:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 07:57:30.539697 :BCE80159:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 07:57:30.540177 :BCE8015A: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-08 07:57:30.540234 :BCE80161: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: 636, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de