Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250719220351\orcl_w000_7564_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:36189M/63366M, Ph+PgF:42070M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 7564, image: ORACLE.EXE (W000) *** 2025-07-19 22:03:51.728 *** SESSION ID:(201.57909) 2025-07-19 22:03:51.728 *** 2025-07-19 22:03:51.728 Process diagnostic dump for ORACLE.EXE (W000), OS id=7564, pid: 25, proc_ser: 123, sid: 201, sess_ser: 57909 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.160915 sec, exc=0.160915 sec, total=0.160915 sec wait times: max=5.000000 sec, heur=2 min 55 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.000036 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=5.014710 sec, exc=5.014710 sec, total=5.014710 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=5.009771 sec, exc=5.009771 sec, total=5.009771 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=5.002112 sec, exc=5.002112 sec, total=5.002112 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=5.002019 sec, exc=5.002019 sec, total=5.002019 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000040 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=5.015114 sec, exc=5.015114 sec, total=5.015114 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.008883 sec, exc=5.008883 sec, total=5.008883 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 6: 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.004572 sec, exc=5.004572 sec, total=5.004572 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000081 sec of elapsed time 7: 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.014722 sec, exc=5.014722 sec, total=5.014722 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.010145 sec, exc=5.010145 sec, total=5.010145 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 9: 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.009619 sec, exc=5.009619 sec, total=5.009619 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 201 serial 57909 --------------------------------------------------- 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, 22:01:51 - 22:03:51] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-19 22:03:51.728 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 7564, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-19 19:30:36.183546 :B43C7A74:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 19:30:36.183547 :B43C7A75:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 19:30:36.184050 :B43C7A76: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-19 19:30:36.184099 :B43C7A7D: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-19 19:40:37.136203 :B43D45B9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 19:40:37.718728 :B43D45F6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 19:40:37.714 2025-07-19 19:40:37.718730 :B43D45F7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12820 pso_num=25 pso_serial#=110 2025-07-19 19:40:37.718866 :B43D45F8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12820 osp_idx=23 osp_ver=203967 osp_pg=0 (spawn #402180) 2025-07-19 19:40:37.718867 :B43D45F9: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-19 19:40:37.718867 :B43D45FA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=141 time=1634813611 2025-07-19 19:40:37.718867 :B43D45FB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 19:40:37.718868 :B43D45FC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 19:40:37.718868 :B43D45FD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 19:40:37.718869 :B43D45FE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 19:40:37.718869 :B43D45FF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 19:40:37.719372 :B43D4600: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-19 19:40:37.719416 :B43D4608: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-19 19:50:38.573494 :B43E1233:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 19:50:39.051961 :B43E1259:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 19:50:39.047 2025-07-19 19:50:39.051963 :B43E125A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16496 pso_num=25 pso_serial#=111 2025-07-19 19:50:39.052105 :B43E125B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16496 osp_idx=23 osp_ver=203968 osp_pg=0 (spawn #402204) 2025-07-19 19:50:39.052105 :B43E125C: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-19 19:50:39.052106 :B43E125D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=142 time=1635414939 2025-07-19 19:50:39.052107 :B43E125E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 19:50:39.052107 :B43E125F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 19:50:39.052107 :B43E1260:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 19:50:39.052108 :B43E1261:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 19:50:39.052108 :B43E1262:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 19:50:39.052611 :B43E1263: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-19 19:50:39.052654 :B43E126A: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-19 20:00:39.982027 :B43EDF0C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 20:00:40.408426 :B43EDF49:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 20:00:40.404 2025-07-19 20:00:40.408428 :B43EDF4A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13376 pso_num=25 pso_serial#=112 2025-07-19 20:00:40.408570 :B43EDF4B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13376 osp_idx=23 osp_ver=203969 osp_pg=0 (spawn #402228) 2025-07-19 20:00:40.408571 :B43EDF4C: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-19 20:00:40.408571 :B43EDF4D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=143 time=1636016298 2025-07-19 20:00:40.408572 :B43EDF4E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 20:00:40.408572 :B43EDF4F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 20:00:40.408573 :B43EDF50:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 20:00:40.408573 :B43EDF51:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 20:00:40.408573 :B43EDF52:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 20:00:40.409081 :B43EDF53: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-19 20:00:40.409126 :B43EDF5B: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-19 20:10:41.297327 :B43FB4FD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 20:10:41.765765 :B43FB546:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 20:10:41.761 2025-07-19 20:10:41.765767 :B43FB547:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17512 pso_num=25 pso_serial#=113 2025-07-19 20:10:41.765910 :B43FB548:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17512 osp_idx=23 osp_ver=203970 osp_pg=0 (spawn #402250) 2025-07-19 20:10:41.765911 :B43FB549: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-19 20:10:41.765911 :B43FB54A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=144 time=1636617658 2025-07-19 20:10:41.765912 :B43FB54B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 20:10:41.765912 :B43FB54C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 20:10:41.765913 :B43FB54D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 20:10:41.765913 :B43FB54E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 20:10:41.765913 :B43FB54F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 20:10:41.766422 :B43FB550: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-19 20:10:41.766470 :B43FB558: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-19 20:20:42.700383 :B4408061:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 20:20:43.065388 :B440809E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 20:20:43.060 2025-07-19 20:20:43.065390 :B440809F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18824 pso_num=25 pso_serial#=114 2025-07-19 20:20:43.065535 :B44080A0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18824 osp_idx=23 osp_ver=203971 osp_pg=0 (spawn #402273) 2025-07-19 20:20:43.065536 :B44080A1: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-19 20:20:43.065536 :B44080A2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=145 time=1637218954 2025-07-19 20:20:43.065537 :B44080A3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 20:20:43.065537 :B44080A4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 20:20:43.065538 :B44080A5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 20:20:43.065538 :B44080A6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 20:20:43.065538 :B44080A7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 20:20:43.066042 :B44080A8: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-19 20:20:43.066089 :B44080AE: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-19 20:30:44.062520 :B4414BBE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 20:30:44.411444 :B4414C07:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 20:30:44.406 2025-07-19 20:30:44.411446 :B4414C08:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9112 pso_num=25 pso_serial#=115 2025-07-19 20:30:44.411580 :B4414C09:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9112 osp_idx=23 osp_ver=203972 osp_pg=0 (spawn #402295) 2025-07-19 20:30:44.411580 :B4414C0A: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-19 20:30:44.411581 :B4414C0B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=146 time=1637820298 2025-07-19 20:30:44.411581 :B4414C0C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 20:30:44.411582 :B4414C0D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 20:30:44.411582 :B4414C0E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 20:30:44.411582 :B4414C0F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 20:30:44.411582 :B4414C10:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 20:30:44.412133 :B4414C11: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-19 20:30:44.412183 :B4414C1A: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-19 20:40:45.237833 :B4421650:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 20:40:45.758052 :B442168D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 20:40:45.753 2025-07-19 20:40:45.758054 :B442168E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1168 pso_num=25 pso_serial#=116 2025-07-19 20:40:45.758189 :B442168F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1168 osp_idx=23 osp_ver=203973 osp_pg=0 (spawn #402318) 2025-07-19 20:40:45.758190 :B4421690: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-19 20:40:45.758190 :B4421691:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=147 time=1638421658 2025-07-19 20:40:45.758191 :B4421692:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 20:40:45.758191 :B4421693:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 20:40:45.758191 :B4421694:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 20:40:45.758192 :B4421695:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 20:40:45.758192 :B4421696:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 20:40:45.758680 :B4421697: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-19 20:40:45.758733 :B442169D: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-19 20:50:46.790999 :B442E21B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 20:50:47.076830 :B442E264:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 20:50:47.072 2025-07-19 20:50:47.076832 :B442E265:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17996 pso_num=25 pso_serial#=117 2025-07-19 20:50:47.076974 :B442E266:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17996 osp_idx=23 osp_ver=203974 osp_pg=0 (spawn #402341) 2025-07-19 20:50:47.076975 :B442E267: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-19 20:50:47.076975 :B442E268:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=148 time=1639022970 2025-07-19 20:50:47.076975 :B442E269:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 20:50:47.076976 :B442E26A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 20:50:47.076976 :B442E26B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 20:50:47.076976 :B442E26C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 20:50:47.076977 :B442E26D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 20:50:47.077481 :B442E26E: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-19 20:50:47.077528 :B442E274: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-19 20:52:43.011350 :B443093A: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-19 21:00:48.421377 :B443AF5E: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-19 21:10:49.377153 :B44483BD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 21:10:49.741473 :B4448413:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 21:10:49.736 2025-07-19 21:10:49.741475 :B4448414:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14420 pso_num=25 pso_serial#=118 2025-07-19 21:10:49.741616 :B4448415:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14420 osp_idx=23 osp_ver=203975 osp_pg=0 (spawn #402388) 2025-07-19 21:10:49.741617 :B4448416: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-19 21:10:49.741617 :B4448417:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=149 time=1640225642 2025-07-19 21:10:49.741617 :B4448418:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 21:10:49.741617 :B4448419:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 21:10:49.741618 :B444841A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 21:10:49.741618 :B444841B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 21:10:49.741618 :B444841C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 21:10:49.742136 :B444841D: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-19 21:10:49.742194 :B4448426: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-19 21:20:50.576679 :B4454EE2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 21:20:51.050040 :B4454F1F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 21:20:51.045 2025-07-19 21:20:51.050042 :B4454F20:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13260 pso_num=25 pso_serial#=119 2025-07-19 21:20:51.050179 :B4454F21:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13260 osp_idx=23 osp_ver=203976 osp_pg=0 (spawn #402411) 2025-07-19 21:20:51.050180 :B4454F22: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-19 21:20:51.050180 :B4454F23:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=150 time=1640826939 2025-07-19 21:20:51.050181 :B4454F24:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 21:20:51.050181 :B4454F25:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 21:20:51.050181 :B4454F26:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 21:20:51.050182 :B4454F27:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 21:20:51.050182 :B4454F28:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 21:20:51.050673 :B4454F29: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-19 21:20:51.050717 :B4454F32: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-19 21:30:52.003736 :B4461A0C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 21:30:52.320868 :B4461A49:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 21:30:52.315 2025-07-19 21:30:52.320871 :B4461A4A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19396 pso_num=25 pso_serial#=120 2025-07-19 21:30:52.321010 :B4461A4B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19396 osp_idx=23 osp_ver=203977 osp_pg=0 (spawn #402433) 2025-07-19 21:30:52.321010 :B4461A4C: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-19 21:30:52.321011 :B4461A4D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=151 time=1641428220 2025-07-19 21:30:52.321011 :B4461A4E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 21:30:52.321012 :B4461A4F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 21:30:52.321012 :B4461A50:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 21:30:52.321012 :B4461A51:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 21:30:52.321013 :B4461A52:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 21:30:52.321505 :B4461A53: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-19 21:30:52.321550 :B4461A5A: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-19 21:40:53.246189 :B446E5FB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 21:40:53.594586 :B446E66C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 21:40:53.590 2025-07-19 21:40:53.594588 :B446E66D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4284 pso_num=25 pso_serial#=121 2025-07-19 21:40:53.594727 :B446E66E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4284 osp_idx=23 osp_ver=203978 osp_pg=0 (spawn #402456) 2025-07-19 21:40:53.594728 :B446E66F: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-19 21:40:53.594728 :B446E670:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=152 time=1642029486 2025-07-19 21:40:53.594728 :B446E671:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 21:40:53.594729 :B446E672:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 21:40:53.594729 :B446E673:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 21:40:53.594729 :B446E674:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 21:40:53.594730 :B446E675:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 21:40:53.595217 :B446E676: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-19 21:40:53.595264 :B446E680: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-19 21:50:54.574228 :B447B1CE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 21:50:54.985013 :B447B233:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 21:50:54.980 2025-07-19 21:50:54.985015 :B447B234:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19364 pso_num=25 pso_serial#=122 2025-07-19 21:50:54.985156 :B447B235:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19364 osp_idx=23 osp_ver=203979 osp_pg=0 (spawn #402479) 2025-07-19 21:50:54.985156 :B447B236: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-19 21:50:54.985157 :B447B237:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=153 time=1642630876 2025-07-19 21:50:54.985157 :B447B238:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 21:50:54.985157 :B447B239:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 21:50:54.985158 :B447B23A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 21:50:54.985158 :B447B23B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 21:50:54.985159 :B447B23C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 21:50:54.985698 :B447B23D: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-19 21:50:54.985742 :B447B246: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-19 22:00:55.920553 :B4487F26:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-19 22:00:56.285480 :B4487F97:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-19 22:00:56.280 2025-07-19 22:00:56.285482 :B4487F98:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7564 pso_num=25 pso_serial#=123 2025-07-19 22:00:56.285619 :B4487F99:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7564 osp_idx=23 osp_ver=203980 osp_pg=0 (spawn #402503) 2025-07-19 22:00:56.285619 :B4487F9A: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-19 22:00:56.285620 :B4487F9B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=154 time=1643232173 2025-07-19 22:00:56.285620 :B4487F9C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 22:00:56.285621 :B4487F9D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 22:00:56.285621 :B4487F9E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 22:00:56.285621 :B4487F9F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 22:00:56.285621 :B4487FA0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 22:00:56.286114 :B4487FA1: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-19 22:00:56.286159 :B4487FA9: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: 7564, W000)