Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250616220004\orcl_j000_17172_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:36588M/63366M, Ph+PgF:43025M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 17172, image: ORACLE.EXE (J000) *** 2025-06-16 22:00:04.911 *** SESSION ID:(193.39117) 2025-06-16 22:00:04.911 *** 2025-06-16 22:00:04.911 Process diagnostic dump for ORACLE.EXE (J000), OS id=17172, pid: 24, proc_ser: 182, sid: 193, sess_ser: 39117 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17172 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.408365 sec, exc=0.408365 sec, total=0.408365 sec wait times: max=0.500000 sec, heur=0.894829 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.486456 sec, exc=0.486456 sec, total=0.486456 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 39117 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:00:04] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-16 22:00:04.911 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 17172, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-16 21:52:13.648977 :A5A0DCB1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-06-16 21:52:13.649180 :A5A0DCB2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-06-16 21:52:13.649192 :A5A0DCB3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-06-16 21:52:13.649395 :A5A0DCB4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-06-16 21:52:13.649407 :A5A0DCB5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-06-16 21:52:13.651686 :A5A0DCB6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 21:52:20.339575 :A5A0DED0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 21:52:20.328 2025-06-16 21:52:20.339577 :A5A0DED1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14600 pso_num=24 pso_serial#=173 2025-06-16 21:52:20.339699 :A5A0DED2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14600 osp_idx=22 osp_ver=417627 osp_pg=0 (spawn #292473) 2025-06-16 21:52:20.339700 :A5A0DED3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:52:20.339701 :A5A0DED4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=223 time=3086483516 2025-06-16 21:52:20.339701 :A5A0DED5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:52:20.339702 :A5A0DED6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:52:20.339702 :A5A0DED7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:52:20.339702 :A5A0DED8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:52:20.339703 :A5A0DED9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:52:20.340253 :A5A0DEDA: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-06-16 21:52:45.365880 :A5A0E81D: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-06-16 21:53:15.438043 :A5A0F1BF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 21:53:20.421837 :A5A0F35E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 21:53:20.410 2025-06-16 21:53:20.421839 :A5A0F35F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16552 pso_num=24 pso_serial#=174 2025-06-16 21:53:20.421979 :A5A0F360:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16552 osp_idx=22 osp_ver=417628 osp_pg=0 (spawn #292475) 2025-06-16 21:53:20.421979 :A5A0F361:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:53:20.421980 :A5A0F362:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=224 time=3086543610 2025-06-16 21:53:20.421980 :A5A0F363:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:53:20.421980 :A5A0F364:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:53:20.421981 :A5A0F365:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:53:20.421981 :A5A0F366:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:53:20.421981 :A5A0F367:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:53:20.422463 :A5A0F368: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-06-16 21:53:50.532573 :A5A0FD1D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 21:54:20.507125 :A5A106CA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 21:54:20.496 2025-06-16 21:54:20.507127 :A5A106CB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16204 pso_num=24 pso_serial#=175 2025-06-16 21:54:20.507252 :A5A106CC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16204 osp_idx=22 osp_ver=417629 osp_pg=0 (spawn #292477) 2025-06-16 21:54:20.507253 :A5A106CD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:54:20.507253 :A5A106CE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=225 time=3086603688 2025-06-16 21:54:20.507254 :A5A106CF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:54:20.507254 :A5A106D0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:54:20.507255 :A5A106D1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:54:20.507255 :A5A106D2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:54:20.507255 :A5A106D3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:54:20.507738 :A5A106D4: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-06-16 21:54:50.581150 :A5A11042:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 21:55:20.614800 :A5A11A17:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 21:55:20.614 2025-06-16 21:55:20.614802 :A5A11A18:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16620 pso_num=24 pso_serial#=176 2025-06-16 21:55:20.614935 :A5A11A19:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16620 osp_idx=22 osp_ver=417630 osp_pg=0 (spawn #292479) 2025-06-16 21:55:20.614936 :A5A11A1A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:55:20.614936 :A5A11A1B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=226 time=3086663797 2025-06-16 21:55:20.614936 :A5A11A1C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:55:20.614937 :A5A11A1D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:55:20.614937 :A5A11A1E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:55:20.614938 :A5A11A1F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:55:20.614938 :A5A11A20:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:55:20.615448 :A5A11A21: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-06-16 21:55:50.691655 :A5A1248B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 21:56:20.705652 :A5A12E39:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 21:56:20.694 2025-06-16 21:56:20.705654 :A5A12E3A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13420 pso_num=24 pso_serial#=177 2025-06-16 21:56:20.705769 :A5A12E3B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13420 osp_idx=22 osp_ver=417631 osp_pg=0 (spawn #292481) 2025-06-16 21:56:20.705769 :A5A12E3C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:56:20.705770 :A5A12E3D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=227 time=3086723891 2025-06-16 21:56:20.705771 :A5A12E3E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:56:20.705771 :A5A12E3F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:56:20.705771 :A5A12E40:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:56:20.705772 :A5A12E41:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:56:20.705772 :A5A12E42:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:56:20.706338 :A5A12E43: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-06-16 21:56:50.784748 :A5A137E7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 21:57:20.786832 :A5A141A2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 21:57:20.776 2025-06-16 21:57:20.786834 :A5A141A3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12920 pso_num=24 pso_serial#=178 2025-06-16 21:57:20.786955 :A5A141A4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12920 osp_idx=22 osp_ver=417632 osp_pg=0 (spawn #292483) 2025-06-16 21:57:20.786956 :A5A141A5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:57:20.786956 :A5A141A6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=228 time=3086783969 2025-06-16 21:57:20.786957 :A5A141A7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:57:20.786957 :A5A141A8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:57:20.786958 :A5A141A9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:57:20.786958 :A5A141AA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:57:20.786958 :A5A141AB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:57:20.787441 :A5A141AC: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-06-16 21:57:50.902778 :A5A14BC0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 21:58:20.868019 :A5A15584:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 21:58:20.856 2025-06-16 21:58:20.868022 :A5A15585:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13912 pso_num=24 pso_serial#=179 2025-06-16 21:58:20.868150 :A5A15586:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13912 osp_idx=22 osp_ver=417633 osp_pg=0 (spawn #292485) 2025-06-16 21:58:20.868151 :A5A15587:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:58:20.868151 :A5A15588:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=229 time=3086844047 2025-06-16 21:58:20.868151 :A5A15589:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:58:20.868152 :A5A1558A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:58:20.868152 :A5A1558B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:58:20.868152 :A5A1558C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:58:20.868153 :A5A1558D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:58:20.868628 :A5A1558E: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-06-16 21:58:50.981166 :A5A15F0C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 21:59:20.956042 :A5A168E8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 21:59:20.945 2025-06-16 21:59:20.956044 :A5A168E9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15484 pso_num=24 pso_serial#=180 2025-06-16 21:59:20.956171 :A5A168EA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15484 osp_idx=22 osp_ver=417634 osp_pg=0 (spawn #292487) 2025-06-16 21:59:20.956172 :A5A168EB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:59:20.956173 :A5A168EC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=230 time=3086904141 2025-06-16 21:59:20.956174 :A5A168ED:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:59:20.956174 :A5A168EE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:59:20.956174 :A5A168EF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:59:20.956175 :A5A168F0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:59:20.956175 :A5A168F1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:59:20.956650 :A5A168F2: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-06-16 21:59:51.027681 :A5A172DC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 22:00:00.045122 :A5A1761D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 22:00:00.036 2025-06-16 22:00:00.045124 :A5A1761E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10976 pso_num=24 pso_serial#=181 2025-06-16 22:00:00.045256 :A5A1761F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10976 osp_idx=22 osp_ver=417635 osp_pg=0 (spawn #292490) 2025-06-16 22:00:00.045257 :A5A17620:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 22:00:00.045257 :A5A17621:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=231 time=3086943235 2025-06-16 22:00:00.045257 :A5A17622:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 22:00:00.045258 :A5A17623:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 22:00:00.045258 :A5A17624:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 22:00:00.045258 :A5A17625:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 22:00:00.045259 :A5A17626:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 22:00:00.045807 :A5A17627: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-06-16 22:00:00.045850 :A5A1762E: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-06-16 22:00:00.045924 :A5A17641: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-06-16 22:00:00.049773 :A5A17650: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-06-16 22:00:00.050959 :A5A17658: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-06-16 22:00:00.050985 :A5A1765B: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-06-16 22:00:00.051028 :A5A17666: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-06-16 22:00:00.052765 :A5A17671: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-06-16 22:00:00.052793 :A5A17674: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-06-16 22:00:00.052827 :A5A1767F: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-06-16 22:00:00.053276 :A5A1768A: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-06-16 22:00:00.055788 :A5A17692: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-06-16 22:00:00.061021 :A5A1769A: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-06-16 22:00:00.066838 :A5A176A2: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-06-16 22:00:00.068265 :A5A176AA: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-06-16 22:00:00.071255 :A5A176B2: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-06-16 22:00:00.072557 :A5A176BA: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-06-16 22:00:00.075653 :A5A176C2: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-06-16 22:00:00.079018 :A5A176CA: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-06-16 22:00:00.079444 :A5A176D9: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-06-16 22:00:00.079503 :A5A176DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-06-16 22:00:00.079519 :A5A176DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-06-16 22:00:00.081488 :A5A176E3: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-06-16 22:00:00.081533 :A5A176E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-06-16 22:00:00.081545 :A5A176E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-06-16 22:00:00.083553 :A5A176ED: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-06-16 22:00:00.083601 :A5A176F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-06-16 22:00:00.083612 :A5A176F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-06-16 22:00:00.083791 :A5A176F7: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-06-16 22:00:00.083805 :A5A176FA: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-06-16 22:00:00.083841 :A5A17705: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-06-16 22:00:00.085352 :A5A17710:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-16 22:00:00.994934 :A5A17777:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-16 22:00:00.989 2025-06-16 22:00:00.994936 :A5A17778:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17172 pso_num=24 pso_serial#=182 2025-06-16 22:00:00.995080 :A5A17779:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17172 osp_idx=22 osp_ver=417636 osp_pg=0 (spawn #292491) 2025-06-16 22:00:00.995081 :A5A1777A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 22:00:00.995081 :A5A1777B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=232 time=3086944188 2025-06-16 22:00:00.995082 :A5A1777C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 22:00:00.995082 :A5A1777D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 22:00:00.995082 :A5A1777E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 22:00:00.995083 :A5A1777F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 22:00:00.995083 :A5A17780:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 22:00:00.995571 :A5A17781: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-06-16 22:00:01.001123 :A5A177A4: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-06-16 22:00:01.001169 :A5A177AF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-16 22:00:01.012634 :A5A17801: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-06-16 22:00:01.017888 :A5A1781E: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-06-16 22:00:01.018649 :A5A1783B: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-06-16 22:00:01.024577 :A5A1786B: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-06-16 22:00:01.024731 :A5A1787A: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-06-16 22:00:01.025460 :A5A1788C: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-06-16 22:00:01.025703 :A5A17894:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-16 22:00:01.027299 :A5A17895: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-06-16 22:00:01.027342 :A5A17898: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-06-16 22:00:01.027413 :A5A178A5: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-06-16 22:00:01.130294 :A5A17B16: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-06-16 22:00:04.005223 :A5A17FE4: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-06-16 22:00:04.005223*:A5A17FE7:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1750082403 eid=16782469 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-16 22:00:04.005223*:A5A17FE8:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-06-16 22:00:04.005223*:A5A17FE9:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-06-16 22:00:04.005223*:A5A17FEA:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-06-16 22:00:04.005223*:A5A17FEB:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-06-16 22:00:04.005223*:A5A17FEC:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-06-16 22:00:04.007325 :A5A17FED: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-06-16 22:00:04.007372 :A5A17FF0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-16 22:00:04.013998 :A5A17FF6: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-06-16 22:00:04.014725 :A5A17FFE: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-06-16 22:00:04.015517 :A5A18006: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-06-16 22:00:04.015706 :A5A1800E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-16 22:00:04.017120 :A5A1800F: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-06-16 22:00:04.017159 :A5A18012: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-06-16 22:00:04.017230 :A5A1801F: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 17172, J000)