Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250719060009\orcl_w000_15704_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:42078M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15704, image: ORACLE.EXE (W000) *** 2025-07-19 06:00:09.220 *** SESSION ID:(177.15467) 2025-07-19 06:00:09.220 *** 2025-07-19 06:00:09.220 Process diagnostic dump for ORACLE.EXE (W000), OS id=15704, pid: 22, proc_ser: 69, sid: 177, sess_ser: 15467 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=3.144299 sec, exc=3.144299 sec, total=3.144299 sec wait times: max=5.000000 sec, heur=1 min 23 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.000030 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=5.013624 sec, exc=5.013624 sec, total=5.013624 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=15 seq_num=16 snap_id=1 wait times: snap=5.004648 sec, exc=5.004648 sec, total=5.004648 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=5.004623 sec, exc=5.004623 sec, total=5.004623 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=13 seq_num=14 snap_id=1 wait times: snap=5.014746 sec, exc=5.014746 sec, total=5.014746 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=5.008335 sec, exc=5.008335 sec, total=5.008335 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=5.000278 sec, exc=5.000278 sec, total=5.000278 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=5.002176 sec, exc=5.002176 sec, total=5.002176 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=5.013782 sec, exc=5.013782 sec, total=5.013782 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 8: 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.004640 sec, exc=5.004640 sec, total=5.004640 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 9: 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.004587 sec, exc=5.004587 sec, total=5.004587 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time Sampled Session History of session 177 serial 15467 --------------------------------------------------- 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 --------------------------------------------------- [84 samples, 05:58:45 - 06:00:09] idle wait at each sample [session created at: 05:58:45] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-19 06:00:09.220 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15704, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-19 03:38:26.579987 :B3EF8D41:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 03:38:26.579987 :B3EF8D42:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 03:38:26.580553 :B3EF8D43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 03:38:26.580614 :B3EF8D49:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 03:48:27.481914 :B3F058A3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 03:48:27.902913 :B3F058C9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 03:48:27.898 2025-07-19 03:48:27.902915 :B3F058CA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14100 pso_num=22 pso_serial#=55 2025-07-19 03:48:27.903048 :B3F058CB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14100 osp_idx=0 osp_ver=419857 osp_pg=0 (spawn #399969) 2025-07-19 03:48:27.903049 :B3F058CC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 03:48:27.903049 :B3F058CD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=169 time=1577683798 2025-07-19 03:48:27.903049 :B3F058CE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 03:48:27.903050 :B3F058CF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 03:48:27.903050 :B3F058D0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 03:48:27.903051 :B3F058D1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 03:48:27.903051 :B3F058D2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 03:48:27.903581 :B3F058D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 03:48:27.903624 :B3F058DA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 03:58:28.874943 :B3F1239E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 03:58:29.317303 :B3F123C4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 03:58:29.312 2025-07-19 03:58:29.317305 :B3F123C5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18588 pso_num=22 pso_serial#=56 2025-07-19 03:58:29.317445 :B3F123C6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18588 osp_idx=0 osp_ver=419858 osp_pg=0 (spawn #399991) 2025-07-19 03:58:29.317446 :B3F123C7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 03:58:29.317446 :B3F123C8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=170 time=1578285204 2025-07-19 03:58:29.317447 :B3F123C9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 03:58:29.317447 :B3F123CA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 03:58:29.317447 :B3F123CB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 03:58:29.317448 :B3F123CC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 03:58:29.317448 :B3F123CD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 03:58:29.317954 :B3F123CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 03:58:29.317998 :B3F123D6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 04:08:30.301793 :B3F1F7CD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 04:08:30.665920 :B3F1F7F3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 04:08:30.661 2025-07-19 04:08:30.665922 :B3F1F7F4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18780 pso_num=22 pso_serial#=57 2025-07-19 04:08:30.666060 :B3F1F7F5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18780 osp_idx=0 osp_ver=419859 osp_pg=0 (spawn #400017) 2025-07-19 04:08:30.666061 :B3F1F7F6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 04:08:30.666062 :B3F1F7F7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=171 time=1578886564 2025-07-19 04:08:30.666062 :B3F1F7F8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 04:08:30.666063 :B3F1F7F9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 04:08:30.666063 :B3F1F7FA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 04:08:30.666063 :B3F1F7FB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 04:08:30.666063 :B3F1F7FC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 04:08:30.666570 :B3F1F7FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 04:08:30.666614 :B3F1F805:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 04:18:31.524104 :B3F2C5A1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 04:18:32.091501 :B3F2C5E0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 04:18:32.087 2025-07-19 04:18:32.091503 :B3F2C5E1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19380 pso_num=22 pso_serial#=58 2025-07-19 04:18:32.091646 :B3F2C5E2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19380 osp_idx=0 osp_ver=419860 osp_pg=0 (spawn #400040) 2025-07-19 04:18:32.091647 :B3F2C5E3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 04:18:32.091647 :B3F2C5E4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=172 time=1579487986 2025-07-19 04:18:32.091647 :B3F2C5E5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 04:18:32.091647 :B3F2C5E6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 04:18:32.091648 :B3F2C5E7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 04:18:32.091648 :B3F2C5E8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 04:18:32.091648 :B3F2C5E9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 04:18:32.092155 :B3F2C5EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 04:18:32.092203 :B3F2C5F2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 04:28:33.129687 :B3F39092:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 04:28:33.603314 :B3F390B8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 04:28:33.598 2025-07-19 04:28:33.603316 :B3F390B9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15520 pso_num=22 pso_serial#=59 2025-07-19 04:28:33.603460 :B3F390BA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15520 osp_idx=0 osp_ver=419861 osp_pg=0 (spawn #400062) 2025-07-19 04:28:33.603460 :B3F390BB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 04:28:33.603461 :B3F390BC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=173 time=1580089501 2025-07-19 04:28:33.603461 :B3F390BD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 04:28:33.603462 :B3F390BE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 04:28:33.603462 :B3F390BF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 04:28:33.603462 :B3F390C0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 04:28:33.603463 :B3F390C1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 04:28:33.603964 :B3F390C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 04:28:33.604009 :B3F390CA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 04:36:51.932453 :B3F439BA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-19 04:38:34.920961 :B3F45C82:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-19 04:48:35.846184 :B3F52859:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 04:48:36.335069 :B3F5287F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 04:48:36.330 2025-07-19 04:48:36.335071 :B3F52880:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10112 pso_num=22 pso_serial#=60 2025-07-19 04:48:36.335206 :B3F52881:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10112 osp_idx=0 osp_ver=419862 osp_pg=0 (spawn #400107) 2025-07-19 04:48:36.335207 :B3F52882:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 04:48:36.335208 :B3F52883:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=174 time=1581292236 2025-07-19 04:48:36.335209 :B3F52884:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 04:48:36.335209 :B3F52885:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 04:48:36.335209 :B3F52886:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 04:48:36.335210 :B3F52887:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 04:48:36.335210 :B3F52888:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 04:48:36.335711 :B3F52889:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 04:48:36.335757 :B3F52890:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 04:58:37.244305 :B3F5F358:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 04:58:37.780359 :B3F5F38A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 04:58:37.776 2025-07-19 04:58:37.780361 :B3F5F38B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14300 pso_num=22 pso_serial#=61 2025-07-19 04:58:37.780497 :B3F5F38C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14300 osp_idx=0 osp_ver=419863 osp_pg=0 (spawn #400129) 2025-07-19 04:58:37.780498 :B3F5F38D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 04:58:37.780498 :B3F5F38E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=175 time=1581893673 2025-07-19 04:58:37.780498 :B3F5F38F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 04:58:37.780499 :B3F5F390:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 04:58:37.780499 :B3F5F391:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 04:58:37.780499 :B3F5F392:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 04:58:37.780500 :B3F5F393:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 04:58:37.780991 :B3F5F394:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 04:58:37.781049 :B3F5F39B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 05:08:38.746811 :B3F6C764:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 05:08:39.142119 :B3F6C78A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 05:08:39.137 2025-07-19 05:08:39.142121 :B3F6C78B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16496 pso_num=22 pso_serial#=62 2025-07-19 05:08:39.142259 :B3F6C78C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16496 osp_idx=0 osp_ver=419864 osp_pg=0 (spawn #400155) 2025-07-19 05:08:39.142260 :B3F6C78D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 05:08:39.142262 :B3F6C78E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=176 time=1582495033 2025-07-19 05:08:39.142262 :B3F6C78F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 05:08:39.142263 :B3F6C790:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 05:08:39.142263 :B3F6C791:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 05:08:39.142263 :B3F6C792:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 05:08:39.142263 :B3F6C793:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 05:08:39.142768 :B3F6C794:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 05:08:39.142811 :B3F6C79C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 05:18:40.117023 :B3F79578:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 05:18:40.543520 :B3F795AA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 05:18:40.538 2025-07-19 05:18:40.543522 :B3F795AB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=208 pso_num=22 pso_serial#=63 2025-07-19 05:18:40.543656 :B3F795AC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=208 osp_idx=0 osp_ver=419865 osp_pg=0 (spawn #400178) 2025-07-19 05:18:40.543657 :B3F795AD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 05:18:40.543657 :B3F795AE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=177 time=1583096439 2025-07-19 05:18:40.543658 :B3F795AF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 05:18:40.543658 :B3F795B0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 05:18:40.543658 :B3F795B1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 05:18:40.543659 :B3F795B2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 05:18:40.543659 :B3F795B3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 05:18:40.544144 :B3F795B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 05:18:40.544188 :B3F795BE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 05:28:41.561462 :B3F86099:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 05:28:41.951358 :B3F860BF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 05:28:41.946 2025-07-19 05:28:41.951360 :B3F860C0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16572 pso_num=22 pso_serial#=64 2025-07-19 05:28:41.951498 :B3F860C1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16572 osp_idx=0 osp_ver=419866 osp_pg=0 (spawn #400200) 2025-07-19 05:28:41.951499 :B3F860C2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 05:28:41.951499 :B3F860C3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=178 time=1583697845 2025-07-19 05:28:41.951500 :B3F860C4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 05:28:41.951500 :B3F860C5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 05:28:41.951501 :B3F860C6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 05:28:41.951501 :B3F860C7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 05:28:41.951501 :B3F860C8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 05:28:41.951986 :B3F860C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 05:28:41.952043 :B3F860D2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 05:38:42.801305 :B3F92B56:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 05:38:42.878962 :B3F92B59:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 05:38:42.864 2025-07-19 05:38:42.878964 :B3F92B5A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16988 pso_num=22 pso_serial#=65 2025-07-19 05:38:42.879136 :B3F92B5B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16988 (legacy spawn) 2025-07-19 05:38:42.885581 :B3F92B5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 05:38:42.885766 :B3F92B61:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-19 05:38:42.886558 :B3F92B66:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 05:38:42.895274 :B3F92B69:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 05:38:42.895 2025-07-19 05:38:42.895275 :B3F92B6A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16516 pso_num=22 pso_serial#=66 2025-07-19 05:38:42.895409 :B3F92B6B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16516 (legacy spawn) 2025-07-19 05:38:42.901852 :B3F92B6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 05:38:42.902016 :B3F92B75:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-19 05:38:42.902807 :B3F92B76:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 05:38:43.321585 :B3F92B9D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 05:38:43.317 2025-07-19 05:38:43.321587 :B3F92B9E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19000 pso_num=22 pso_serial#=67 2025-07-19 05:38:43.321724 :B3F92B9F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19000 osp_idx=0 osp_ver=419869 osp_pg=0 (spawn #400223) 2025-07-19 05:38:43.321725 :B3F92BA0:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 05:38:43.321725 :B3F92BA1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=179 time=1584299220 2025-07-19 05:38:43.321726 :B3F92BA2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 05:38:43.321726 :B3F92BA3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 05:38:43.321726 :B3F92BA4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 05:38:43.321727 :B3F92BA5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 05:38:43.321727 :B3F92BA6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 05:38:43.322216 :B3F92BA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 05:38:43.322261 :B3F92BAF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 05:48:44.306865 :B3F9F755:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 05:48:44.624091 :B3F9F779:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 05:48:44.619 2025-07-19 05:48:44.624093 :B3F9F77A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16944 pso_num=22 pso_serial#=68 2025-07-19 05:48:44.624231 :B3F9F77B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16944 osp_idx=0 osp_ver=419870 osp_pg=0 (spawn #400246) 2025-07-19 05:48:44.624231 :B3F9F77C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 05:48:44.624231 :B3F9F77D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=180 time=1584900517 2025-07-19 05:48:44.624232 :B3F9F77E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 05:48:44.624232 :B3F9F77F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 05:48:44.624233 :B3F9F780:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 05:48:44.624233 :B3F9F781:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 05:48:44.624234 :B3F9F782:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 05:48:44.624727 :B3F9F783:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 05:48:44.624774 :B3F9F78B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 05:58:45.483065 :B3FAC22A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 05:58:45.972240 :B3FAC251:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 05:58:45.967 2025-07-19 05:58:45.972242 :B3FAC252:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15704 pso_num=22 pso_serial#=69 2025-07-19 05:58:45.972373 :B3FAC253:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15704 osp_idx=0 osp_ver=419871 osp_pg=0 (spawn #400268) 2025-07-19 05:58:45.972374 :B3FAC254:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 05:58:45.972374 :B3FAC255:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=181 time=1585501861 2025-07-19 05:58:45.972374 :B3FAC256:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 05:58:45.972375 :B3FAC257:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 05:58:45.972375 :B3FAC258:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 05:58:45.972376 :B3FAC259:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 05:58:45.972376 :B3FAC25A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 05:58:45.972859 :B3FAC25B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 05:58:45.972922 :B3FAC265:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 15704, W000)