Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250709080942\orcl_j001_12024_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:36515M/63366M, Ph+PgF:42624M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12024, image: ORACLE.EXE (J001) *** 2025-07-09 08:09:42.411 *** SESSION ID:(201.23885) 2025-07-09 08:09:42.411 *** 2025-07-09 08:09:42.411 Process diagnostic dump for ORACLE.EXE (J001), OS id=12024, pid: 25, proc_ser: 61, sid: 201, sess_ser: 23885 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12024 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.241788 sec, exc=0.241788 sec, total=0.241788 sec wait times: max=0.500000 sec, heur=15.325142 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=0.513301 sec, exc=0.513301 sec, total=0.513301 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.510112 sec, exc=0.510112 sec, total=0.510112 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=0.510091 sec, exc=0.510091 sec, total=0.510091 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time Sampled Session History of session 201 serial 23885 --------------------------------------------------- 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 --------------------------------------------------- [16 samples, 08:09:27 - 08:09:42] idle wait at each sample [session created at: 08:09:27] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-09 08:09:42.411 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12024, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-09 07:55:55.787777 :AF91BACC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 07:56:25.762367 :AF91C4CD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 07:56:25.751 2025-07-09 07:56:25.762369 :AF91C4CE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14600 pso_num=25 pso_serial#=47 2025-07-09 07:56:25.762496 :AF91C4CF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14600 osp_idx=23 osp_ver=188604 osp_pg=0 (spawn #367204) 2025-07-09 07:56:25.762497 :AF91C4D0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 07:56:25.762497 :AF91C4D1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=230 time=728561658 2025-07-09 07:56:25.762498 :AF91C4D2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 07:56:25.762498 :AF91C4D3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 07:56:25.762498 :AF91C4D4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 07:56:25.762499 :AF91C4D5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 07:56:25.762499 :AF91C4D6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 07:56:25.762982 :AF91C4D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 07:56:55.834394 :AF91CEE3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 07:57:25.865822 :AF91D908:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 07:57:25.854 2025-07-09 07:57:25.865824 :AF91D909:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17488 pso_num=25 pso_serial#=48 2025-07-09 07:57:25.865962 :AF91D90A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17488 osp_idx=23 osp_ver=188605 osp_pg=0 (spawn #367207) 2025-07-09 07:57:25.865963 :AF91D90B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 07:57:25.865963 :AF91D90C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=231 time=728621751 2025-07-09 07:57:25.865964 :AF91D90D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 07:57:25.865964 :AF91D90E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 07:57:25.865965 :AF91D90F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 07:57:25.865965 :AF91D910:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 07:57:25.865965 :AF91D911:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 07:57:25.866465 :AF91D912:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 07:57:55.960652 :AF91E307:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 07:58:25.973635 :AF91ED01:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 07:58:25.962 2025-07-09 07:58:25.973637 :AF91ED02:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2768 pso_num=25 pso_serial#=49 2025-07-09 07:58:25.973772 :AF91ED03:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=2768 osp_idx=23 osp_ver=188606 osp_pg=0 (spawn #367209) 2025-07-09 07:58:25.973773 :AF91ED04:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 07:58:25.973773 :AF91ED05:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=232 time=728681861 2025-07-09 07:58:25.973774 :AF91ED06:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 07:58:25.973774 :AF91ED07:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 07:58:25.973774 :AF91ED08:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 07:58:25.973774 :AF91ED09:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 07:58:25.973775 :AF91ED0A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 07:58:25.974234 :AF91ED0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 07:58:56.056398 :AF91F7DF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 07:59:26.066816 :AF920206:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 07:59:26.055 2025-07-09 07:59:26.066818 :AF920207:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8568 pso_num=25 pso_serial#=50 2025-07-09 07:59:26.066957 :AF920208:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8568 osp_idx=23 osp_ver=188607 osp_pg=0 (spawn #367211) 2025-07-09 07:59:26.066958 :AF920209:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 07:59:26.066959 :AF92020A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=728741954 2025-07-09 07:59:26.066959 :AF92020B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 07:59:26.066960 :AF92020C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 07:59:26.066960 :AF92020D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 07:59:26.066960 :AF92020E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 07:59:26.066962 :AF92020F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 07:59:26.067467 :AF920210:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 07:59:56.154566 :AF920C4D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:00:20.959774 :AF921558:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:00:20.959 2025-07-09 08:00:20.959776 :AF921559:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4492 pso_num=25 pso_serial#=51 2025-07-09 08:00:20.959914 :AF92155A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4492 osp_idx=23 osp_ver=188608 osp_pg=0 (spawn #367216) 2025-07-09 08:00:20.959915 :AF92155B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:00:20.959915 :AF92155C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=728796845 2025-07-09 08:00:20.959915 :AF92155D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:00:20.959916 :AF92155E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:00:20.959916 :AF92155F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:00:20.959917 :AF921560:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:00:20.959917 :AF921561:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:00:20.960401 :AF921562:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:00:20.960437 :AF921568:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-09 08:00:20.967541 :AF921576: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-07-09 08:00:20.968623 :AF92157E: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-07-09 08:00:20.969272 :AF921586: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-07-09 08:00:20.969760 :AF92158E: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-07-09 08:00:20.970212 :AF921596: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-07-09 08:00:20.972751 :AF92159E: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-07-09 08:00:20.973869 :AF9215A6: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-07-09 08:00:21.023151 :AF9215AE: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-07-09 08:00:21.024036 :AF9215B6: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-07-09 08:00:21.024273 :AF9215BE: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-07-09 08:00:21.024483 :AF9215C6: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-07-09 08:00:21.026282 :AF9215CE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:00:26.130885 :AF9217C3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:00:26.120 2025-07-09 08:00:26.130887 :AF9217C4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13280 pso_num=25 pso_serial#=52 2025-07-09 08:00:26.131025 :AF9217C5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13280 osp_idx=23 osp_ver=188609 osp_pg=0 (spawn #367218) 2025-07-09 08:00:26.131025 :AF9217C6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:00:26.131026 :AF9217C7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=235 time=728802017 2025-07-09 08:00:26.131026 :AF9217C8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:00:26.131027 :AF9217C9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:00:26.131027 :AF9217CA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:00:26.131027 :AF9217CB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:00:26.131028 :AF9217CC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:00:26.131502 :AF9217CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:00:51.184504 :AF922098:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-09 08:01:21.282720 :AF922AA6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:01:26.232226 :AF922C7B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:01:26.221 2025-07-09 08:01:26.232228 :AF922C7C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18248 pso_num=25 pso_serial#=53 2025-07-09 08:01:26.232372 :AF922C7D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18248 osp_idx=23 osp_ver=188610 osp_pg=0 (spawn #367220) 2025-07-09 08:01:26.232373 :AF922C7E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:01:26.232374 :AF922C7F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=236 time=728862126 2025-07-09 08:01:26.232375 :AF922C80:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:01:26.232375 :AF922C81:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:01:26.232375 :AF922C82:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:01:26.232376 :AF922C83:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:01:26.232376 :AF922C84:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:01:26.232893 :AF922C85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:01:56.336421 :AF9236B0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:02:26.369932 :AF9240B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:02:26.358 2025-07-09 08:02:26.369934 :AF9240B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17512 pso_num=25 pso_serial#=54 2025-07-09 08:02:26.370071 :AF9240B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17512 osp_idx=23 osp_ver=188611 osp_pg=0 (spawn #367222) 2025-07-09 08:02:26.370071 :AF9240B9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:02:26.370072 :AF9240BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=237 time=728922251 2025-07-09 08:02:26.370072 :AF9240BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:02:26.370073 :AF9240BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:02:26.370073 :AF9240BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:02:26.370074 :AF9240BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:02:26.370074 :AF9240BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:02:26.370647 :AF9240C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:02:56.463370 :AF924AD9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:03:26.471411 :AF9254E3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:03:26.460 2025-07-09 08:03:26.471413 :AF9254E4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=5196 pso_num=25 pso_serial#=55 2025-07-09 08:03:26.471545 :AF9254E5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=5196 osp_idx=23 osp_ver=188612 osp_pg=0 (spawn #367224) 2025-07-09 08:03:26.471545 :AF9254E6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:03:26.471546 :AF9254E7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=238 time=728982361 2025-07-09 08:03:26.471546 :AF9254E8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:03:26.471546 :AF9254E9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:03:26.471547 :AF9254EA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:03:26.471547 :AF9254EB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:03:26.471547 :AF9254EC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:03:26.472013 :AF9254ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:03:56.566151 :AF925F45:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:04:26.543470 :AF92692C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:04:26.532 2025-07-09 08:04:26.543472 :AF92692D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7572 pso_num=25 pso_serial#=56 2025-07-09 08:04:26.543615 :AF92692E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7572 osp_idx=23 osp_ver=188613 osp_pg=0 (spawn #367226) 2025-07-09 08:04:26.543616 :AF92692F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:04:26.543617 :AF926930:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=239 time=729042439 2025-07-09 08:04:26.543617 :AF926931:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:04:26.543617 :AF926932:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:04:26.543618 :AF926933:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:04:26.543618 :AF926934:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:04:26.543619 :AF926935:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:04:26.544102 :AF926936:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:04:56.633662 :AF92733C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:05:26.663977 :AF928333:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:05:26.652 2025-07-09 08:05:26.663980 :AF928334:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7016 pso_num=25 pso_serial#=57 2025-07-09 08:05:26.664114 :AF928335:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7016 osp_idx=23 osp_ver=188614 osp_pg=0 (spawn #367228) 2025-07-09 08:05:26.664114 :AF928336:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:05:26.664115 :AF928337:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=240 time=729102548 2025-07-09 08:05:26.664115 :AF928338:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:05:26.664115 :AF928339:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:05:26.664116 :AF92833A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:05:26.664116 :AF92833B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:05:26.664116 :AF92833C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:05:26.664627 :AF92833D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:05:56.771738 :AF928E23:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:06:26.737394 :AF92984D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:06:26.726 2025-07-09 08:06:26.737396 :AF92984E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14600 pso_num=25 pso_serial#=58 2025-07-09 08:06:26.737532 :AF92984F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14600 osp_idx=23 osp_ver=188615 osp_pg=0 (spawn #367230) 2025-07-09 08:06:26.737533 :AF929850:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:06:26.737533 :AF929851:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=241 time=729162626 2025-07-09 08:06:26.737534 :AF929852:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:06:26.737534 :AF929853:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:06:26.737535 :AF929854:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:06:26.737535 :AF929855:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:06:26.737535 :AF929856:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:06:26.738002 :AF929857:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:06:56.861882 :AF92A243:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:07:26.832690 :AF92AC27:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:07:26.821 2025-07-09 08:07:26.832692 :AF92AC28:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16560 pso_num=25 pso_serial#=59 2025-07-09 08:07:26.832842 :AF92AC29:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16560 osp_idx=23 osp_ver=188616 osp_pg=0 (spawn #367233) 2025-07-09 08:07:26.832843 :AF92AC2A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:07:26.832843 :AF92AC2B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=242 time=729222720 2025-07-09 08:07:26.832843 :AF92AC2C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:07:26.832844 :AF92AC2D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:07:26.832844 :AF92AC2E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:07:26.832844 :AF92AC2F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:07:26.832844 :AF92AC30:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:07:26.833347 :AF92AC31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:07:56.917379 :AF92B63D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:08:26.949519 :AF92C07B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:08:26.938 2025-07-09 08:08:26.949522 :AF92C07C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9420 pso_num=25 pso_serial#=60 2025-07-09 08:08:26.949696 :AF92C07D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9420 osp_idx=23 osp_ver=188617 osp_pg=0 (spawn #367235) 2025-07-09 08:08:26.949696 :AF92C07E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:08:26.949697 :AF92C07F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=243 time=729282845 2025-07-09 08:08:26.949697 :AF92C080:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:08:26.949698 :AF92C081:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:08:26.949698 :AF92C082:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:08:26.949699 :AF92C083:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:08:26.949699 :AF92C084:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:08:26.950214 :AF92C085:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 08:08:57.073842 :AF92CB3B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-09 08:09:27.092827 :AF92D574:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-09 08:09:27.093 2025-07-09 08:09:27.092829 :AF92D575:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12024 pso_num=25 pso_serial#=61 2025-07-09 08:09:27.092963 :AF92D576:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12024 osp_idx=23 osp_ver=188618 osp_pg=0 (spawn #367237) 2025-07-09 08:09:27.092964 :AF92D577:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 08:09:27.092964 :AF92D578:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=244 time=729342970 2025-07-09 08:09:27.092965 :AF92D579:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:09:27.092965 :AF92D57A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:09:27.092965 :AF92D57B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:09:27.092966 :AF92D57C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:09:27.092966 :AF92D57D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:09:27.093437 :AF92D57E: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 12024, J001)