Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525140428\orcl_j001_15200_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:37329M/63366M, Ph+PgF:44388M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 15200, image: ORACLE.EXE (J001) *** 2025-05-25 14:04:28.815 *** SESSION ID:(209.63187) 2025-05-25 14:04:28.815 *** 2025-05-25 14:04:28.815 Process diagnostic dump for ORACLE.EXE (J001), OS id=15200, pid: 26, proc_ser: 123, sid: 209, sess_ser: 63187 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15200 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.167605 sec, exc=0.167605 sec, total=0.167605 sec wait times: max=0.500000 sec, heur=6.173155 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500104 sec, exc=0.500104 sec, total=0.500104 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=6 seq_num=7 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.512950 sec, exc=0.512950 sec, total=0.512950 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 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=3 seq_num=4 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000013 sec of elapsed time Sampled Session History of session 209 serial 63187 --------------------------------------------------- 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, 14:04:22 - 14:04:28] idle wait at each sample [session created at: 14:04:22] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 14:04:28.815 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 15200, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 13:54:56.030919 :9BD3C3DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:ktcc.c:879 2025-05-25 13:54:56.030954 :9BD3C3DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:ktcc.c:879 2025-05-25 13:54:56.031242 :9BD3C3E2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:26:209] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-25 13:54:56.031587 :9BD3C3E3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:26:209] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-25 13:54:56.031707 :9BD3C3E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 13:54:56.031913 :9BD3C3E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 13:54:56.031934 :9BD3C3E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 13:54:56.033469 :9BD3C3EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 13:54:56.033661 :9BD3C3EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 13:54:56.033688 :9BD3C3F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 13:54:56.034558 :9BD3C3F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 13:55:26.131404 :9BD3CDC6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 13:55:56.045631 :9BD3D778:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 13:55:56.034 2025-05-25 13:55:56.045634 :9BD3D779:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15244 pso_num=26 pso_serial#=115 2025-05-25 13:55:56.045765 :9BD3D77A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15244 osp_idx=24 osp_ver=325240 osp_pg=0 (spawn #218043) 2025-05-25 13:55:56.045766 :9BD3D77B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:55:56.045766 :9BD3D77C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=242 time=1157128297 2025-05-25 13:55:56.045767 :9BD3D77D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:55:56.045767 :9BD3D77E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:55:56.045767 :9BD3D77F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:55:56.045768 :9BD3D780:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:55:56.045768 :9BD3D781:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:55:56.046246 :9BD3D782:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:56:26.176168 :9BD3E141:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 13:56:56.164893 :9BD3EAF4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 13:56:56.154 2025-05-25 13:56:56.164896 :9BD3EAF5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14140 pso_num=26 pso_serial#=116 2025-05-25 13:56:56.165030 :9BD3EAF6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14140 osp_idx=24 osp_ver=325241 osp_pg=0 (spawn #218045) 2025-05-25 13:56:56.165030 :9BD3EAF7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:56:56.165031 :9BD3EAF8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=243 time=1157188407 2025-05-25 13:56:56.165031 :9BD3EAF9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:56:56.165031 :9BD3EAFA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:56:56.165032 :9BD3EAFB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:56:56.165032 :9BD3EAFC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:56:56.165032 :9BD3EAFD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:56:56.165537 :9BD3EAFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:57:26.269962 :9BD3F4B6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 13:57:56.260222 :9BD3FE50:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 13:57:56.249 2025-05-25 13:57:56.260224 :9BD3FE51:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13756 pso_num=26 pso_serial#=117 2025-05-25 13:57:56.260357 :9BD3FE52:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13756 osp_idx=24 osp_ver=325242 osp_pg=0 (spawn #218047) 2025-05-25 13:57:56.260358 :9BD3FE53:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:57:56.260358 :9BD3FE54:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=244 time=1157248516 2025-05-25 13:57:56.260358 :9BD3FE55:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:57:56.260359 :9BD3FE56:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:57:56.260359 :9BD3FE57:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:57:56.260359 :9BD3FE58:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:57:56.260359 :9BD3FE59:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:57:56.260844 :9BD3FE5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:58:26.330518 :9BD40835:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 13:58:56.327512 :9BD411E7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 13:58:56.317 2025-05-25 13:58:56.327513 :9BD411E8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8780 pso_num=26 pso_serial#=118 2025-05-25 13:58:56.327649 :9BD411E9:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8780 osp_idx=24 osp_ver=325243 osp_pg=0 (spawn #218049) 2025-05-25 13:58:56.327649 :9BD411EA:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:58:56.327650 :9BD411EB:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=245 time=1157308579 2025-05-25 13:58:56.327650 :9BD411EC:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:58:56.327650 :9BD411ED:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:58:56.327651 :9BD411EE:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:58:56.327651 :9BD411EF:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:58:56.327652 :9BD411F0:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:58:56.328117 :9BD411F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 13:59:26.394164 :9BD41C02:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 13:59:56.470118 :9BD4262A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 13:59:56.459 2025-05-25 13:59:56.470120 :9BD4262B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14072 pso_num=26 pso_serial#=119 2025-05-25 13:59:56.470246 :9BD4262C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14072 osp_idx=24 osp_ver=325244 osp_pg=0 (spawn #218052) 2025-05-25 13:59:56.470247 :9BD4262D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 13:59:56.470247 :9BD4262E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=246 time=1157368719 2025-05-25 13:59:56.470248 :9BD4262F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 13:59:56.470248 :9BD42630:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 13:59:56.470248 :9BD42631:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 13:59:56.470249 :9BD42632:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 13:59:56.470249 :9BD42633:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 13:59:56.470744 :9BD42634:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 14:00:26.558660 :9BD43199:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 14:00:56.557938 :9BD43B17:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 14:00:56.547 2025-05-25 14:00:56.557940 :9BD43B18:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10200 pso_num=26 pso_serial#=120 2025-05-25 14:00:56.558066 :9BD43B19:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10200 osp_idx=24 osp_ver=325245 osp_pg=0 (spawn #218056) 2025-05-25 14:00:56.558066 :9BD43B1A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 14:00:56.558067 :9BD43B1B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=247 time=1157428813 2025-05-25 14:00:56.558067 :9BD43B1C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 14:00:56.558068 :9BD43B1D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 14:00:56.558068 :9BD43B1E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 14:00:56.558068 :9BD43B1F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 14:00:56.558069 :9BD43B20:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 14:00:56.558534 :9BD43B21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 14:01:26.645538 :9BD44597:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 14:01:56.642693 :9BD44F38:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 14:01:56.641 2025-05-25 14:01:56.642696 :9BD44F39:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14148 pso_num=26 pso_serial#=121 2025-05-25 14:01:56.642833 :9BD44F3A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14148 osp_idx=24 osp_ver=325246 osp_pg=0 (spawn #218058) 2025-05-25 14:01:56.642833 :9BD44F3B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 14:01:56.642834 :9BD44F3C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=248 time=1157488891 2025-05-25 14:01:56.642835 :9BD44F3D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 14:01:56.642835 :9BD44F3E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 14:01:56.642835 :9BD44F3F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 14:01:56.642836 :9BD44F40:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 14:01:56.642836 :9BD44F41:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 14:01:56.643341 :9BD44F42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 14:02:26.736740 :9BD458F9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 14:02:56.752033 :9BD462CE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 14:02:56.741 2025-05-25 14:02:56.752035 :9BD462CF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13380 pso_num=26 pso_serial#=122 2025-05-25 14:02:56.752171 :9BD462D0:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13380 osp_idx=24 osp_ver=325247 osp_pg=0 (spawn #218061) 2025-05-25 14:02:56.752172 :9BD462D1:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 14:02:56.752172 :9BD462D2:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=249 time=1157549000 2025-05-25 14:02:56.752173 :9BD462D3:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 14:02:56.752173 :9BD462D4:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 14:02:56.752174 :9BD462D5:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 14:02:56.752174 :9BD462D6:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 14:02:56.752174 :9BD462D7:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 14:02:56.752703 :9BD462D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 14:03:26.822511 :9BD46CBE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-25 14:03:56.861624 :9BD4763A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-25 14:03:56.850 2025-05-25 14:03:56.861626 :9BD4763B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15200 pso_num=26 pso_serial#=123 2025-05-25 14:03:56.861755 :9BD4763C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15200 osp_idx=24 osp_ver=325248 osp_pg=0 (spawn #218063) 2025-05-25 14:03:56.861756 :9BD4763D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 14:03:56.861756 :9BD4763E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=250 time=1157609110 2025-05-25 14:03:56.861757 :9BD4763F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 14:03:56.861757 :9BD47640:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 14:03:56.861758 :9BD47641:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 14:03:56.861758 :9BD47642:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 14:03:56.861758 :9BD47643:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 14:03:56.862234 :9BD47644:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 14:04:20.851323 :9BD47F62:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 14:04:20.957812 :9BD4828C:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 14:04:20.957812*:9BD4829A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271EC718 estart=1748153060 eid=16781095 ctx=0x000000077FD6A000 cwh=0x00000000271E0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-25 14:04:20.957812*:9BD4829B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000271EC718 curCtx=0x00000000271E8938 pubTabIdxCnt=0 2025-05-25 14:04:20.957812*:9BD4829C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000271EC718 2025-05-25 14:04:20.957812*:9BD4829D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000271EC718 2025-05-25 14:04:20.957812*:9BD4829E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000271EC718 curCtx=0x00000000271E8938 xsc->flg4=65792 flags=2 2025-05-25 14:04:20.957812*:9BD4829F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000271EC718 2025-05-25 14:04:20.962955 :9BD482AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:20.962994 :9BD482AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-05-25 14:04:20.966866 :9BD482BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:21.073816 :9BD482D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:21.201214 :9BD482D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:21.331564 :9BD482E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.479987 :9BD48337:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.481031 :9BD4833F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.482755 :9BD48347:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.485326 :9BD4834F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.488429 :9BD48357:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.490084 :9BD4835F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.505185 :9BD48367:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.505987 :9BD4836F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.506588 :9BD48377:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.507116 :9BD4837F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.509786 :9BD48387:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.510757 :9BD4838F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 14:04:22.511580 :9BD48390:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 14:04:22.512355 :9BD48391:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 14:04:22.512783 :9BD48392:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 14:04:22.512790 :9BD48393:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 14:04:22.513370 :9BD48394:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 14:04:22.513944 :9BD48395:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 14:04:22.514280 :9BD48396:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 14:04:22.515367 :9BD48397:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 14:04:22.543989 :9BD48398:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.544044 :9BD4839B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 14:04:22.545125 :9BD483A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.546070 :9BD483A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 14:04:22.546538 :9BD483AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.566095 :9BD483BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.566151 :9BD483BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 14:04:22.567062 :9BD483C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.568017 :9BD483CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 14:04:22.568510 :9BD483CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.589118 :9BD4840D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.589168 :9BD48410:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 14:04:22.590096 :9BD48416:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.601939 :9BD4841E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 14:04:22.602503 :9BD4841F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.622053 :9BD48427:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.622107 :9BD4842A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 14:04:22.623038 :9BD48430:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.623930 :9BD48438:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-25 14:04:22.624421 :9BD48439:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.644127 :9BD48441:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.644213 :9BD48444:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-25 14:04:22.645178 :9BD4844A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.645690 :9BD48452:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.646080 :9BD4845A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.646259 :9BD48462:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.646398 :9BD4846A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.646561 :9BD48472:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-05-25 14:04:22.650670 :9BD48473:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.650761 :9BD48476:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-25 14:04:22.650860 :9BD48483:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 15200, J001)