Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607140510\orcl_w000_14080_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607140510\orcl_w000_14080_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:36808M/63366M, Ph+PgF:43431M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 14080, image: ORACLE.EXE (W000) *** 2025-06-07 14:05:10.789 *** SESSION ID:(177.64763) 2025-06-07 14:05:10.789 *** 2025-06-07 14:05:10.789 Process diagnostic dump for ORACLE.EXE (W000), OS id=14080, pid: 22, proc_ser: 155, sid: 177, sess_ser: 64763 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=2.641479 sec, exc=2.641479 sec, total=2.641479 sec wait times: max=5.000000 sec, heur=2 min 17 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.000017 sec since current wait 0: 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.004610 sec, exc=5.004610 sec, total=5.004610 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 1: 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.014728 sec, exc=5.014728 sec, total=5.014728 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: 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.004625 sec, exc=5.004625 sec, total=5.004625 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: 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.008318 sec, exc=5.008318 sec, total=5.008318 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: 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.001744 sec, exc=5.001744 sec, total=5.001744 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.013895 sec, exc=5.013895 sec, total=5.013895 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=5.014747 sec, exc=5.014747 sec, total=5.014747 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=22 seq_num=23 snap_id=1 wait times: snap=5.014747 sec, exc=5.014747 sec, total=5.014747 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=21 seq_num=22 snap_id=1 wait times: snap=5.010142 sec, exc=5.010142 sec, total=5.010142 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=20 seq_num=21 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.000022 sec of elapsed time Sampled Session History of session 177 serial 64763 --------------------------------------------------- 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, 14:03:10 - 14:05:10] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-07 14:05:10.789 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 14080, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 11:42:35.044922 :A17EDCA8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15988 osp_idx=0 osp_ver=274339 osp_pg=0 (spawn #261040) 2025-06-07 11:42:35.044923 :A17EDCA9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 11:42:35.044923 :A17EDCAA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=251 time=2272313688 2025-06-07 11:42:35.044924 :A17EDCAB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 11:42:35.044924 :A17EDCAC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 11:42:35.044925 :A17EDCAD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 11:42:35.044925 :A17EDCAE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 11:42:35.044926 :A17EDCAF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 11:42:35.045396 :A17EDCB0: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-06-07 11:42:35.045440 :A17EDCB8: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-06-07 11:52:35.905416 :A17FA51B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 11:52:36.300775 :A17FA557:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 11:52:36.296 2025-06-07 11:52:36.300778 :A17FA558:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15704 pso_num=22 pso_serial#=143 2025-06-07 11:52:36.300912 :A17FA559:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15704 osp_idx=0 osp_ver=274340 osp_pg=0 (spawn #261062) 2025-06-07 11:52:36.300913 :A17FA55A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 11:52:36.300913 :A17FA55B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=252 time=2272914954 2025-06-07 11:52:36.300914 :A17FA55C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 11:52:36.300914 :A17FA55D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 11:52:36.300915 :A17FA55E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 11:52:36.300915 :A17FA55F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 11:52:36.300916 :A17FA560:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 11:52:36.301413 :A17FA561: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-06-07 11:52:36.301456 :A17FA56A: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-06-07 12:01:31.731556 :A1805A62: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-06-07 12:02:37.450236 :A18072DC: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-06-07 12:12:38.173092 :A181419C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 12:12:38.568215 :A18141D2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 12:12:38.563 2025-06-07 12:12:38.568217 :A18141D3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15436 pso_num=22 pso_serial#=144 2025-06-07 12:12:38.568363 :A18141D4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15436 osp_idx=0 osp_ver=274341 osp_pg=0 (spawn #261110) 2025-06-07 12:12:38.568364 :A18141D5:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 12:12:38.568364 :A18141D6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=253 time=2274117219 2025-06-07 12:12:38.568365 :A18141D7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 12:12:38.568365 :A18141D8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 12:12:38.568365 :A18141D9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 12:12:38.568366 :A18141DA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 12:12:38.568366 :A18141DB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 12:12:38.568874 :A18141DC: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-06-07 12:12:38.568923 :A18141E4: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-06-07 12:22:39.370764 :A1820B2F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 12:22:39.750372 :A1820B73:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 12:22:39.746 2025-06-07 12:22:39.750374 :A1820B74:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13080 pso_num=22 pso_serial#=145 2025-06-07 12:22:39.750512 :A1820B75:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13080 osp_idx=0 osp_ver=274342 osp_pg=0 (spawn #261132) 2025-06-07 12:22:39.750513 :A1820B76:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 12:22:39.750513 :A1820B77:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=254 time=2274718391 2025-06-07 12:22:39.750514 :A1820B78:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 12:22:39.750514 :A1820B79:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 12:22:39.750514 :A1820B7A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 12:22:39.750515 :A1820B7B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 12:22:39.750515 :A1820B7C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 12:22:39.751079 :A1820B7D: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-06-07 12:22:39.751125 :A1820B85: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-06-07 12:32:40.651415 :A182D4FB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 12:32:40.953020 :A182D55C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 12:32:40.948 2025-06-07 12:32:40.953022 :A182D55D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13732 pso_num=22 pso_serial#=146 2025-06-07 12:32:40.953161 :A182D55E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13732 osp_idx=0 osp_ver=274343 osp_pg=0 (spawn #261155) 2025-06-07 12:32:40.953162 :A182D55F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 12:32:40.953162 :A182D560:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=255 time=2275319594 2025-06-07 12:32:40.953162 :A182D561:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 12:32:40.953163 :A182D562:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 12:32:40.953163 :A182D563:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 12:32:40.953163 :A182D564:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 12:32:40.953164 :A182D565:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 12:32:40.953654 :A182D566: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-06-07 12:32:40.953699 :A182D56E: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-06-07 12:42:41.888587 :A1839E86:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 12:42:42.096426 :A1839EBE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 12:42:42.091 2025-06-07 12:42:42.096428 :A1839EBF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5020 pso_num=22 pso_serial#=147 2025-06-07 12:42:42.096561 :A1839EC0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=5020 osp_idx=0 osp_ver=274344 osp_pg=0 (spawn #261178) 2025-06-07 12:42:42.096562 :A1839EC1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 12:42:42.096562 :A1839EC2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=2275920750 2025-06-07 12:42:42.096562 :A1839EC3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 12:42:42.096563 :A1839EC4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 12:42:42.096563 :A1839EC5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 12:42:42.096563 :A1839EC6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 12:42:42.096564 :A1839EC7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 12:42:42.097130 :A1839EC8: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-06-07 12:42:42.097182 :A1839ECF: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-06-07 12:52:42.966824 :A18467A5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 12:52:43.284972 :A18467DA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 12:52:43.280 2025-06-07 12:52:43.284975 :A18467DB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14064 pso_num=22 pso_serial#=148 2025-06-07 12:52:43.285106 :A18467DC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14064 osp_idx=0 osp_ver=274345 osp_pg=0 (spawn #261200) 2025-06-07 12:52:43.285107 :A18467DD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 12:52:43.285107 :A18467DE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=1 time=2276521938 2025-06-07 12:52:43.285107 :A18467DF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 12:52:43.285107 :A18467E0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 12:52:43.285108 :A18467E1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 12:52:43.285108 :A18467E2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 12:52:43.285108 :A18467E3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 12:52:43.285584 :A18467E4: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-06-07 12:52:43.285625 :A18467EC: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-06-07 12:53:13.317375 :A184735E: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-06-07 12:53:13.317379 :A184735F: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-06-07 12:53:13.317380 :A1847363:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=6, exc=6, tot=6 2025-06-07 12:53:13.317391 :A1847365: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-06-07 13:02:44.103856 :A18533BB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 13:02:44.359209 :A185340F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 13:02:44.354 2025-06-07 13:02:44.359211 :A1853410:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13328 pso_num=22 pso_serial#=149 2025-06-07 13:02:44.359348 :A1853411:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13328 osp_idx=0 osp_ver=274346 osp_pg=0 (spawn #261225) 2025-06-07 13:02:44.359349 :A1853412:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 13:02:44.359349 :A1853413:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=2 time=2277123000 2025-06-07 13:02:44.359350 :A1853414:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 13:02:44.359350 :A1853415:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 13:02:44.359351 :A1853416:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 13:02:44.359351 :A1853417:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 13:02:44.359351 :A1853418:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 13:02:44.359910 :A1853419: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-06-07 13:02:44.359984 :A1853423: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-06-07 13:12:45.213448 :A1860682:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 13:12:45.546323 :A18606B5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 13:12:45.541 2025-06-07 13:12:45.546325 :A18606B6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12880 pso_num=22 pso_serial#=150 2025-06-07 13:12:45.546468 :A18606B7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12880 osp_idx=0 osp_ver=274347 osp_pg=0 (spawn #261249) 2025-06-07 13:12:45.546468 :A18606B8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 13:12:45.546469 :A18606B9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=3 time=2277724188 2025-06-07 13:12:45.546469 :A18606BA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 13:12:45.546470 :A18606BB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 13:12:45.546470 :A18606BC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 13:12:45.546471 :A18606BD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 13:12:45.546471 :A18606BE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 13:12:45.546965 :A18606BF: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-06-07 13:12:45.547014 :A18606C6: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-06-07 13:22:46.506822 :A186CFC1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 13:22:46.730354 :A186CFF7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 13:22:46.726 2025-06-07 13:22:46.730356 :A186CFF8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13356 pso_num=22 pso_serial#=151 2025-06-07 13:22:46.730503 :A186CFF9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13356 osp_idx=0 osp_ver=274348 osp_pg=0 (spawn #261271) 2025-06-07 13:22:46.730503 :A186CFFA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 13:22:46.730504 :A186CFFB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=4 time=2278325375 2025-06-07 13:22:46.730504 :A186CFFC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 13:22:46.730505 :A186CFFD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 13:22:46.730505 :A186CFFE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 13:22:46.730505 :A186CFFF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 13:22:46.730506 :A186D000:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 13:22:46.731006 :A186D001: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-06-07 13:22:46.731058 :A186D008: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-06-07 13:32:47.630768 :A18799DB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 13:32:47.885350 :A1879A17:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 13:32:47.881 2025-06-07 13:32:47.885352 :A1879A18:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14556 pso_num=22 pso_serial#=152 2025-06-07 13:32:47.885488 :A1879A19:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14556 osp_idx=0 osp_ver=274349 osp_pg=0 (spawn #261294) 2025-06-07 13:32:47.885488 :A1879A1A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 13:32:47.885489 :A1879A1B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=5 time=2278926532 2025-06-07 13:32:47.885489 :A1879A1C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 13:32:47.885490 :A1879A1D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 13:32:47.885490 :A1879A1E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 13:32:47.885490 :A1879A1F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 13:32:47.885491 :A1879A20:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 13:32:47.885979 :A1879A21: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-06-07 13:32:47.886024 :A1879A28: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-06-07 13:42:48.732084 :A1886220:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 13:42:49.017960 :A1886255:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 13:42:49.013 2025-06-07 13:42:49.017962 :A1886256:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16220 pso_num=22 pso_serial#=153 2025-06-07 13:42:49.018098 :A1886257:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16220 osp_idx=0 osp_ver=274350 osp_pg=0 (spawn #261317) 2025-06-07 13:42:49.018098 :A1886258:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 13:42:49.018099 :A1886259:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=2279527672 2025-06-07 13:42:49.018099 :A188625A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 13:42:49.018100 :A188625B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 13:42:49.018100 :A188625C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 13:42:49.018100 :A188625D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 13:42:49.018101 :A188625E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 13:42:49.018650 :A188625F: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-06-07 13:42:49.018705 :A1886265: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-06-07 13:52:49.887452 :A1892BE7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 13:52:50.173402 :A1892C1F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 13:52:50.168 2025-06-07 13:52:50.173404 :A1892C20:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6572 pso_num=22 pso_serial#=154 2025-06-07 13:52:50.173537 :A1892C21:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6572 osp_idx=0 osp_ver=274351 osp_pg=0 (spawn #261339) 2025-06-07 13:52:50.173538 :A1892C22:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 13:52:50.173538 :A1892C23:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=7 time=2280128813 2025-06-07 13:52:50.173539 :A1892C24:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 13:52:50.173539 :A1892C25:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 13:52:50.173539 :A1892C26:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 13:52:50.173539 :A1892C27:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 13:52:50.173539 :A1892C28:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 13:52:50.174069 :A1892C29: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-06-07 13:52:50.174113 :A1892C30: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-06-07 14:02:50.952326 :A189F6D8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 14:02:51.316406 :A189F71C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 14:02:51.312 2025-06-07 14:02:51.316409 :A189F71D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14080 pso_num=22 pso_serial#=155 2025-06-07 14:02:51.316548 :A189F71E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14080 osp_idx=0 osp_ver=274352 osp_pg=0 (spawn #261365) 2025-06-07 14:02:51.316549 :A189F71F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 14:02:51.316549 :A189F720:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=8 time=2280729969 2025-06-07 14:02:51.316550 :A189F721:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 14:02:51.316550 :A189F722:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 14:02:51.316551 :A189F723:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 14:02:51.316551 :A189F724:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 14:02:51.316551 :A189F725:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 14:02:51.317037 :A189F726: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-06-07 14:02:51.317085 :A189F72E: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-06-07 14:02:52.910763 :A189F7CE: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 14080, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de