Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250830060006\orcl_j000_23312_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:35115M/63366M, Ph+PgF:40110M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 23312, image: ORACLE.EXE (J000) *** 2025-08-30 06:00:06.553 *** SESSION ID:(193.61923) 2025-08-30 06:00:06.553 *** 2025-08-30 06:00:06.553 Process diagnostic dump for ORACLE.EXE (J000), OS id=23312, pid: 24, proc_ser: 163, sid: 193, sess_ser: 61923 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23312 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.365754 sec, exc=0.365754 sec, total=0.365754 sec wait times: max=0.500000 sec, heur=4.487786 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.510093 sec, exc=0.510093 sec, total=0.510093 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=7 seq_num=8 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500011 sec, exc=0.500011 sec, total=0.500011 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 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=3 seq_num=4 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.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.510081 sec, exc=0.510081 sec, total=0.510081 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499145 sec, exc=0.499145 sec, total=0.499145 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=0 seq_num=1 snap_id=1 wait times: snap=0.102637 sec, exc=0.102637 sec, total=0.102637 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 61923 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 06:00:02 - 06:00:06] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-30 06:00:06.553 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 23312, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-30 05:49:37.034153 :C6856B4E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:50:07.055367 :C6857502:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:50:07.043 2025-08-30 05:50:07.055369 :C6857503:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17544 pso_num=24 pso_serial#=152 2025-08-30 05:50:07.055489 :C6857504:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17544 osp_idx=22 osp_ver=819222 osp_pg=0 (spawn #540232) 2025-08-30 05:50:07.055489 :C6857505:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:50:07.055490 :C6857506:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=201 time=918658346 2025-08-30 05:50:07.055490 :C6857507:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:50:07.055491 :C6857508:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:50:07.055491 :C6857509:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:50:07.055491 :C685750A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:50:07.055492 :C685750B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:50:07.055969 :C685750C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:50:37.183527 :C6857FD0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:51:07.138793 :C685897D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:51:07.127 2025-08-30 05:51:07.138795 :C685897E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13244 pso_num=24 pso_serial#=153 2025-08-30 05:51:07.138927 :C685897F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13244 osp_idx=22 osp_ver=819223 osp_pg=0 (spawn #540234) 2025-08-30 05:51:07.138927 :C6858980:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:51:07.138927 :C6858981:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=202 time=918718440 2025-08-30 05:51:07.138928 :C6858982:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:51:07.138928 :C6858983:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:51:07.138928 :C6858984:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:51:07.138929 :C6858985:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:51:07.138929 :C6858986:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:51:07.139386 :C6858987:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:51:37.197349 :C6859346:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:52:07.229084 :C6859D49:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:52:07.218 2025-08-30 05:52:07.229086 :C6859D4A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22228 pso_num=24 pso_serial#=154 2025-08-30 05:52:07.229206 :C6859D4B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22228 osp_idx=22 osp_ver=819224 osp_pg=0 (spawn #540236) 2025-08-30 05:52:07.229207 :C6859D4C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:52:07.229207 :C6859D4D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=203 time=918778533 2025-08-30 05:52:07.229208 :C6859D4E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:52:07.229208 :C6859D4F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:52:07.229208 :C6859D50:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:52:07.229209 :C6859D51:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:52:07.229209 :C6859D52:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:52:07.229698 :C6859D53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:52:37.289815 :C685A74D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:52:52.325658 :C685AC8A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:52:52.315 2025-08-30 05:52:52.325661 :C685AC8B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20520 pso_num=24 pso_serial#=155 2025-08-30 05:52:52.325829 :C685AC8C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20520 osp_idx=22 osp_ver=819225 osp_pg=0 (spawn #540239) 2025-08-30 05:52:52.325829 :C685AC8D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:52:52.325830 :C685AC8E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=204 time=918823627 2025-08-30 05:52:52.325830 :C685AC8F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:52:52.325830 :C685AC90:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:52:52.325831 :C685AC91:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:52:52.325831 :C685AC92:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:52:52.325831 :C685AC93:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:52:52.326316 :C685AC94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:53:07.339642 :C685B1C0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-30 05:53:37.443442 :C685BBAB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:54:07.431814 :C685C56C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:54:07.420 2025-08-30 05:54:07.431817 :C685C56D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6364 pso_num=24 pso_serial#=156 2025-08-30 05:54:07.431945 :C685C56E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=6364 osp_idx=22 osp_ver=819226 osp_pg=0 (spawn #540241) 2025-08-30 05:54:07.431946 :C685C56F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:54:07.431946 :C685C570:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=205 time=918898737 2025-08-30 05:54:07.431946 :C685C571:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:54:07.431947 :C685C572:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:54:07.431947 :C685C573:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:54:07.431948 :C685C574:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:54:07.431948 :C685C575:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:54:07.432429 :C685C576:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:54:37.539461 :C685D02C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:55:07.523456 :C685DA0F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:55:07.512 2025-08-30 05:55:07.523458 :C685DA10:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22648 pso_num=24 pso_serial#=157 2025-08-30 05:55:07.523574 :C685DA11:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22648 osp_idx=22 osp_ver=819227 osp_pg=0 (spawn #540243) 2025-08-30 05:55:07.523575 :C685DA12:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:55:07.523575 :C685DA13:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=206 time=918958815 2025-08-30 05:55:07.523576 :C685DA14:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:55:07.523577 :C685DA15:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:55:07.523577 :C685DA16:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:55:07.523578 :C685DA17:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:55:07.523578 :C685DA18:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:55:07.524144 :C685DA19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:55:37.587595 :C685E44B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:56:07.616789 :C685EE07:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:56:07.606 2025-08-30 05:56:07.616790 :C685EE08:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21804 pso_num=24 pso_serial#=158 2025-08-30 05:56:07.616933 :C685EE09:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21804 osp_idx=22 osp_ver=819228 osp_pg=0 (spawn #540246) 2025-08-30 05:56:07.616934 :C685EE0A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:56:07.616935 :C685EE0B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=207 time=919018908 2025-08-30 05:56:07.616935 :C685EE0C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:56:07.616935 :C685EE0D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:56:07.616936 :C685EE0E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:56:07.616936 :C685EE0F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:56:07.616936 :C685EE10:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:56:07.617423 :C685EE11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:56:37.658666 :C685F7D3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:57:07.736912 :C686018F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:57:07.726 2025-08-30 05:57:07.736914 :C6860190:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21892 pso_num=24 pso_serial#=159 2025-08-30 05:57:07.737033 :C6860191:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21892 osp_idx=22 osp_ver=819229 osp_pg=0 (spawn #540248) 2025-08-30 05:57:07.737034 :C6860192:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:57:07.737035 :C6860193:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=208 time=919079033 2025-08-30 05:57:07.737035 :C6860194:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:57:07.737035 :C6860195:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:57:07.737036 :C6860196:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:57:07.737036 :C6860197:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:57:07.737036 :C6860198:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:57:07.737509 :C6860199:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:57:37.812315 :C6860BE5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:58:07.831166 :C68615CB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:58:07.820 2025-08-30 05:58:07.831168 :C68615CC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22684 pso_num=24 pso_serial#=160 2025-08-30 05:58:07.831298 :C68615CD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22684 osp_idx=22 osp_ver=819230 osp_pg=0 (spawn #540251) 2025-08-30 05:58:07.831299 :C68615CE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:58:07.831299 :C68615CF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=209 time=919139127 2025-08-30 05:58:07.831300 :C68615D0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:58:07.831300 :C68615D1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:58:07.831300 :C68615D2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:58:07.831301 :C68615D3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:58:07.831301 :C68615D4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:58:07.831807 :C68615D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:58:37.935187 :C6861FD5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 05:59:07.901108 :C6862998:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 05:59:07.890 2025-08-30 05:59:07.901110 :C6862999:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23020 pso_num=24 pso_serial#=161 2025-08-30 05:59:07.901228 :C686299A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23020 osp_idx=22 osp_ver=819231 osp_pg=0 (spawn #540253) 2025-08-30 05:59:07.901229 :C686299B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:59:07.901229 :C686299C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=210 time=919199205 2025-08-30 05:59:07.901230 :C686299D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:59:07.901230 :C686299E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:59:07.901230 :C686299F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:59:07.901230 :C68629A0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:59:07.901230 :C68629A1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:59:07.901709 :C68629A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:59:37.917006 :C6863410:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 06:00:00.042277 :C6863BCE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 06:00:00.033 2025-08-30 06:00:00.042280 :C6863BCF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21328 pso_num=24 pso_serial#=162 2025-08-30 06:00:00.042412 :C6863BD0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21328 osp_idx=22 osp_ver=819232 osp_pg=0 (spawn #540255) 2025-08-30 06:00:00.042412 :C6863BD1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 06:00:00.042413 :C6863BD2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=211 time=919251346 2025-08-30 06:00:00.042413 :C6863BD3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 06:00:00.042414 :C6863BD4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 06:00:00.042414 :C6863BD5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 06:00:00.042414 :C6863BD6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 06:00:00.042415 :C6863BD7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 06:00:00.042917 :C6863BD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.042969 :C6863BDE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 06:00:00.043048 :C6863BF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.047000 :C6863C01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.048189 :C6863C09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.048214 :C6863C0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.048251 :C6863C17:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-30 06:00:00.050079 :C6863C22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.050106 :C6863C25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.050144 :C6863C30:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-30 06:00:00.050597 :C6863C3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.053089 :C6863C43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.058345 :C6863C4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.063808 :C6863C53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.065333 :C6863C5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.068379 :C6863C63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.069695 :C6863C6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.072639 :C6863C73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.076422 :C6863C7B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.076818 :C6863C8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.076867 :C6863C8D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-30 06:00:00.076881 :C6863C8E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-30 06:00:00.079700 :C6863C94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.080008 :C6863CA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.080059 :C6863CA6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-30 06:00:00.080072 :C6863CA7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-30 06:00:00.082975 :C6863CAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.083262 :C6863CBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.083309 :C6863CBF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-30 06:00:00.083324 :C6863CC0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-30 06:00:00.083504 :C6863CC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.083515 :C6863CC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-30 06:00:00.083544 :C6863CD5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-30 06:00:00.085021 :C6863CDF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 06:00:02.038646 :C6863D62:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 06:00:02.033 2025-08-30 06:00:02.038648 :C6863D63:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23312 pso_num=24 pso_serial#=163 2025-08-30 06:00:02.038786 :C6863D64:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23312 osp_idx=22 osp_ver=819233 osp_pg=0 (spawn #540256) 2025-08-30 06:00:02.038787 :C6863D65:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 06:00:02.038788 :C6863D66:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=212 time=919253346 2025-08-30 06:00:02.038788 :C6863D67:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 06:00:02.038788 :C6863D68:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 06:00:02.038789 :C6863D69:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 06:00:02.038789 :C6863D6A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 06:00:02.038790 :C6863D6B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 06:00:02.039282 :C6863D6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.044519 :C6863D8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.044558 :C6863D8E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-30 06:00:02.055917 :C6863E02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.061200 :C6863E2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.061926 :C6863E4A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.067614 :C6863F84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.067778 :C6863F93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.068691 :C6863FA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.069041 :C6863FAD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-30 06:00:02.070723 :C6863FAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.070774 :C6863FB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-30 06:00:02.070851 :C6863FBE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-30 06:00:02.174307 :C68640BD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 23312, J000)