Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250722220008\orcl_w000_18548_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:36070M/63366M, Ph+PgF:41887M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 18548, image: ORACLE.EXE (W000) *** 2025-07-22 22:00:08.191 *** SESSION ID:(201.4669) 2025-07-22 22:00:08.191 *** 2025-07-22 22:00:08.191 Process diagnostic dump for ORACLE.EXE (W000), OS id=18548, pid: 25, proc_ser: 98, sid: 201, sess_ser: 4669 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=118 seq_num=119 snap_id=1 wait times: snap=1.629262 sec, exc=1.629262 sec, total=1.629262 sec wait times: max=5.000000 sec, heur=9 min 47 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.000026 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=117 seq_num=118 snap_id=1 wait times: snap=5.014816 sec, exc=5.014816 sec, total=5.014816 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=116 seq_num=117 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.000025 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=115 seq_num=116 snap_id=1 wait times: snap=5.015250 sec, exc=5.015250 sec, total=5.015250 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=114 seq_num=115 snap_id=1 wait times: snap=5.003380 sec, exc=5.003380 sec, total=5.003380 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=113 seq_num=114 snap_id=1 wait times: snap=5.000425 sec, exc=5.000425 sec, total=5.000425 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=112 seq_num=113 snap_id=1 wait times: snap=5.014088 sec, exc=5.014088 sec, total=5.014088 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=111 seq_num=112 snap_id=1 wait times: snap=5.014750 sec, exc=5.014750 sec, total=5.014750 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=110 seq_num=111 snap_id=1 wait times: snap=5.014724 sec, exc=5.014724 sec, total=5.014724 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=109 seq_num=110 snap_id=1 wait times: snap=5.004622 sec, exc=5.004622 sec, total=5.004622 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=108 seq_num=109 snap_id=1 wait times: snap=5.004639 sec, exc=5.004639 sec, total=5.004639 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time Sampled Session History of session 201 serial 4669 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 21:58:08 - 22:00:08] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-22 22:00:08.191 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 18548, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-22 19:29:01.748042 :B592CAE5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 19:29:01.748042 :B592CAE6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 19:29:01.748042 :B592CAE7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 19:29:01.748043 :B592CAE8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 19:29:01.748545 :B592CAE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 19:29:31.807572 :B592D496:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 19:30:02.131630 :B592DF44:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 19:30:02.126 2025-07-22 19:30:02.131632 :B592DF45:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11428 pso_num=25 pso_serial#=85 2025-07-22 19:30:02.131790 :B592DF46:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11428 osp_idx=23 osp_ver=209552 osp_pg=0 (spawn #412136) 2025-07-22 19:30:02.131790 :B592DF47:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 19:30:02.131791 :B592DF48:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=1893378033 2025-07-22 19:30:02.131791 :B592DF49:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 19:30:02.131792 :B592DF4A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 19:30:02.131792 :B592DF4B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 19:30:02.131793 :B592DF4C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 19:30:02.131793 :B592DF4D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 19:30:02.132386 :B592DF4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 19:30:02.132457 :B592DF58:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 19:40:02.973657 :B593A53D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 19:40:03.525170 :B593A5BE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 19:40:03.520 2025-07-22 19:40:03.525172 :B593A5BF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19320 pso_num=25 pso_serial#=86 2025-07-22 19:40:03.525315 :B593A5C0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19320 osp_idx=23 osp_ver=209553 osp_pg=0 (spawn #412158) 2025-07-22 19:40:03.525315 :B593A5C1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 19:40:03.525315 :B593A5C2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=254 time=1893979423 2025-07-22 19:40:03.525316 :B593A5C3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 19:40:03.525316 :B593A5C4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 19:40:03.525317 :B593A5C5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 19:40:03.525317 :B593A5C6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 19:40:03.525317 :B593A5C7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 19:40:03.525826 :B593A5C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 19:40:03.525872 :B593A5D2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 19:50:04.515292 :B5946CAC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 19:50:04.926210 :B5946D29:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 19:50:04.921 2025-07-22 19:50:04.926212 :B5946D2A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16332 pso_num=25 pso_serial#=87 2025-07-22 19:50:04.926358 :B5946D2B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16332 osp_idx=23 osp_ver=209554 osp_pg=0 (spawn #412181) 2025-07-22 19:50:04.926359 :B5946D2C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 19:50:04.926359 :B5946D2D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=255 time=1894580814 2025-07-22 19:50:04.926360 :B5946D2E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 19:50:04.926360 :B5946D2F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 19:50:04.926361 :B5946D30:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 19:50:04.926361 :B5946D31:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 19:50:04.926361 :B5946D32:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 19:50:04.926870 :B5946D33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 19:50:04.926915 :B5946D3B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 20:00:05.908089 :B595354B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 20:00:06.209946 :B59535D6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 20:00:06.204 2025-07-22 20:00:06.209948 :B59535D7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17200 pso_num=25 pso_serial#=88 2025-07-22 20:00:06.210093 :B59535D8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17200 osp_idx=23 osp_ver=209555 osp_pg=0 (spawn #412205) 2025-07-22 20:00:06.210094 :B59535D9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 20:00:06.210094 :B59535DA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=0 time=1895182111 2025-07-22 20:00:06.210095 :B59535DB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 20:00:06.210095 :B59535DC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 20:00:06.210096 :B59535DD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 20:00:06.210096 :B59535DE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 20:00:06.210097 :B59535DF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 20:00:06.210594 :B59535E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 20:00:06.210653 :B59535EA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 20:10:06.978865 :B5960464:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 20:10:07.484408 :B59604EC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 20:10:07.479 2025-07-22 20:10:07.484410 :B59604ED:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9912 pso_num=25 pso_serial#=89 2025-07-22 20:10:07.484545 :B59604EE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9912 osp_idx=23 osp_ver=209556 osp_pg=0 (spawn #412230) 2025-07-22 20:10:07.484545 :B59604EF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 20:10:07.484545 :B59604F0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=1 time=1895783376 2025-07-22 20:10:07.484546 :B59604F1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 20:10:07.484546 :B59604F2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 20:10:07.484546 :B59604F3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 20:10:07.484547 :B59604F4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 20:10:07.484547 :B59604F5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 20:10:07.485047 :B59604F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 20:10:07.485093 :B59604FE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 20:20:08.462822 :B596CE56:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 20:20:08.813416 :B596CEBA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 20:20:08.808 2025-07-22 20:20:08.813417 :B596CEBB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18636 pso_num=25 pso_serial#=90 2025-07-22 20:20:08.813554 :B596CEBC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18636 osp_idx=23 osp_ver=209557 osp_pg=0 (spawn #412253) 2025-07-22 20:20:08.813554 :B596CEBD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 20:20:08.813555 :B596CEBE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=2 time=1896384704 2025-07-22 20:20:08.813555 :B596CEBF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 20:20:08.813556 :B596CEC0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 20:20:08.813556 :B596CEC1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 20:20:08.813557 :B596CEC2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 20:20:08.813557 :B596CEC3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 20:20:08.814067 :B596CEC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 20:20:08.814118 :B596CECE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 20:23:57.847705 :B5971A48:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-22 20:30:10.141240 :B59795F1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-22 20:40:11.093201 :B5985D9E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 20:40:11.488197 :B5985E18:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 20:40:11.483 2025-07-22 20:40:11.488199 :B5985E19:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11148 pso_num=25 pso_serial#=91 2025-07-22 20:40:11.488340 :B5985E1A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11148 osp_idx=23 osp_ver=209558 osp_pg=0 (spawn #412297) 2025-07-22 20:40:11.488341 :B5985E1B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 20:40:11.488341 :B5985E1C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=3 time=1897587376 2025-07-22 20:40:11.488341 :B5985E1D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 20:40:11.488342 :B5985E1E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 20:40:11.488342 :B5985E1F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 20:40:11.488343 :B5985E20:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 20:40:11.488343 :B5985E21:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 20:40:11.488850 :B5985E22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 20:40:11.488895 :B5985E29:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 20:50:12.361533 :B59924BF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 20:50:12.741578 :B5992548:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 20:50:12.736 2025-07-22 20:50:12.741581 :B5992549:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17516 pso_num=25 pso_serial#=92 2025-07-22 20:50:12.741727 :B599254A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17516 osp_idx=23 osp_ver=209559 osp_pg=0 (spawn #412320) 2025-07-22 20:50:12.741728 :B599254B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 20:50:12.741729 :B599254C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=4 time=1898188642 2025-07-22 20:50:12.741729 :B599254D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 20:50:12.741729 :B599254E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 20:50:12.741730 :B599254F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 20:50:12.741730 :B5992550:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 20:50:12.741731 :B5992551:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 20:50:12.742218 :B5992552:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 20:50:12.742264 :B599255C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 21:00:13.727330 :B599EDB2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 21:00:14.044571 :B599EE0D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 21:00:14.039 2025-07-22 21:00:14.044573 :B599EE0E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1740 pso_num=25 pso_serial#=93 2025-07-22 21:00:14.044707 :B599EE0F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1740 osp_idx=23 osp_ver=209560 osp_pg=0 (spawn #412343) 2025-07-22 21:00:14.044708 :B599EE10:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 21:00:14.044708 :B599EE11:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=5 time=1898789939 2025-07-22 21:00:14.044709 :B599EE12:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:00:14.044709 :B599EE13:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:00:14.044709 :B599EE14:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:00:14.044710 :B599EE15:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:00:14.044710 :B599EE16:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:00:14.045268 :B599EE17:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 21:00:14.045315 :B599EE1F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 21:10:14.929602 :B59ABCAE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 21:10:15.247359 :B59ABD24:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 21:10:15.242 2025-07-22 21:10:15.247361 :B59ABD25:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10908 pso_num=25 pso_serial#=94 2025-07-22 21:10:15.247499 :B59ABD26:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10908 osp_idx=23 osp_ver=209561 osp_pg=0 (spawn #412368) 2025-07-22 21:10:15.247499 :B59ABD27:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 21:10:15.247500 :B59ABD28:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=6 time=1899391142 2025-07-22 21:10:15.247500 :B59ABD29:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:10:15.247501 :B59ABD2A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:10:15.247501 :B59ABD2B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:10:15.247501 :B59ABD2C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:10:15.247501 :B59ABD2D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:10:15.247991 :B59ABD2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 21:10:15.248032 :B59ABD36:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 21:20:16.184549 :B59B86C4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 21:20:16.533524 :B59B8740:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 21:20:16.528 2025-07-22 21:20:16.533526 :B59B8741:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18156 pso_num=25 pso_serial#=95 2025-07-22 21:20:16.533671 :B59B8742:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18156 osp_idx=23 osp_ver=209562 osp_pg=0 (spawn #412391) 2025-07-22 21:20:16.533671 :B59B8743:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 21:20:16.533672 :B59B8744:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=7 time=1899992423 2025-07-22 21:20:16.533672 :B59B8745:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:20:16.533673 :B59B8746:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:20:16.533673 :B59B8747:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:20:16.533673 :B59B8748:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:20:16.533674 :B59B8749:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:20:16.534175 :B59B874A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 21:20:16.534222 :B59B8754:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 21:30:17.378116 :B59C4DED:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 21:30:17.830210 :B59C4E7B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 21:30:17.825 2025-07-22 21:30:17.830212 :B59C4E7C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=280 pso_num=25 pso_serial#=96 2025-07-22 21:30:17.830347 :B59C4E7D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=280 osp_idx=23 osp_ver=209563 osp_pg=0 (spawn #412414) 2025-07-22 21:30:17.830348 :B59C4E7E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 21:30:17.830348 :B59C4E7F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=8 time=1900593720 2025-07-22 21:30:17.830349 :B59C4E80:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:30:17.830349 :B59C4E81:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:30:17.830349 :B59C4E82:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:30:17.830350 :B59C4E83:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:30:17.830350 :B59C4E84:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:30:17.830854 :B59C4E85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 21:30:17.830902 :B59C4E8C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 21:40:18.801385 :B59D1553:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 21:40:19.165943 :B59D15D7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 21:40:19.161 2025-07-22 21:40:19.165946 :B59D15D8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4192 pso_num=25 pso_serial#=97 2025-07-22 21:40:19.166092 :B59D15D9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4192 osp_idx=23 osp_ver=209564 osp_pg=0 (spawn #412436) 2025-07-22 21:40:19.166093 :B59D15DA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 21:40:19.166093 :B59D15DB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=9 time=1901195064 2025-07-22 21:40:19.166094 :B59D15DC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:40:19.166094 :B59D15DD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:40:19.166094 :B59D15DE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:40:19.166094 :B59D15DF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:40:19.166095 :B59D15E0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:40:19.166620 :B59D15E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 21:40:19.166670 :B59D15EB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 21:50:20.195983 :B59DDCE6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-22 21:50:20.575479 :B59DDD5D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-22 21:50:20.571 2025-07-22 21:50:20.575481 :B59DDD5E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18548 pso_num=25 pso_serial#=98 2025-07-22 21:50:20.575615 :B59DDD5F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18548 osp_idx=23 osp_ver=209565 osp_pg=0 (spawn #412459) 2025-07-22 21:50:20.575616 :B59DDD60:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 21:50:20.575616 :B59DDD61:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=10 time=1901796470 2025-07-22 21:50:20.575617 :B59DDD62:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:50:20.575617 :B59DDD63:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:50:20.575618 :B59DDD64:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:50:20.575618 :B59DDD65:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:50:20.575619 :B59DDD66:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:50:20.576161 :B59DDD67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 21:50:20.576205 :B59DDD6F: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: 18548, W000)