Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614180558\orcl_w000_16764_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:36683M/63366M, Ph+PgF:43169M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 16764, image: ORACLE.EXE (W000) *** 2025-06-14 18:05:58.913 *** SESSION ID:(177.8613) 2025-06-14 18:05:58.913 *** 2025-06-14 18:05:58.913 Process diagnostic dump for ORACLE.EXE (W000), OS id=16764, pid: 22, proc_ser: 193, sid: 177, sess_ser: 8613 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=4.214410 sec, exc=4.214410 sec, total=4.214410 sec wait times: max=5.000000 sec, heur=2 min 39 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.000046 sec since current wait 0: 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.014726 sec, exc=5.014726 sec, total=5.014726 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 1: 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.004598 sec, exc=5.004598 sec, total=5.004598 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000042 sec of elapsed time 2: 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.004627 sec, exc=5.004627 sec, total=5.004627 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=28 seq_num=29 snap_id=1 wait times: snap=5.001718 sec, exc=5.001718 sec, total=5.001718 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 4: 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.001173 sec, exc=5.001173 sec, total=5.001173 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000077 sec of elapsed time 5: 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.000466 sec, exc=5.000466 sec, total=5.000466 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=25 seq_num=26 snap_id=1 wait times: snap=5.001994 sec, exc=5.001994 sec, total=5.001994 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=24 seq_num=25 snap_id=1 wait times: snap=5.008745 sec, exc=5.008745 sec, total=5.008745 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=5.014766 sec, exc=5.014766 sec, total=5.014766 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=5.009727 sec, exc=5.009727 sec, total=5.009727 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 177 serial 8613 --------------------------------------------------- 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, 18:03:58 - 18:05:58] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-14 18:05:58.913 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 16764, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-14 15:33:00.894928 :A49F3761:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 15:33:00.894929 :A49F3762:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 15:33:00.895472 :A49F3763: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-06-14 15:33:00.895519 :A49F376A: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-06-14 15:43:01.722263 :A4A00097:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 15:43:02.148788 :A4A000D1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 15:43:02.144 2025-06-14 15:43:02.148790 :A4A000D2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13360 pso_num=22 pso_serial#=180 2025-06-14 15:43:02.148920 :A4A000D3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13360 osp_idx=0 osp_ver=305997 osp_pg=0 (spawn #284946) 2025-06-14 15:43:02.148921 :A4A000D4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 15:43:02.148921 :A4A000D5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=71 time=2891525344 2025-06-14 15:43:02.148921 :A4A000D6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 15:43:02.148922 :A4A000D7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 15:43:02.148922 :A4A000D8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 15:43:02.148922 :A4A000D9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 15:43:02.148923 :A4A000DA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 15:43:02.149490 :A4A000DB: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-06-14 15:43:02.149545 :A4A000E3: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-06-14 15:53:02.955948 :A4A0CA35:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 15:53:03.429350 :A4A0CA7C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 15:53:03.424 2025-06-14 15:53:03.429352 :A4A0CA7D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14720 pso_num=22 pso_serial#=181 2025-06-14 15:53:03.429488 :A4A0CA7E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14720 osp_idx=0 osp_ver=305998 osp_pg=0 (spawn #284969) 2025-06-14 15:53:03.429489 :A4A0CA7F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 15:53:03.429489 :A4A0CA80:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=72 time=2892126625 2025-06-14 15:53:03.429490 :A4A0CA81:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 15:53:03.429490 :A4A0CA82:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 15:53:03.429490 :A4A0CA83:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 15:53:03.429491 :A4A0CA84:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 15:53:03.429491 :A4A0CA85:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 15:53:03.429978 :A4A0CA86: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-06-14 15:53:03.430038 :A4A0CA90: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-06-14 16:03:04.277847 :A4A1978A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 16:03:04.777014 :A4A197D1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 16:03:04.772 2025-06-14 16:03:04.777017 :A4A197D2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17120 pso_num=22 pso_serial#=182 2025-06-14 16:03:04.777156 :A4A197D3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17120 osp_idx=0 osp_ver=305999 osp_pg=0 (spawn #284996) 2025-06-14 16:03:04.777157 :A4A197D4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 16:03:04.777157 :A4A197D5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=73 time=2892727969 2025-06-14 16:03:04.777158 :A4A197D6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 16:03:04.777158 :A4A197D7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 16:03:04.777159 :A4A197D8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 16:03:04.777159 :A4A197D9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 16:03:04.777160 :A4A197DA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 16:03:04.777652 :A4A197DB: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-06-14 16:03:04.777697 :A4A197E2: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-06-14 16:13:05.559309 :A4A26B56:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 16:13:06.079821 :A4A26B9B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 16:13:06.074 2025-06-14 16:13:06.079824 :A4A26B9C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16248 pso_num=22 pso_serial#=183 2025-06-14 16:13:06.079973 :A4A26B9D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16248 osp_idx=0 osp_ver=306000 osp_pg=0 (spawn #285018) 2025-06-14 16:13:06.079974 :A4A26B9E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 16:13:06.079974 :A4A26B9F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=74 time=2893329266 2025-06-14 16:13:06.079975 :A4A26BA0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 16:13:06.079975 :A4A26BA1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 16:13:06.079975 :A4A26BA2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 16:13:06.079976 :A4A26BA3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 16:13:06.079976 :A4A26BA4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 16:13:06.080487 :A4A26BA5: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-06-14 16:13:06.080533 :A4A26BAD: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-06-14 16:23:06.879201 :A4A33612:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 16:23:07.336935 :A4A33655:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 16:23:07.332 2025-06-14 16:23:07.336938 :A4A33656:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16768 pso_num=22 pso_serial#=184 2025-06-14 16:23:07.337070 :A4A33657:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16768 osp_idx=0 osp_ver=306001 osp_pg=0 (spawn #285041) 2025-06-14 16:23:07.337071 :A4A33658:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 16:23:07.337071 :A4A33659:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=75 time=2893930532 2025-06-14 16:23:07.337072 :A4A3365A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 16:23:07.337072 :A4A3365B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 16:23:07.337072 :A4A3365C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 16:23:07.337073 :A4A3365D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 16:23:07.337075 :A4A3365E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 16:23:07.337594 :A4A3365F: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-06-14 16:23:07.337637 :A4A33667: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-06-14 16:33:08.111187 :A4A400C6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 16:33:08.631604 :A4A400FB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 16:33:08.627 2025-06-14 16:33:08.631606 :A4A400FC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13812 pso_num=22 pso_serial#=185 2025-06-14 16:33:08.631753 :A4A400FD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13812 osp_idx=0 osp_ver=306002 osp_pg=0 (spawn #285064) 2025-06-14 16:33:08.631753 :A4A400FE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 16:33:08.631754 :A4A400FF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=76 time=2894531829 2025-06-14 16:33:08.631754 :A4A40100:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 16:33:08.631755 :A4A40101:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 16:33:08.631755 :A4A40102:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 16:33:08.631755 :A4A40103:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 16:33:08.631755 :A4A40104:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 16:33:08.632284 :A4A40105: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-06-14 16:33:08.632340 :A4A4010B: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-06-14 16:43:09.418738 :A4A4CABA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 16:43:09.954603 :A4A4CAF3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 16:43:09.949 2025-06-14 16:43:09.954605 :A4A4CAF4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16448 pso_num=22 pso_serial#=186 2025-06-14 16:43:09.954744 :A4A4CAF5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16448 osp_idx=0 osp_ver=306003 osp_pg=0 (spawn #285086) 2025-06-14 16:43:09.954744 :A4A4CAF6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 16:43:09.954745 :A4A4CAF7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=77 time=2895133141 2025-06-14 16:43:09.954745 :A4A4CAF8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 16:43:09.954746 :A4A4CAF9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 16:43:09.954746 :A4A4CAFA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 16:43:09.954746 :A4A4CAFB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 16:43:09.954746 :A4A4CAFC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 16:43:09.955243 :A4A4CAFD: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-06-14 16:43:09.955288 :A4A4CB05: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-06-14 16:53:10.814482 :A4A594B7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 16:53:11.225597 :A4A594FB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 16:53:11.221 2025-06-14 16:53:11.225600 :A4A594FC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14592 pso_num=22 pso_serial#=187 2025-06-14 16:53:11.225737 :A4A594FD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14592 osp_idx=0 osp_ver=306004 osp_pg=0 (spawn #285109) 2025-06-14 16:53:11.225738 :A4A594FE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 16:53:11.225738 :A4A594FF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=78 time=2895734422 2025-06-14 16:53:11.225738 :A4A59500:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 16:53:11.225739 :A4A59501:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 16:53:11.225739 :A4A59502:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 16:53:11.225739 :A4A59503:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 16:53:11.225740 :A4A59504:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 16:53:11.226255 :A4A59505: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-06-14 16:53:11.226305 :A4A5950D: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-06-14 17:03:12.087774 :A4A660F1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 17:03:12.483040 :A4A6612C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 17:03:12.479 2025-06-14 17:03:12.483042 :A4A6612D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16304 pso_num=22 pso_serial#=188 2025-06-14 17:03:12.483175 :A4A6612E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16304 osp_idx=0 osp_ver=306005 osp_pg=0 (spawn #285135) 2025-06-14 17:03:12.483176 :A4A6612F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 17:03:12.483176 :A4A66130:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=79 time=2896335672 2025-06-14 17:03:12.483177 :A4A66131:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 17:03:12.483177 :A4A66132:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:03:12.483178 :A4A66133:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:03:12.483178 :A4A66134:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:03:12.483178 :A4A66135:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:03:12.483674 :A4A66136: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-06-14 17:03:12.483719 :A4A6613D: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-06-14 17:10:39.496249 :A4A6FF26: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-06-14 17:13:13.661739 :A4A73401: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-06-14 17:23:14.531522 :A4A7FDF3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 17:23:14.817432 :A4A7FE5A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 17:23:14.812 2025-06-14 17:23:14.817434 :A4A7FE5B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17296 pso_num=22 pso_serial#=189 2025-06-14 17:23:14.817575 :A4A7FE5C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17296 osp_idx=0 osp_ver=306006 osp_pg=0 (spawn #285179) 2025-06-14 17:23:14.817576 :A4A7FE5D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 17:23:14.817576 :A4A7FE5E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=80 time=2897538000 2025-06-14 17:23:14.817577 :A4A7FE5F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 17:23:14.817577 :A4A7FE60:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:23:14.817577 :A4A7FE61:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:23:14.817577 :A4A7FE62:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:23:14.817578 :A4A7FE63:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:23:14.818069 :A4A7FE64: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-06-14 17:23:14.818116 :A4A7FE6C: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-06-14 17:33:15.689505 :A4A8C9A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 17:33:16.006719 :A4A8CA09:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 17:33:16.002 2025-06-14 17:33:16.006721 :A4A8CA0A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15408 pso_num=22 pso_serial#=190 2025-06-14 17:33:16.006868 :A4A8CA0B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15408 osp_idx=0 osp_ver=306007 osp_pg=0 (spawn #285202) 2025-06-14 17:33:16.006869 :A4A8CA0C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 17:33:16.006869 :A4A8CA0D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=81 time=2898139204 2025-06-14 17:33:16.006869 :A4A8CA0E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 17:33:16.006870 :A4A8CA0F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:33:16.006870 :A4A8CA10:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:33:16.006871 :A4A8CA11:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:33:16.006871 :A4A8CA12:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:33:16.007388 :A4A8CA13: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-06-14 17:33:16.007438 :A4A8CA19: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-06-14 17:43:16.990045 :A4A99434:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 17:43:17.244718 :A4A9946A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 17:43:17.239 2025-06-14 17:43:17.244720 :A4A9946B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15192 pso_num=22 pso_serial#=191 2025-06-14 17:43:17.244858 :A4A9946C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15192 osp_idx=0 osp_ver=306008 osp_pg=0 (spawn #285224) 2025-06-14 17:43:17.244859 :A4A9946D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 17:43:17.244859 :A4A9946E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=82 time=2898740438 2025-06-14 17:43:17.244860 :A4A9946F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 17:43:17.244860 :A4A99470:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:43:17.244860 :A4A99471:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:43:17.244860 :A4A99472:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:43:17.244861 :A4A99473:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:43:17.245359 :A4A99474: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-06-14 17:43:17.245403 :A4A9947C: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-06-14 17:53:18.130960 :A4AA5E1F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 17:53:18.354286 :A4AA5E58:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 17:53:18.349 2025-06-14 17:53:18.354288 :A4AA5E59:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15000 pso_num=22 pso_serial#=192 2025-06-14 17:53:18.354429 :A4AA5E5A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15000 osp_idx=0 osp_ver=306009 osp_pg=0 (spawn #285247) 2025-06-14 17:53:18.354430 :A4AA5E5B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 17:53:18.354431 :A4AA5E5C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=83 time=2899341547 2025-06-14 17:53:18.354431 :A4AA5E5D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 17:53:18.354432 :A4AA5E5E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:53:18.354432 :A4AA5E5F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:53:18.354432 :A4AA5E60:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:53:18.354433 :A4AA5E61:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:53:18.354930 :A4AA5E62: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-06-14 17:53:18.354983 :A4AA5E6C: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-06-14 18:03:19.205657 :A4AB2A37:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-14 18:03:19.507317 :A4AB2A79:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-14 18:03:19.503 2025-06-14 18:03:19.507319 :A4AB2A7A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16764 pso_num=22 pso_serial#=193 2025-06-14 18:03:19.507454 :A4AB2A7B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16764 osp_idx=0 osp_ver=306010 osp_pg=0 (spawn #285273) 2025-06-14 18:03:19.507454 :A4AB2A7C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 18:03:19.507455 :A4AB2A7D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=84 time=2899942704 2025-06-14 18:03:19.507455 :A4AB2A7E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 18:03:19.507456 :A4AB2A7F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 18:03:19.507456 :A4AB2A80:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 18:03:19.507456 :A4AB2A81:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 18:03:19.507457 :A4AB2A82:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 18:03:19.507954 :A4AB2A83: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-06-14 18:03:19.508000 :A4AB2A89: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: 16764, W000)