Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250721221952\orcl_j001_18600_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:36134M/63366M, Ph+PgF:42015M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 18600, image: ORACLE.EXE (J001) *** 2025-07-21 22:19:52.282 *** SESSION ID:(209.8265) 2025-07-21 22:19:52.282 *** 2025-07-21 22:19:52.282 Process diagnostic dump for ORACLE.EXE (J001), OS id=18600, pid: 26, proc_ser: 51, sid: 209, sess_ser: 8265 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18600 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=99 seq_num=100 snap_id=1 wait times: snap=0.197931 sec, exc=0.197931 sec, total=0.197931 sec wait times: max=0.500000 sec, heur=49.819748 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=98 seq_num=99 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=97 seq_num=98 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.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=0.510092 sec, exc=0.510092 sec, total=0.510092 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=95 seq_num=96 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=94 seq_num=95 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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=93 seq_num=94 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=92 seq_num=93 snap_id=1 wait times: snap=0.510115 sec, exc=0.510115 sec, total=0.510115 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=91 seq_num=92 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.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=90 seq_num=91 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.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=89 seq_num=90 snap_id=1 wait times: snap=0.499982 sec, exc=0.499982 sec, total=0.499982 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time Sampled Session History of session 209 serial 8265 --------------------------------------------------- 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 --------------------------------------------------- [50 samples, 22:19:02 - 22:19:52] idle wait at each sample [session created at: 22:19:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-21 22:19:52.282 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 18600, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-21 22:05:01.100204 :B52C4CF4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:05:01.088 2025-07-21 22:05:01.100206 :B52C4CF5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15704 pso_num=26 pso_serial#=37 2025-07-21 22:05:01.100324 :B52C4CF6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15704 osp_idx=24 osp_ver=657682 osp_pg=0 (spawn #409176) 2025-07-21 22:05:01.100324 :B52C4CF7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:05:01.100325 :B52C4CF8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=161 time=1816276986 2025-07-21 22:05:01.100325 :B52C4CF9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:05:01.100326 :B52C4CFA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:05:01.100326 :B52C4CFB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:05:01.100326 :B52C4CFC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:05:01.100327 :B52C4CFD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:05:01.100878 :B52C4CFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:05:31.183774 :B52C5A50:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:06:01.225909 :B52C6A4A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:06:01.215 2025-07-21 22:06:01.225911 :B52C6A4B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=3404 pso_num=26 pso_serial#=38 2025-07-21 22:06:01.226047 :B52C6A4C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=3404 osp_idx=24 osp_ver=657683 osp_pg=0 (spawn #409178) 2025-07-21 22:06:01.226048 :B52C6A4D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:06:01.226048 :B52C6A4E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=162 time=1816337111 2025-07-21 22:06:01.226049 :B52C6A4F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:06:01.226049 :B52C6A50:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:06:01.226050 :B52C6A51:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:06:01.226050 :B52C6A52:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:06:01.226050 :B52C6A53:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:06:01.226529 :B52C6A54:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:06:31.336335 :B52C7549:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:07:01.363458 :B52C7F61:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:07:01.352 2025-07-21 22:07:01.363461 :B52C7F62:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17112 pso_num=26 pso_serial#=39 2025-07-21 22:07:01.363587 :B52C7F63:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17112 osp_idx=24 osp_ver=657684 osp_pg=0 (spawn #409180) 2025-07-21 22:07:01.363588 :B52C7F64:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:07:01.363588 :B52C7F65:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=163 time=1816397251 2025-07-21 22:07:01.363589 :B52C7F66:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:07:01.363589 :B52C7F67:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:07:01.363590 :B52C7F68:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:07:01.363590 :B52C7F69:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:07:01.363590 :B52C7F6A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:07:01.364118 :B52C7F6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:07:31.470878 :B52C896E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:08:01.482590 :B52C936E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:08:01.471 2025-07-21 22:08:01.482592 :B52C936F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13380 pso_num=26 pso_serial#=40 2025-07-21 22:08:01.482713 :B52C9370:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13380 osp_idx=24 osp_ver=657685 osp_pg=0 (spawn #409182) 2025-07-21 22:08:01.482713 :B52C9371:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:08:01.482714 :B52C9372:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=164 time=1816457376 2025-07-21 22:08:01.482714 :B52C9373:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:08:01.482715 :B52C9374:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:08:01.482715 :B52C9375:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:08:01.482715 :B52C9376:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:08:01.482716 :B52C9377:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:08:01.483206 :B52C9378:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:08:31.578070 :B52C9D4A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:09:01.580230 :B52CA722:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:09:01.569 2025-07-21 22:09:01.580233 :B52CA723:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9900 pso_num=26 pso_serial#=41 2025-07-21 22:09:01.580368 :B52CA724:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9900 osp_idx=24 osp_ver=657686 osp_pg=0 (spawn #409184) 2025-07-21 22:09:01.580369 :B52CA725:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:09:01.580369 :B52CA726:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=165 time=1816517470 2025-07-21 22:09:01.580370 :B52CA727:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:09:01.580370 :B52CA728:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:09:01.580371 :B52CA729:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:09:01.580371 :B52CA72A:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:09:01.580371 :B52CA72B:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:09:01.580848 :B52CA72C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:09:26.605818 :B52CAF7F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-21 22:09:26.605866*:B52CAF80:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BABB58 estart=1753106966 eid=16968316 ctx=0x000000077BD98A08 cwh=0x0000000027B801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-21 22:09:26.605866*:B52CAF81:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BABB58 curCtx=0x0000000027B8F708 pubTabIdxCnt=0 2025-07-21 22:09:26.605866*:B52CAF82:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BABB58 2025-07-21 22:09:26.605866*:B52CAF83:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BABB58 2025-07-21 22:09:26.605866*:B52CAF84:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BABB58 curCtx=0x0000000027B8F708 xsc->flg4=65792 flags=2 2025-07-21 22:09:26.605866*:B52CAF85:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BABB58 2025-07-21 22:09:26.606493 :B52CAF86:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-21 22:09:26.611966 :B52CAF8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-21 22:09:26.612947 :B52CAF96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-21 22:09:26.613083 :B52CAF9F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-21 22:09:56.675705 :B52CB96C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:10:01.656750 :B52CBB87:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:10:01.645 2025-07-21 22:10:01.656752 :B52CBB88:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16212 pso_num=26 pso_serial#=42 2025-07-21 22:10:01.656888 :B52CBB89:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16212 osp_idx=24 osp_ver=657687 osp_pg=0 (spawn #409187) 2025-07-21 22:10:01.656889 :B52CBB8A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:10:01.656889 :B52CBB8B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=166 time=1816577548 2025-07-21 22:10:01.656890 :B52CBB8C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:10:01.656890 :B52CBB8D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:10:01.656891 :B52CBB8E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:10:01.656891 :B52CBB8F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:10:01.656891 :B52CBB90:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:10:01.657352 :B52CBB91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:10:31.742996 :B52CC5B8:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:11:01.745966 :B52CCFC0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:11:01.735 2025-07-21 22:11:01.745968 :B52CCFC1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14636 pso_num=26 pso_serial#=43 2025-07-21 22:11:01.746086 :B52CCFC2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14636 osp_idx=24 osp_ver=657688 osp_pg=0 (spawn #409190) 2025-07-21 22:11:01.746087 :B52CCFC3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:11:01.746087 :B52CCFC4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=167 time=1816637642 2025-07-21 22:11:01.746088 :B52CCFC5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:11:01.746088 :B52CCFC6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:11:01.746088 :B52CCFC7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:11:01.746088 :B52CCFC8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:11:01.746089 :B52CCFC9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:11:01.746577 :B52CCFCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:11:31.846429 :B52CDA23:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:12:01.851106 :B52CE3FE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:12:01.840 2025-07-21 22:12:01.851108 :B52CE3FF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=5964 pso_num=26 pso_serial#=44 2025-07-21 22:12:01.851230 :B52CE400:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=5964 osp_idx=24 osp_ver=657689 osp_pg=0 (spawn #409192) 2025-07-21 22:12:01.851231 :B52CE401:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:12:01.851231 :B52CE402:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=168 time=1816697736 2025-07-21 22:12:01.851232 :B52CE403:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:12:01.851232 :B52CE404:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:12:01.851233 :B52CE405:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:12:01.851233 :B52CE406:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:12:01.851233 :B52CE407:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:12:01.851716 :B52CE408:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:12:31.921166 :B52CEE16:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:13:01.934338 :B52CF827:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:13:01.923 2025-07-21 22:13:01.934340 :B52CF828:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11328 pso_num=26 pso_serial#=45 2025-07-21 22:13:01.934469 :B52CF829:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11328 osp_idx=24 osp_ver=657690 osp_pg=0 (spawn #409194) 2025-07-21 22:13:01.934470 :B52CF82A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:13:01.934470 :B52CF82B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=169 time=1816757829 2025-07-21 22:13:01.934471 :B52CF82C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:13:01.934471 :B52CF82D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:13:01.934472 :B52CF82E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:13:01.934472 :B52CF82F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:13:01.934472 :B52CF830:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:13:01.934960 :B52CF831:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:13:31.988558 :B52D0219:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:14:02.031826 :B52D0BF9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:14:02.020 2025-07-21 22:14:02.031828 :B52D0BFA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17404 pso_num=26 pso_serial#=46 2025-07-21 22:14:02.031959 :B52D0BFB:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17404 osp_idx=24 osp_ver=657691 osp_pg=0 (spawn #409196) 2025-07-21 22:14:02.031960 :B52D0BFC:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:14:02.031961 :B52D0BFD:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=170 time=1816817923 2025-07-21 22:14:02.031961 :B52D0BFE:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:14:02.031961 :B52D0BFF:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:14:02.031962 :B52D0C00:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:14:02.031962 :B52D0C01:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:14:02.031963 :B52D0C02:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:14:02.032445 :B52D0C03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:14:32.133895 :B52D1603:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:15:02.121522 :B52D1FEE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:15:02.110 2025-07-21 22:15:02.121524 :B52D1FEF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18848 pso_num=26 pso_serial#=47 2025-07-21 22:15:02.121658 :B52D1FF0:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18848 osp_idx=24 osp_ver=657692 osp_pg=0 (spawn #409198) 2025-07-21 22:15:02.121658 :B52D1FF1:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:15:02.121659 :B52D1FF2:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=171 time=1816878017 2025-07-21 22:15:02.121659 :B52D1FF3:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:15:02.121659 :B52D1FF4:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:15:02.121660 :B52D1FF5:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:15:02.121660 :B52D1FF6:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:15:02.121660 :B52D1FF7:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:15:02.122135 :B52D1FF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:15:32.205788 :B52D29D3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:16:02.195191 :B52D33D9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:16:02.183 2025-07-21 22:16:02.195193 :B52D33DA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15536 pso_num=26 pso_serial#=48 2025-07-21 22:16:02.195333 :B52D33DB:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15536 osp_idx=24 osp_ver=657693 osp_pg=0 (spawn #409200) 2025-07-21 22:16:02.195334 :B52D33DC:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:16:02.195335 :B52D33DD:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=172 time=1816938079 2025-07-21 22:16:02.195335 :B52D33DE:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:16:02.195336 :B52D33DF:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:16:02.195336 :B52D33E0:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:16:02.195336 :B52D33E1:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:16:02.195337 :B52D33E2:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:16:02.195818 :B52D33E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:16:32.244147 :B52D3EBF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:17:02.277468 :B52D48E8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:17:02.266 2025-07-21 22:17:02.277470 :B52D48E9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15516 pso_num=26 pso_serial#=49 2025-07-21 22:17:02.277597 :B52D48EA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15516 osp_idx=24 osp_ver=657694 osp_pg=0 (spawn #409202) 2025-07-21 22:17:02.277598 :B52D48EB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:17:02.277598 :B52D48EC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=173 time=1816998158 2025-07-21 22:17:02.277599 :B52D48ED:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:17:02.277599 :B52D48EE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:17:02.277599 :B52D48EF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:17:02.277600 :B52D48F0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:17:02.277600 :B52D48F1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:17:02.278087 :B52D48F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:17:32.349589 :B52D5661:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:18:02.356371 :B52D6070:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:18:02.345 2025-07-21 22:18:02.356373 :B52D6071:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10908 pso_num=26 pso_serial#=50 2025-07-21 22:18:02.356506 :B52D6072:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10908 osp_idx=24 osp_ver=657695 osp_pg=0 (spawn #409205) 2025-07-21 22:18:02.356506 :B52D6073:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:18:02.356507 :B52D6074:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=174 time=1817058251 2025-07-21 22:18:02.356509 :B52D6075:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:18:02.356509 :B52D6076:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:18:02.356509 :B52D6077:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:18:02.356510 :B52D6078:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:18:02.356510 :B52D6079:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:18:02.357041 :B52D607A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:18:32.451520 :B52D6A6B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-21 22:19:02.471962 :B52D7474:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-21 22:19:02.468 2025-07-21 22:19:02.471964 :B52D7475:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18600 pso_num=26 pso_serial#=51 2025-07-21 22:19:02.472080 :B52D7476:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18600 osp_idx=24 osp_ver=657696 osp_pg=0 (spawn #409207) 2025-07-21 22:19:02.472081 :B52D7477:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:19:02.472081 :B52D7478:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=175 time=1817118345 2025-07-21 22:19:02.472081 :B52D7479:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:19:02.472082 :B52D747A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:19:02.472082 :B52D747B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:19:02.472082 :B52D747C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:19:02.472083 :B52D747D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:19:02.472587 :B52D747E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:19:27.505070 :B52D7D3B:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 18600, J001)