Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250806082919\orcl_j001_20560_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:35694M/63366M, Ph+PgF:41218M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 20560, image: ORACLE.EXE (J001) *** 2025-08-06 08:29:19.551 *** SESSION ID:(201.47143) 2025-08-06 08:29:19.551 *** 2025-08-06 08:29:19.551 Process diagnostic dump for ORACLE.EXE (J001), OS id=20560, pid: 25, proc_ser: 241, sid: 201, sess_ser: 47143 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20560 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=0.069510 sec, exc=0.069510 sec, total=0.069510 sec wait times: max=0.500000 sec, heur=28.628098 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.000014 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 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=54 seq_num=55 snap_id=1 wait times: snap=0.510109 sec, exc=0.510109 sec, total=0.510109 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=0.499981 sec, exc=0.499981 sec, total=0.499981 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=0.499914 sec, exc=0.499914 sec, total=0.499914 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=0.500089 sec, exc=0.500089 sec, total=0.500089 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 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.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=0.510095 sec, exc=0.510095 sec, total=0.510095 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=47 seq_num=48 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 201 serial 47143 --------------------------------------------------- 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 --------------------------------------------------- [29 samples, 08:28:50 - 08:29:19] idle wait at each sample [session created at: 08:28:50] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-06 08:29:19.551 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 20560, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-06 08:14:19.674051 :BC088291:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:14:49.676466 :BC088C46:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:14:49.666 2025-08-06 08:14:49.676468 :BC088C47:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14076 pso_num=25 pso_serial#=227 2025-08-06 08:14:49.676600 :BC088C48:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14076 osp_idx=23 osp_ver=229329 osp_pg=0 (spawn #460576) 2025-08-06 08:14:49.676600 :BC088C49:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:14:49.676601 :BC088C4A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=165 time=3148758970 2025-08-06 08:14:49.676601 :BC088C4B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:14:49.676602 :BC088C4C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:14:49.676602 :BC088C4D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:14:49.676603 :BC088C4E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:14:49.676603 :BC088C4F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:14:49.677072 :BC088C50: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-08-06 08:15:19.765727 :BC08964C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:15:49.761764 :BC08A07D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:15:49.750 2025-08-06 08:15:49.761766 :BC08A07E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19480 pso_num=25 pso_serial#=228 2025-08-06 08:15:49.761891 :BC08A07F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19480 osp_idx=23 osp_ver=229330 osp_pg=0 (spawn #460578) 2025-08-06 08:15:49.761892 :BC08A080:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:15:49.761892 :BC08A081:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=166 time=3148819064 2025-08-06 08:15:49.761892 :BC08A082:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:15:49.761893 :BC08A083:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:15:49.761893 :BC08A084:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:15:49.761893 :BC08A085:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:15:49.761894 :BC08A086:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:15:49.762382 :BC08A087: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-08-06 08:16:19.826669 :BC08AA4E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:16:49.831546 :BC08B453:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:16:49.820 2025-08-06 08:16:49.831548 :BC08B454:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21352 pso_num=25 pso_serial#=229 2025-08-06 08:16:49.831688 :BC08B455:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21352 osp_idx=23 osp_ver=229331 osp_pg=0 (spawn #460581) 2025-08-06 08:16:49.831689 :BC08B456:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:16:49.831689 :BC08B457:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=167 time=3148879126 2025-08-06 08:16:49.831690 :BC08B458:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:16:49.831690 :BC08B459:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:16:49.831690 :BC08B45A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:16:49.831691 :BC08B45B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:16:49.831691 :BC08B45C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:16:49.832197 :BC08B45D: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-08-06 08:17:19.897940 :BC08BE66:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:17:49.903569 :BC08C84E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:17:49.892 2025-08-06 08:17:49.903571 :BC08C84F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19040 pso_num=25 pso_serial#=230 2025-08-06 08:17:49.903715 :BC08C850:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19040 osp_idx=23 osp_ver=229332 osp_pg=0 (spawn #460583) 2025-08-06 08:17:49.903715 :BC08C851:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:17:49.903716 :BC08C852:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=168 time=3148939204 2025-08-06 08:17:49.903716 :BC08C853:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:17:49.903717 :BC08C854:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:17:49.903717 :BC08C855:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:17:49.903717 :BC08C856:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:17:49.903718 :BC08C857:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:17:49.904220 :BC08C858: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-08-06 08:18:19.999320 :BC08D233:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:18:50.007384 :BC08DC12:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:18:49.996 2025-08-06 08:18:50.007386 :BC08DC13:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19948 pso_num=25 pso_serial#=231 2025-08-06 08:18:50.007522 :BC08DC14:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19948 osp_idx=23 osp_ver=229333 osp_pg=0 (spawn #460585) 2025-08-06 08:18:50.007522 :BC08DC15:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:18:50.007522 :BC08DC16:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=169 time=3148999314 2025-08-06 08:18:50.007523 :BC08DC17:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:18:50.007523 :BC08DC18:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:18:50.007524 :BC08DC19:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:18:50.007524 :BC08DC1A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:18:50.007525 :BC08DC1B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:18:50.008000 :BC08DC1C: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-08-06 08:19:20.106190 :BC08E6D8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:19:50.101261 :BC08F0C2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:19:50.090 2025-08-06 08:19:50.101263 :BC08F0C3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18336 pso_num=25 pso_serial#=232 2025-08-06 08:19:50.101386 :BC08F0C4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18336 osp_idx=23 osp_ver=229334 osp_pg=0 (spawn #460587) 2025-08-06 08:19:50.101387 :BC08F0C5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:19:50.101387 :BC08F0C6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=170 time=3149059408 2025-08-06 08:19:50.101389 :BC08F0C7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:19:50.101390 :BC08F0C8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:19:50.101390 :BC08F0C9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:19:50.101390 :BC08F0CA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:19:50.101391 :BC08F0CB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:19:50.101866 :BC08F0CC: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-08-06 08:20:20.165761 :BC08FA71:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:20:50.189796 :BC090499:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:20:50.178 2025-08-06 08:20:50.189798 :BC09049A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21360 pso_num=25 pso_serial#=233 2025-08-06 08:20:50.189915 :BC09049B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21360 osp_idx=23 osp_ver=229335 osp_pg=0 (spawn #460589) 2025-08-06 08:20:50.189916 :BC09049C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:20:50.189916 :BC09049D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=171 time=3149119486 2025-08-06 08:20:50.189917 :BC09049E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:20:50.189917 :BC09049F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:20:50.189918 :BC0904A0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:20:50.189918 :BC0904A1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:20:50.189918 :BC0904A2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:20:50.190421 :BC0904A3: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-08-06 08:21:05.189132 :BC0909DD: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-08-06 08:21:05.189164*:BC0909DE:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1754439664 eid=16992791 ctx=0x000000077BD98A08 cwh=0x0000000026B901B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-06 08:21:05.189164*:BC0909DF:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 pubTabIdxCnt=0 2025-08-06 08:21:05.189164*:BC0909E0:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-08-06 08:21:05.189164*:BC0909E1:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-08-06 08:21:05.189164*:BC0909E2:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 xsc->flg4=65792 flags=2 2025-08-06 08:21:05.189164*:BC0909E3:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-08-06 08:21:05.189747 :BC0909E4: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-08-06 08:21:05.195140 :BC0909EC: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-08-06 08:21:05.196178 :BC0909F4: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-08-06 08:21:05.196316 :BC0909FD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-06 08:21:35.273312 :BC0913DF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:21:50.280811 :BC0918FC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:21:50.269 2025-08-06 08:21:50.280813 :BC0918FD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20564 pso_num=25 pso_serial#=234 2025-08-06 08:21:50.280943 :BC0918FE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20564 osp_idx=23 osp_ver=229336 osp_pg=0 (spawn #460592) 2025-08-06 08:21:50.280944 :BC0918FF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:21:50.280945 :BC091900:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=172 time=3149179579 2025-08-06 08:21:50.280945 :BC091901:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:21:50.280946 :BC091902:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:21:50.280946 :BC091903:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:21:50.280946 :BC091904:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:21:50.280947 :BC091905:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:21:50.281428 :BC091906: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-08-06 08:22:20.376379 :BC09236C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:22:50.401466 :BC092D25:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:22:50.391 2025-08-06 08:22:50.401468 :BC092D26:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20456 pso_num=25 pso_serial#=235 2025-08-06 08:22:50.401584 :BC092D27:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20456 osp_idx=23 osp_ver=229337 osp_pg=0 (spawn #460594) 2025-08-06 08:22:50.401585 :BC092D28:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:22:50.401585 :BC092D29:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=173 time=3149239704 2025-08-06 08:22:50.401586 :BC092D2A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:22:50.401586 :BC092D2B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:22:50.401586 :BC092D2C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:22:50.401587 :BC092D2D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:22:50.401587 :BC092D2E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:22:50.402055 :BC092D2F: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-08-06 08:23:20.494318 :BC0936D8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:23:50.489660 :BC0940AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:23:50.478 2025-08-06 08:23:50.489661 :BC0940AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21484 pso_num=25 pso_serial#=236 2025-08-06 08:23:50.489790 :BC0940AD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21484 osp_idx=23 osp_ver=229338 osp_pg=0 (spawn #460596) 2025-08-06 08:23:50.489790 :BC0940AE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:23:50.489791 :BC0940AF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=174 time=3149299783 2025-08-06 08:23:50.489791 :BC0940B0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:23:50.489791 :BC0940B1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:23:50.489792 :BC0940B2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:23:50.489792 :BC0940B3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:23:50.489792 :BC0940B4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:23:50.490261 :BC0940B5: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-08-06 08:24:20.574908 :BC094B04:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:24:50.557780 :BC0954C6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:24:50.546 2025-08-06 08:24:50.557782 :BC0954C7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18064 pso_num=25 pso_serial#=237 2025-08-06 08:24:50.557921 :BC0954C8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18064 osp_idx=23 osp_ver=229339 osp_pg=0 (spawn #460598) 2025-08-06 08:24:50.557922 :BC0954C9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:24:50.557922 :BC0954CA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=175 time=3149359861 2025-08-06 08:24:50.557923 :BC0954CB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:24:50.557923 :BC0954CC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:24:50.557923 :BC0954CD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:24:50.557924 :BC0954CE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:24:50.557924 :BC0954CF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:24:50.558401 :BC0954D0: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-08-06 08:25:20.631938 :BC095EA3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:25:50.654180 :BC0968FF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:25:50.643 2025-08-06 08:25:50.654182 :BC096900:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21288 pso_num=25 pso_serial#=238 2025-08-06 08:25:50.654315 :BC096901:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21288 osp_idx=23 osp_ver=229340 osp_pg=0 (spawn #460600) 2025-08-06 08:25:50.654315 :BC096902:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:25:50.654316 :BC096903:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=176 time=3149419954 2025-08-06 08:25:50.654316 :BC096904:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:25:50.654316 :BC096905:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:25:50.654317 :BC096906:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:25:50.654317 :BC096907:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:25:50.654317 :BC096908:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:25:50.654831 :BC096909: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-08-06 08:26:20.721413 :BC0972BA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:26:50.748613 :BC097CFA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:26:50.737 2025-08-06 08:26:50.748615 :BC097CFB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19824 pso_num=25 pso_serial#=239 2025-08-06 08:26:50.748747 :BC097CFC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19824 osp_idx=23 osp_ver=229341 osp_pg=0 (spawn #460604) 2025-08-06 08:26:50.748747 :BC097CFD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:26:50.748748 :BC097CFE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=177 time=3149480048 2025-08-06 08:26:50.748748 :BC097CFF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:26:50.748749 :BC097D00:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:26:50.748749 :BC097D01:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:26:50.748749 :BC097D02:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:26:50.748750 :BC097D03:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:26:50.749254 :BC097D04: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-08-06 08:27:20.839309 :BC098709:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:27:50.843522 :BC0990E7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:27:50.832 2025-08-06 08:27:50.843524 :BC0990E8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14080 pso_num=25 pso_serial#=240 2025-08-06 08:27:50.843666 :BC0990E9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14080 osp_idx=23 osp_ver=229342 osp_pg=0 (spawn #460606) 2025-08-06 08:27:50.843667 :BC0990EA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:27:50.843667 :BC0990EB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=178 time=3149540142 2025-08-06 08:27:50.843668 :BC0990EC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:27:50.843668 :BC0990ED:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:27:50.843669 :BC0990EE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:27:50.843669 :BC0990EF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:27:50.843669 :BC0990F0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:27:50.844149 :BC0990F1: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-08-06 08:28:20.931683 :BC099AD5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-06 08:28:50.929252 :BC09A44D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-06 08:28:50.918 2025-08-06 08:28:50.929254 :BC09A44E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20560 pso_num=25 pso_serial#=241 2025-08-06 08:28:50.929389 :BC09A44F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20560 osp_idx=23 osp_ver=229343 osp_pg=0 (spawn #460608) 2025-08-06 08:28:50.929390 :BC09A450:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:28:50.929390 :BC09A451:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=179 time=3149600236 2025-08-06 08:28:50.929391 :BC09A452:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:28:50.929391 :BC09A453:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:28:50.929392 :BC09A454:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:28:50.929393 :BC09A455:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:28:50.929393 :BC09A456:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:28:50.929891 :BC09A457: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: 20560, J001)