Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250903221927\orcl_w000_21508_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250903221927\orcl_w000_21508_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:35089M/63366M, Ph+PgF:39971M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 21508, image: ORACLE.EXE (W000) *** 2025-09-03 22:19:27.714 *** SESSION ID:(201.30843) 2025-09-03 22:19:27.714 *** 2025-09-03 22:19:27.714 Process diagnostic dump for ORACLE.EXE (W000), OS id=21508, pid: 25, proc_ser: 21, sid: 201, sess_ser: 30843 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=2.902208 sec, exc=2.902208 sec, total=2.902208 sec wait times: max=5.000000 sec, heur=42.972091 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.000028 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=5.014741 sec, exc=5.014741 sec, total=5.014741 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=5.010157 sec, exc=5.010157 sec, total=5.010157 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=5.009765 sec, exc=5.009765 sec, total=5.009765 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=5 seq_num=6 snap_id=1 wait times: snap=5.003275 sec, exc=5.003275 sec, total=5.003275 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=5.000355 sec, exc=5.000355 sec, total=5.000355 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=5.015448 sec, exc=5.015448 sec, total=5.015448 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=5.013491 sec, exc=5.013491 sec, total=5.013491 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=5.002479 sec, exc=5.002479 sec, total=5.002479 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.004121 sec of elapsed time 8: waited for 'class slave wait' slave id=0x125da4b0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 201 serial 30843 --------------------------------------------------- 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 --------------------------------------------------- [43 samples, 22:18:44 - 22:19:27] idle wait at each sample [session created at: 22:18:44] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-03 22:19:27.714 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 21508, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-03 20:08:29.291240 :C890E2E3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 20:08:29.291240 :C890E2E4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 20:08:29.291241 :C890E2E5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 20:08:29.291241 :C890E2E6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 20:08:29.291241 :C890E2E7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 20:08:29.291732 :C890E2E8: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-09-03 20:08:29.291782 :C890E2F2: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-09-03 20:18:30.069163 :C891AA8E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 20:18:30.464548 :C891AAC6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 20:18:30.459 2025-09-03 20:18:30.464550 :C891AAC7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23572 pso_num=25 pso_serial#=8 2025-09-03 20:18:30.464705 :C891AAC8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23572 osp_idx=23 osp_ver=280521 osp_pg=0 (spawn #555583) 2025-09-03 20:18:30.464706 :C891AAC9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 20:18:30.464706 :C891AACA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=208 time=1316361768 2025-09-03 20:18:30.464706 :C891AACB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 20:18:30.464707 :C891AACC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 20:18:30.464707 :C891AACD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 20:18:30.464708 :C891AACE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 20:18:30.464708 :C891AACF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 20:18:30.465260 :C891AAD0: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-09-03 20:18:30.465304 :C891AAD6: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-09-03 20:20:20.189800 :C891CE4C: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-09-03 20:28:31.703750 :C892756E: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-09-03 20:38:32.539258 :C8933D96:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 20:38:32.939676 :C8933DDB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 20:38:32.934 2025-09-03 20:38:32.939678 :C8933DDC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6008 pso_num=25 pso_serial#=9 2025-09-03 20:38:32.939818 :C8933DDD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6008 osp_idx=23 osp_ver=280522 osp_pg=0 (spawn #555627) 2025-09-03 20:38:32.939818 :C8933DDE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 20:38:32.939819 :C8933DDF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=209 time=1317564237 2025-09-03 20:38:32.939820 :C8933DE0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 20:38:32.939820 :C8933DE1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 20:38:32.939821 :C8933DE2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 20:38:32.939821 :C8933DE3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 20:38:32.939821 :C8933DE4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 20:38:32.940308 :C8933DE5: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-09-03 20:38:32.940352 :C8933DEE: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-09-03 20:48:33.679152 :C89404DC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 20:48:34.074437 :C894051F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 20:48:34.069 2025-09-03 20:48:34.074440 :C8940520:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14644 pso_num=25 pso_serial#=10 2025-09-03 20:48:34.074576 :C8940521:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14644 osp_idx=23 osp_ver=280523 osp_pg=0 (spawn #555650) 2025-09-03 20:48:34.074577 :C8940522:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 20:48:34.074577 :C8940523:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=210 time=1318165377 2025-09-03 20:48:34.074577 :C8940524:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 20:48:34.074578 :C8940525:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 20:48:34.074578 :C8940526:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 20:48:34.074578 :C8940527:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 20:48:34.074579 :C8940528:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 20:48:34.075073 :C8940529: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-09-03 20:48:34.075119 :C8940531: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-09-03 20:48:49.092598 :C8940A1E: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-09-03 20:48:49.092603 :C8940A1F: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-09-03 20:48:49.092604 :C8940A21:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=5, exc=5, tot=5 2025-09-03 20:48:49.092612 :C8940A29: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-09-03 20:58:34.922223 :C894CD1A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 20:58:35.286363 :C894CD5E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 20:58:35.281 2025-09-03 20:58:35.286365 :C894CD5F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8988 pso_num=25 pso_serial#=11 2025-09-03 20:58:35.286504 :C894CD60:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8988 osp_idx=23 osp_ver=280524 osp_pg=0 (spawn #555674) 2025-09-03 20:58:35.286504 :C894CD61:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 20:58:35.286505 :C894CD62:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=211 time=1318766596 2025-09-03 20:58:35.286505 :C894CD63:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 20:58:35.286506 :C894CD64:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 20:58:35.286506 :C894CD65:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 20:58:35.286506 :C894CD66:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 20:58:35.286506 :C894CD67:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 20:58:35.287006 :C894CD68: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-09-03 20:58:35.287050 :C894CD6E: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-09-03 21:08:36.040685 :C8959C68:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 21:08:36.293777 :C8959C77:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 21:08:36.290 2025-09-03 21:08:36.293779 :C8959C78:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22456 pso_num=25 pso_serial#=12 2025-09-03 21:08:36.293923 :C8959C79:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22456 (legacy spawn) 2025-09-03 21:08:36.300431 :C8959C7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 21:08:36.300599 :C8959C83:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-03 21:08:36.301396 :C8959C84:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 21:08:36.498515 :C8959CBE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 21:08:36.494 2025-09-03 21:08:36.498518 :C8959CBF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21600 pso_num=25 pso_serial#=13 2025-09-03 21:08:36.498662 :C8959CC0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21600 osp_idx=23 osp_ver=280526 osp_pg=0 (spawn #555699) 2025-09-03 21:08:36.498662 :C8959CC1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:08:36.498663 :C8959CC2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=212 time=1319367799 2025-09-03 21:08:36.498663 :C8959CC3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:08:36.498663 :C8959CC4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:08:36.498664 :C8959CC5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:08:36.498664 :C8959CC6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:08:36.498664 :C8959CC7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:08:36.499216 :C8959CC8: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-09-03 21:08:36.499268 :C8959CD0: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-09-03 21:18:37.310957 :C896672F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 21:18:37.324181 :C8966732:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 21:18:37.311 2025-09-03 21:18:37.324183 :C8966733:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19064 pso_num=25 pso_serial#=14 2025-09-03 21:18:37.324318 :C8966734:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19064 (legacy spawn) 2025-09-03 21:18:37.330741 :C8966735:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 21:18:37.330908 :C896673E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-03 21:18:37.331730 :C896673F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 21:18:37.784571 :C8966779:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 21:18:37.779 2025-09-03 21:18:37.784573 :C896677A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18624 pso_num=25 pso_serial#=15 2025-09-03 21:18:37.784706 :C896677B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18624 osp_idx=23 osp_ver=280528 osp_pg=0 (spawn #555722) 2025-09-03 21:18:37.784707 :C896677C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:18:37.784707 :C896677D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=213 time=1319969096 2025-09-03 21:18:37.784708 :C896677E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:18:37.784708 :C896677F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:18:37.784708 :C8966780:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:18:37.784709 :C8966781:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:18:37.784709 :C8966782:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:18:37.785273 :C8966783: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-09-03 21:18:37.785329 :C896678A: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-09-03 21:28:38.677702 :C8972EFC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 21:28:38.989392 :C8972F3C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 21:28:38.984 2025-09-03 21:28:38.989393 :C8972F3D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24328 pso_num=25 pso_serial#=16 2025-09-03 21:28:38.989537 :C8972F3E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24328 osp_idx=23 osp_ver=280529 osp_pg=0 (spawn #555745) 2025-09-03 21:28:38.989537 :C8972F3F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:28:38.989538 :C8972F40:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=1320570299 2025-09-03 21:28:38.989538 :C8972F41:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:28:38.989538 :C8972F42:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:28:38.989539 :C8972F43:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:28:38.989539 :C8972F44:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:28:38.989539 :C8972F45:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:28:38.990046 :C8972F46: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-09-03 21:28:38.990092 :C8972F4D: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-09-03 21:38:39.817692 :C897F866:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 21:38:40.166412 :C897F8A0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 21:38:40.161 2025-09-03 21:38:40.166414 :C897F8A1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21600 pso_num=25 pso_serial#=17 2025-09-03 21:38:40.166555 :C897F8A2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21600 osp_idx=23 osp_ver=280530 osp_pg=0 (spawn #555767) 2025-09-03 21:38:40.166556 :C897F8A3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:38:40.166557 :C897F8A4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=1321171471 2025-09-03 21:38:40.166557 :C897F8A5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:38:40.166558 :C897F8A6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:38:40.166558 :C897F8A7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:38:40.166559 :C897F8A8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:38:40.166559 :C897F8A9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:38:40.167140 :C897F8AA: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-09-03 21:38:40.167186 :C897F8B2: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-09-03 21:48:40.894218 :C898C0E1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 21:48:41.273992 :C898C118:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 21:48:41.269 2025-09-03 21:48:41.273994 :C898C119:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19908 pso_num=25 pso_serial#=18 2025-09-03 21:48:41.274141 :C898C11A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19908 osp_idx=23 osp_ver=280531 osp_pg=0 (spawn #555790) 2025-09-03 21:48:41.274141 :C898C11B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:48:41.274142 :C898C11C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=1321772580 2025-09-03 21:48:41.274142 :C898C11D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:48:41.274143 :C898C11E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:48:41.274143 :C898C11F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:48:41.274143 :C898C120:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:48:41.274143 :C898C121:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:48:41.274636 :C898C122: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-09-03 21:48:41.274681 :C898C129: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-09-03 21:58:42.014955 :C89989D0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 21:58:42.420445 :C8998A13:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 21:58:42.415 2025-09-03 21:58:42.420447 :C8998A14:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22272 pso_num=25 pso_serial#=19 2025-09-03 21:58:42.420580 :C8998A15:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22272 osp_idx=23 osp_ver=280532 osp_pg=0 (spawn #555813) 2025-09-03 21:58:42.420581 :C8998A16:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:58:42.420581 :C8998A17:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=1322373721 2025-09-03 21:58:42.420582 :C8998A18:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:58:42.420582 :C8998A19:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:58:42.420583 :C8998A1A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:58:42.420583 :C8998A1B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:58:42.420583 :C8998A1C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:58:42.421076 :C8998A1D: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-09-03 21:58:42.421123 :C8998A25: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-09-03 22:08:43.277849 :C89A67A5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 22:08:43.548334 :C89A67DA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 22:08:43.544 2025-09-03 22:08:43.548335 :C89A67DB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23736 pso_num=25 pso_serial#=20 2025-09-03 22:08:43.548475 :C89A67DC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23736 osp_idx=23 osp_ver=280533 osp_pg=0 (spawn #555841) 2025-09-03 22:08:43.548475 :C89A67DD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:08:43.548476 :C89A67DE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=218 time=1322974846 2025-09-03 22:08:43.548476 :C89A67DF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:08:43.548477 :C89A67E0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:08:43.548477 :C89A67E1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:08:43.548477 :C89A67E2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:08:43.548478 :C89A67E3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:08:43.548984 :C89A67E4: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-09-03 22:08:43.549035 :C89A67EC: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-09-03 22:08:58.578796 :C89A6CB1: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-09-03 22:08:58.578801 :C89A6CB6: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-09-03 22:08:58.578802 :C89A6CB7:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=7, exc=7, tot=7 2025-09-03 22:08:58.578811 :C89A6CB8: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-09-03 22:18:44.485840 :C89B33C9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-03 22:18:44.742903 :C89B3409:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-03 22:18:44.742 2025-09-03 22:18:44.742905 :C89B340A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21508 pso_num=25 pso_serial#=21 2025-09-03 22:18:44.743047 :C89B340B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21508 osp_idx=23 osp_ver=280534 osp_pg=0 (spawn #555864) 2025-09-03 22:18:44.743048 :C89B340C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:18:44.743048 :C89B340D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=219 time=1323576049 2025-09-03 22:18:44.743049 :C89B340E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:18:44.743049 :C89B340F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:18:44.743049 :C89B3410:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:18:44.743050 :C89B3411:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:18:44.743050 :C89B3412:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:18:44.743575 :C89B3413: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-09-03 22:18:44.743623 :C89B3419: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: 21508, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de