Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250817060009\orcl_j001_22072_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:35405M/63366M, Ph+PgF:40667M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 22072, image: ORACLE.EXE (J001) *** 2025-08-17 06:00:09.088 *** SESSION ID:(201.12757) 2025-08-17 06:00:09.088 *** 2025-08-17 06:00:09.088 Process diagnostic dump for ORACLE.EXE (J001), OS id=22072, pid: 25, proc_ser: 26, sid: 201, sess_ser: 12757 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22072 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.415615 sec, exc=0.415615 sec, total=0.415615 sec wait times: max=0.500000 sec, heur=6.936693 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.510124 sec, exc=0.510124 sec, total=0.510124 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.510086 sec, exc=0.510086 sec, total=0.510086 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 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=5 seq_num=6 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=4 seq_num=5 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.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000007 sec of elapsed time Sampled Session History of session 201 serial 12757 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 06:00:02 - 06:00:09] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-08-17 06:00:09.103 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 22072, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-17 05:46:58.144226 :C0CF8D5C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=102 time=4090287439 2025-08-17 05:46:58.144227 :C0CF8D5D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:46:58.144227 :C0CF8D5E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:46:58.144228 :C0CF8D5F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:46:58.144228 :C0CF8D60:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:46:58.144228 :C0CF8D61:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:46:58.144710 :C0CF8D62: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-17 05:47:28.223308 :C0CF9729:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:47:58.263778 :C0CFA0F5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:47:58.253 2025-08-17 05:47:58.263780 :C0CFA0F6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19716 pso_num=25 pso_serial#=14 2025-08-17 05:47:58.263912 :C0CFA0F7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19716 osp_idx=23 osp_ver=243651 osp_pg=0 (spawn #496899) 2025-08-17 05:47:58.263913 :C0CFA0F8: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-17 05:47:58.263914 :C0CFA0F9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=103 time=4090347564 2025-08-17 05:47:58.263914 :C0CFA0FA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:47:58.263914 :C0CFA0FB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:47:58.263915 :C0CFA0FC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:47:58.263915 :C0CFA0FD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:47:58.263915 :C0CFA0FE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:47:58.264399 :C0CFA0FF: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-17 05:48:28.344664 :C0CFAAF0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:48:58.373975 :C0CFB474:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:48:58.362 2025-08-17 05:48:58.373977 :C0CFB475:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19932 pso_num=25 pso_serial#=15 2025-08-17 05:48:58.374103 :C0CFB476:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19932 osp_idx=23 osp_ver=243652 osp_pg=0 (spawn #496901) 2025-08-17 05:48:58.374104 :C0CFB477: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-17 05:48:58.374104 :C0CFB478:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=104 time=4090407673 2025-08-17 05:48:58.374105 :C0CFB479:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:48:58.374105 :C0CFB47A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:48:58.374106 :C0CFB47B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:48:58.374106 :C0CFB47C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:48:58.374107 :C0CFB47D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:48:58.374574 :C0CFB47E: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-17 05:49:28.437397 :C0CFBE7B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:49:58.461931 :C0CFC87F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:49:58.451 2025-08-17 05:49:58.461933 :C0CFC880:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20148 pso_num=25 pso_serial#=16 2025-08-17 05:49:58.462069 :C0CFC881:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20148 osp_idx=23 osp_ver=243653 osp_pg=0 (spawn #496904) 2025-08-17 05:49:58.462069 :C0CFC882: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-17 05:49:58.462070 :C0CFC883:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=105 time=4090467767 2025-08-17 05:49:58.462070 :C0CFC884:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:49:58.462070 :C0CFC885:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:49:58.462071 :C0CFC886:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:49:58.462071 :C0CFC887:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:49:58.462072 :C0CFC888:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:49:58.462544 :C0CFC889: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-17 05:50:28.531533 :C0CFD2E3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:50:58.520170 :C0CFDCB0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:50:58.509 2025-08-17 05:50:58.520171 :C0CFDCB1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22028 pso_num=25 pso_serial#=17 2025-08-17 05:50:58.520297 :C0CFDCB2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22028 osp_idx=23 osp_ver=243654 osp_pg=0 (spawn #496906) 2025-08-17 05:50:58.520297 :C0CFDCB3: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-17 05:50:58.520297 :C0CFDCB4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=106 time=4090527814 2025-08-17 05:50:58.520298 :C0CFDCB5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:50:58.520298 :C0CFDCB6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:50:58.520298 :C0CFDCB7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:50:58.520299 :C0CFDCB8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:50:58.520299 :C0CFDCB9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:50:58.520699 :C0CFDCBA: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-17 05:51:28.591659 :C0CFE65B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:51:58.602337 :C0CFF00F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:51:58.591 2025-08-17 05:51:58.602339 :C0CFF010:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21836 pso_num=25 pso_serial#=18 2025-08-17 05:51:58.602468 :C0CFF011:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21836 osp_idx=23 osp_ver=243655 osp_pg=0 (spawn #496908) 2025-08-17 05:51:58.602468 :C0CFF012: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-17 05:51:58.602469 :C0CFF013:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=107 time=4090587908 2025-08-17 05:51:58.602469 :C0CFF014:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:51:58.602470 :C0CFF015:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:51:58.602470 :C0CFF016:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:51:58.602470 :C0CFF017:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:51:58.602471 :C0CFF018:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:51:58.602981 :C0CFF019: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-17 05:52:28.645085 :C0CFF9A8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:52:58.682297 :C0D0035E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:52:58.670 2025-08-17 05:52:58.682299 :C0D0035F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21368 pso_num=25 pso_serial#=19 2025-08-17 05:52:58.682413 :C0D00360:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21368 osp_idx=23 osp_ver=243656 osp_pg=0 (spawn #496910) 2025-08-17 05:52:58.682414 :C0D00361: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-17 05:52:58.682414 :C0D00362:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=108 time=4090647986 2025-08-17 05:52:58.682414 :C0D00363:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:52:58.682415 :C0D00364:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:52:58.682415 :C0D00365:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:52:58.682415 :C0D00366:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:52:58.682416 :C0D00367:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:52:58.682930 :C0D00368: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-17 05:53:28.778799 :C0D00D58:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:53:58.789313 :C0D01721:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:53:58.777 2025-08-17 05:53:58.789315 :C0D01722:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21168 pso_num=25 pso_serial#=20 2025-08-17 05:53:58.789442 :C0D01723:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21168 osp_idx=23 osp_ver=243657 osp_pg=0 (spawn #496912) 2025-08-17 05:53:58.789443 :C0D01724: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-17 05:53:58.789443 :C0D01725:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=109 time=4090708095 2025-08-17 05:53:58.789443 :C0D01726:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:53:58.789444 :C0D01727:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:53:58.789444 :C0D01728:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:53:58.789445 :C0D01729:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:53:58.789445 :C0D0172A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:53:58.789941 :C0D0172B: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-17 05:54:13.788097 :C0D01C04: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-17 05:54:43.901247 :C0D0269D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:54:58.859971 :C0D02BD2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:54:58.848 2025-08-17 05:54:58.859973 :C0D02BD3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19060 pso_num=25 pso_serial#=21 2025-08-17 05:54:58.860108 :C0D02BD4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19060 osp_idx=23 osp_ver=243658 osp_pg=0 (spawn #496915) 2025-08-17 05:54:58.860109 :C0D02BD5: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-17 05:54:58.860109 :C0D02BD6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=110 time=4090768158 2025-08-17 05:54:58.860110 :C0D02BD7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:54:58.860110 :C0D02BD8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:54:58.860111 :C0D02BD9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:54:58.860111 :C0D02BDA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:54:58.860111 :C0D02BDB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:54:58.860617 :C0D02BDC: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-17 05:55:28.965669 :C0D036A9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:55:58.954154 :C0D04081:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:55:58.941 2025-08-17 05:55:58.954157 :C0D04082:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22148 pso_num=25 pso_serial#=22 2025-08-17 05:55:58.954334 :C0D04083:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22148 osp_idx=23 osp_ver=243659 osp_pg=0 (spawn #496917) 2025-08-17 05:55:58.954335 :C0D04084: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-17 05:55:58.954335 :C0D04085:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=111 time=4090828251 2025-08-17 05:55:58.954336 :C0D04086:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:55:58.954336 :C0D04087:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:55:58.954336 :C0D04088:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:55:58.954337 :C0D04089:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:55:58.954337 :C0D0408A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:55:58.954908 :C0D0408B: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-17 05:56:29.035438 :C0D04A55:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:56:59.048465 :C0D05408:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:56:59.037 2025-08-17 05:56:59.048467 :C0D05409:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4148 pso_num=25 pso_serial#=23 2025-08-17 05:56:59.048601 :C0D0540A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4148 osp_idx=23 osp_ver=243660 osp_pg=0 (spawn #496919) 2025-08-17 05:56:59.048601 :C0D0540B: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-17 05:56:59.048602 :C0D0540C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=112 time=4090888345 2025-08-17 05:56:59.048602 :C0D0540D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:56:59.048603 :C0D0540E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:56:59.048603 :C0D0540F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:56:59.048603 :C0D05410:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:56:59.048604 :C0D05411:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:56:59.049082 :C0D05412: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-17 05:57:29.110903 :C0D05D9B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:57:59.147795 :C0D06725:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:57:59.136 2025-08-17 05:57:59.147797 :C0D06726:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19852 pso_num=25 pso_serial#=24 2025-08-17 05:57:59.147935 :C0D06727:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19852 osp_idx=23 osp_ver=243661 osp_pg=0 (spawn #496921) 2025-08-17 05:57:59.147936 :C0D06728: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-17 05:57:59.147936 :C0D06729:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=113 time=4090948454 2025-08-17 05:57:59.147937 :C0D0672A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:57:59.147937 :C0D0672B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:57:59.147938 :C0D0672C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:57:59.147938 :C0D0672D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:57:59.147938 :C0D0672E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:57:59.148400 :C0D0672F: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-17 05:58:29.215282 :C0D07165:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:58:59.230573 :C0D07B0D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:58:59.219 2025-08-17 05:58:59.230576 :C0D07B0E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21428 pso_num=25 pso_serial#=25 2025-08-17 05:58:59.230697 :C0D07B0F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21428 osp_idx=23 osp_ver=243662 osp_pg=0 (spawn #496923) 2025-08-17 05:58:59.230697 :C0D07B10: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-17 05:58:59.230698 :C0D07B11:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=114 time=4091008533 2025-08-17 05:58:59.230698 :C0D07B12:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:58:59.230698 :C0D07B13:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:58:59.230698 :C0D07B14:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:58:59.230699 :C0D07B15:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:58:59.230699 :C0D07B16:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:58:59.231192 :C0D07B17: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-17 05:59:29.309693 :C0D0852F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-17 05:59:59.334334 :C0D08F31:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-17 05:59:59.323 2025-08-17 05:59:59.334336 :C0D08F32:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22072 pso_num=25 pso_serial#=26 2025-08-17 05:59:59.334478 :C0D08F33:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22072 osp_idx=23 osp_ver=243663 osp_pg=0 (spawn #496927) 2025-08-17 05:59:59.334479 :C0D08F34: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-17 05:59:59.334480 :C0D08F35:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=115 time=4091068642 2025-08-17 05:59:59.334481 :C0D08F36:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:59:59.334481 :C0D08F37:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:59:59.334482 :C0D08F38:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:59:59.334482 :C0D08F39:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:59:59.334483 :C0D08F3A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:59:59.334987 :C0D08F3B: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-17 06:00:02.032108 :C0D0925D: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-17 06:00:02.032108*:C0D0926C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1755381601 eid=16786233 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-17 06:00:02.032108*:C0D0926D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-08-17 06:00:02.032108*:C0D0926E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-08-17 06:00:02.032108*:C0D0926F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-08-17 06:00:02.032108*:C0D09270:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-08-17 06:00:02.032108*:C0D09271:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-08-17 06:00:02.038106 :C0D09280: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-17 06:00:02.038161 :C0D09285:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-17 06:00:02.049027 :C0D092DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-17 06:00:02.049888 :C0D092F0: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-17 06:00:02.054661 :C0D0930D: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-17 06:00:02.055467 :C0D0931A: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-17 06:00:02.061484 :C0D0948C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-17 06:00:02.061648 :C0D0949B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 06:00:02.062540 :C0D094A3: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-17 06:00:02.062794 :C0D094AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-17 06:00:02.064316 :C0D094AC: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-17 06:00:02.064361 :C0D094AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-17 06:00:02.064433 :C0D094BC: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-17 06:00:02.156997 :C0D09591: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-17 06:00:02.159048 :C0D09596: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-17 06:00:02.159095 :C0D09599:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-17 06:00:02.161478 :C0D0959F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-17 06:00:02.164690 :C0D095A0: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-17 06:00:02.164774 :C0D095A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-17 06:00:02.164857 :C0D095B0: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 22072, J001)