Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614060009\orcl_w000_14424_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:36689M/63366M, Ph+PgF:43176M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14424, image: ORACLE.EXE (W000) *** 2025-06-14 06:00:09.993 *** SESSION ID:(201.22183) 2025-06-14 06:00:09.993 *** 2025-06-14 06:00:09.993 Process diagnostic dump for ORACLE.EXE (W000), OS id=14424, pid: 25, proc_ser: 190, sid: 201, sess_ser: 22183 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=100 seq_num=101 snap_id=1 wait times: snap=3.053610 sec, exc=3.053610 sec, total=3.053610 sec wait times: max=5.000000 sec, heur=8 min 18 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=99 seq_num=100 snap_id=1 wait times: snap=5.001602 sec, exc=5.001602 sec, total=5.001602 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=98 seq_num=99 snap_id=1 wait times: snap=5.015292 sec, exc=5.015292 sec, total=5.015292 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=97 seq_num=98 snap_id=1 wait times: snap=5.014748 sec, exc=5.014748 sec, total=5.014748 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=5.004630 sec, exc=5.004630 sec, total=5.004630 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=95 seq_num=96 snap_id=1 wait times: snap=5.014712 sec, exc=5.014712 sec, total=5.014712 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=5.004682 sec, exc=5.004682 sec, total=5.004682 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=93 seq_num=94 snap_id=1 wait times: snap=5.007261 sec, exc=5.007261 sec, total=5.007261 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=92 seq_num=93 snap_id=1 wait times: snap=5.004008 sec, exc=5.004008 sec, total=5.004008 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000043 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=91 seq_num=92 snap_id=1 wait times: snap=5.014353 sec, exc=5.014353 sec, total=5.014353 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=90 seq_num=91 snap_id=1 wait times: snap=5.010148 sec, exc=5.010148 sec, total=5.010148 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time Sampled Session History of session 201 serial 22183 --------------------------------------------------- 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, 05:58:09 - 06:00:09] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-14 06:00:09.993 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14424, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-14 03:40:34.740577 :A4672393:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 03:40:34.740577 :A4672394:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 03:40:34.740577 :A4672395:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 03:40:34.741029 :A4672396: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-06-14 03:41:04.798020 :A4672E3B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 03:41:34.903632 :A467387B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 03:41:34.898 2025-06-14 03:41:34.903634 :A467387C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16716 pso_num=25 pso_serial#=176 2025-06-14 03:41:34.903775 :A467387D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16716 osp_idx=23 osp_ver=157113 osp_pg=0 (spawn #283272) 2025-06-14 03:41:34.903775 :A467387E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 03:41:34.903776 :A467387F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=88 time=2848238094 2025-06-14 03:41:34.903776 :A4673880:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 03:41:34.903777 :A4673881:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 03:41:34.903777 :A4673882:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 03:41:34.903777 :A4673883:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 03:41:34.903778 :A4673884:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 03:41:34.904265 :A4673885: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-06-14 03:41:34.904311 :A467388D: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-06-14 03:51:35.790269 :A46800F9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 03:51:36.185539 :A4680149:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 03:51:36.181 2025-06-14 03:51:36.185541 :A468014A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10888 pso_num=25 pso_serial#=177 2025-06-14 03:51:36.185678 :A468014B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10888 osp_idx=23 osp_ver=157114 osp_pg=0 (spawn #283295) 2025-06-14 03:51:36.185679 :A468014C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 03:51:36.185679 :A468014D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=89 time=2848839375 2025-06-14 03:51:36.185680 :A468014E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 03:51:36.185680 :A468014F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 03:51:36.185680 :A4680150:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 03:51:36.185681 :A4680151:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 03:51:36.185681 :A4680152:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 03:51:36.186178 :A4680153: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-06-14 03:51:36.186224 :A468015A: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-06-14 04:01:36.993550 :A468C9F8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 04:01:37.466811 :A468CA3E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 04:01:37.462 2025-06-14 04:01:37.466813 :A468CA3F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16092 pso_num=25 pso_serial#=178 2025-06-14 04:01:37.466951 :A468CA40:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16092 osp_idx=23 osp_ver=157115 osp_pg=0 (spawn #283320) 2025-06-14 04:01:37.466951 :A468CA41:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 04:01:37.466952 :A468CA42:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=90 time=2849440657 2025-06-14 04:01:37.466952 :A468CA43:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 04:01:37.466952 :A468CA44:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 04:01:37.466953 :A468CA45:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 04:01:37.466953 :A468CA46:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 04:01:37.466954 :A468CA47:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 04:01:37.467444 :A468CA48: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-06-14 04:01:37.467504 :A468CA52: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-06-14 04:11:38.272177 :A4699A93:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 04:11:38.823612 :A4699AE5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 04:11:38.819 2025-06-14 04:11:38.823614 :A4699AE6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9988 pso_num=25 pso_serial#=179 2025-06-14 04:11:38.823748 :A4699AE7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9988 osp_idx=23 osp_ver=157116 osp_pg=0 (spawn #283343) 2025-06-14 04:11:38.823748 :A4699AE8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 04:11:38.823749 :A4699AE9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=91 time=2850042016 2025-06-14 04:11:38.823749 :A4699AEA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 04:11:38.823749 :A4699AEB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 04:11:38.823750 :A4699AEC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 04:11:38.823750 :A4699AED:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 04:11:38.823750 :A4699AEE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 04:11:38.824254 :A4699AEF: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-06-14 04:11:38.824297 :A4699AF7: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-06-14 04:21:39.616309 :A46A616D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 04:21:39.860904 :A46A6170:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 04:21:39.850 2025-06-14 04:21:39.860906 :A46A6171:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14976 pso_num=25 pso_serial#=180 2025-06-14 04:21:39.861042 :A46A6172:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14976 (legacy spawn) 2025-06-14 04:21:39.867564 :A46A6173:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 04:21:39.867732 :A46A6178:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 04:21:39.868517 :A46A617D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 04:21:39.877147 :A46A6180:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 04:21:39.866 2025-06-14 04:21:39.877150 :A46A6181:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16120 pso_num=25 pso_serial#=181 2025-06-14 04:21:39.877330 :A46A6182:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16120 (legacy spawn) 2025-06-14 04:21:39.883890 :A46A6183:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 04:21:39.884060 :A46A618C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 04:21:39.884882 :A46A618D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 04:21:40.199426 :A46A61DE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 04:21:40.194 2025-06-14 04:21:40.199428 :A46A61DF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10700 pso_num=25 pso_serial#=182 2025-06-14 04:21:40.199576 :A46A61E0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10700 osp_idx=23 osp_ver=157119 osp_pg=0 (spawn #283366) 2025-06-14 04:21:40.199576 :A46A61E1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 04:21:40.199576 :A46A61E2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=92 time=2850643391 2025-06-14 04:21:40.199577 :A46A61E3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 04:21:40.199577 :A46A61E4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 04:21:40.199578 :A46A61E5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 04:21:40.199578 :A46A61E6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 04:21:40.199579 :A46A61E7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 04:21:40.200100 :A46A61E8: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-06-14 04:21:40.200142 :A46A61EF: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-06-14 04:31:40.968594 :A46B2919:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 04:31:41.405213 :A46B296C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 04:31:41.400 2025-06-14 04:31:41.405216 :A46B296D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13424 pso_num=25 pso_serial#=183 2025-06-14 04:31:41.405412 :A46B296E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13424 osp_idx=23 osp_ver=157120 osp_pg=0 (spawn #283389) 2025-06-14 04:31:41.405413 :A46B296F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 04:31:41.405414 :A46B2970:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=93 time=2851244594 2025-06-14 04:31:41.405414 :A46B2971:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 04:31:41.405415 :A46B2972:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 04:31:41.405415 :A46B2973:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 04:31:41.405415 :A46B2974:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 04:31:41.405415 :A46B2975:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 04:31:41.405918 :A46B2976: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-06-14 04:31:41.405961 :A46B297D: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-06-14 04:41:42.182023 :A46BF1D0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 04:41:42.764636 :A46BF218:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 04:41:42.760 2025-06-14 04:41:42.764639 :A46BF219:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16460 pso_num=25 pso_serial#=184 2025-06-14 04:41:42.764791 :A46BF21A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16460 osp_idx=23 osp_ver=157121 osp_pg=0 (spawn #283411) 2025-06-14 04:41:42.764792 :A46BF21B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 04:41:42.764792 :A46BF21C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=94 time=2851845954 2025-06-14 04:41:42.764793 :A46BF21D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 04:41:42.764793 :A46BF21E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 04:41:42.764793 :A46BF21F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 04:41:42.764794 :A46BF220:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 04:41:42.764794 :A46BF221:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 04:41:42.765297 :A46BF222: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-06-14 04:41:42.765343 :A46BF22A: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-06-14 04:45:39.799321 :A46C3FE8: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-06-14 04:51:44.025611 :A46CB8EC: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-06-14 05:01:44.829735 :A46D84E3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 05:01:45.287341 :A46D8533:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 05:01:45.282 2025-06-14 05:01:45.287343 :A46D8534:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13808 pso_num=25 pso_serial#=185 2025-06-14 05:01:45.287476 :A46D8535:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13808 osp_idx=23 osp_ver=157122 osp_pg=0 (spawn #283458) 2025-06-14 05:01:45.287477 :A46D8536:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:01:45.287478 :A46D8537:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=95 time=2853048485 2025-06-14 05:01:45.287478 :A46D8538:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:01:45.287479 :A46D8539:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:01:45.287480 :A46D853A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:01:45.287480 :A46D853B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:01:45.287480 :A46D853C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:01:45.287974 :A46D853D: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-06-14 05:01:45.288028 :A46D8547: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-06-14 05:11:46.133602 :A46E51A4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 05:11:46.607122 :A46E51ED:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 05:11:46.603 2025-06-14 05:11:46.607124 :A46E51EE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15368 pso_num=25 pso_serial#=186 2025-06-14 05:11:46.607271 :A46E51EF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15368 osp_idx=23 osp_ver=157123 osp_pg=0 (spawn #283481) 2025-06-14 05:11:46.607272 :A46E51F0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:11:46.607273 :A46E51F1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=96 time=2853649797 2025-06-14 05:11:46.607273 :A46E51F2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:11:46.607274 :A46E51F3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:11:46.607274 :A46E51F4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:11:46.607274 :A46E51F5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:11:46.607274 :A46E51F6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:11:46.607783 :A46E51F7: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-06-14 05:11:46.607831 :A46E51FE: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-06-14 05:21:47.434472 :A46F1942:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 05:21:47.735990 :A46F1987:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 05:21:47.731 2025-06-14 05:21:47.735992 :A46F1988:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14908 pso_num=25 pso_serial#=187 2025-06-14 05:21:47.736134 :A46F1989:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14908 osp_idx=23 osp_ver=157124 osp_pg=0 (spawn #283504) 2025-06-14 05:21:47.736135 :A46F198A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:21:47.736135 :A46F198B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=97 time=2854250922 2025-06-14 05:21:47.736136 :A46F198C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:21:47.736136 :A46F198D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:21:47.736136 :A46F198E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:21:47.736137 :A46F198F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:21:47.736137 :A46F1990:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:21:47.736626 :A46F1991: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-06-14 05:21:47.736670 :A46F199B: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-06-14 05:31:48.559478 :A46FE13B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 05:31:48.845407 :A46FE180:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 05:31:48.841 2025-06-14 05:31:48.845409 :A46FE181:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15196 pso_num=25 pso_serial#=188 2025-06-14 05:31:48.845545 :A46FE182:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15196 osp_idx=23 osp_ver=157125 osp_pg=0 (spawn #283527) 2025-06-14 05:31:48.845546 :A46FE183:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:31:48.845547 :A46FE184:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=98 time=2854852032 2025-06-14 05:31:48.845547 :A46FE185:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:31:48.845547 :A46FE186:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:31:48.845548 :A46FE187:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:31:48.845548 :A46FE188:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:31:48.845548 :A46FE189:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:31:48.846055 :A46FE18A: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-06-14 05:31:48.846101 :A46FE192: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-06-14 05:41:49.698999 :A470A9AE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 05:41:50.078724 :A470AA0F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 05:41:50.074 2025-06-14 05:41:50.078726 :A470AA10:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16736 pso_num=25 pso_serial#=189 2025-06-14 05:41:50.078867 :A470AA11:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16736 osp_idx=23 osp_ver=157126 osp_pg=0 (spawn #283549) 2025-06-14 05:41:50.078868 :A470AA12:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:41:50.078868 :A470AA13:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=99 time=2855453266 2025-06-14 05:41:50.078868 :A470AA14:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:41:50.078868 :A470AA15:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:41:50.078868 :A470AA16:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:41:50.078869 :A470AA17:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:41:50.078869 :A470AA18:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:41:50.079447 :A470AA19: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-06-14 05:41:50.079488 :A470AA22: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-06-14 05:51:50.890839 :A4717175:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 05:51:51.223713 :A47171BC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 05:51:51.219 2025-06-14 05:51:51.223715 :A47171BD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14424 pso_num=25 pso_serial#=190 2025-06-14 05:51:51.223857 :A47171BE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14424 osp_idx=23 osp_ver=157127 osp_pg=0 (spawn #283572) 2025-06-14 05:51:51.223858 :A47171BF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:51:51.223859 :A47171C0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=100 time=2856054407 2025-06-14 05:51:51.223859 :A47171C1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:51:51.223859 :A47171C2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:51:51.223860 :A47171C3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:51:51.223860 :A47171C4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:51:51.223860 :A47171C5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:51:51.224342 :A47171C6: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-06-14 05:51:51.224388 :A47171CE: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: 14424, W000)