Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250901220005\orcl_j001_20172_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250901220005\orcl_j001_20172_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:35034M/63366M, Ph+PgF:39972M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 20172, image: ORACLE.EXE (J001) *** 2025-09-01 22:00:05.933 *** SESSION ID:(201.27097) 2025-09-01 22:00:05.933 *** 2025-09-01 22:00:05.933 Process diagnostic dump for ORACLE.EXE (J001), OS id=20172, pid: 25, proc_ser: 233, sid: 201, sess_ser: 27097 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20172 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.392673 sec, exc=0.392673 sec, total=0.392673 sec wait times: max=0.500000 sec, heur=1.895378 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=2 seq_num=3 snap_id=1 wait times: snap=0.510116 sec, exc=0.510116 sec, total=0.510116 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.492569 sec, exc=0.492569 sec, total=0.492569 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 201 serial 27097 --------------------------------------------------- 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 --------------------------------------------------- [2 samples, 22:00:04 - 22:00:05] 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-09-01 22:00:05.933 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 20172, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-01 21:50:21.567891 :C7B46A25:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-09-01 21:50:21.568106 :C7B46A26:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-09-01 21:50:21.568120 :C7B46A27:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-09-01 21:50:21.568328 :C7B46A28:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-09-01 21:50:21.568340 :C7B46A29:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-09-01 21:50:21.568541 :C7B46A2A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-09-01 21:50:21.568553 :C7B46A2B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-09-01 21:50:21.568753 :C7B46A2C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-09-01 21:50:21.568766 :C7B46A2D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-09-01 21:50:21.571201 :C7B46A2E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:50:30.955547 :C7B46D57:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:50:30.944 2025-09-01 21:50:30.955548 :C7B46D58:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19796 pso_num=25 pso_serial#=224 2025-09-01 21:50:30.955665 :C7B46D59:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19796 osp_idx=22 osp_ver=826493 osp_pg=0 (spawn #549140) 2025-09-01 21:50:30.955666 :C7B46D5A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:50:30.955667 :C7B46D5B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=222 time=1149082252 2025-09-01 21:50:30.955667 :C7B46D5C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:50:30.955668 :C7B46D5D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:50:30.955668 :C7B46D5E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:50:30.955669 :C7B46D5F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:50:30.955670 :C7B46D60:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:50:30.956184 :C7B46D61: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-09-01 21:51:01.055167 :C7B4774E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:51:31.064177 :C7B48131:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:51:31.052 2025-09-01 21:51:31.064179 :C7B48132:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21664 pso_num=25 pso_serial#=225 2025-09-01 21:51:31.064300 :C7B48133:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21664 osp_idx=22 osp_ver=826494 osp_pg=0 (spawn #549142) 2025-09-01 21:51:31.064302 :C7B48134:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:51:31.064302 :C7B48135:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=223 time=1149142362 2025-09-01 21:51:31.064303 :C7B48136:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:51:31.064303 :C7B48137:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:51:31.064303 :C7B48138:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:51:31.064304 :C7B48139:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:51:31.064304 :C7B4813A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:51:31.064779 :C7B4813B: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-09-01 21:52:01.118425 :C7B48B05:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:52:31.125384 :C7B4950A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:52:31.114 2025-09-01 21:52:31.125387 :C7B4950B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21796 pso_num=25 pso_serial#=226 2025-09-01 21:52:31.125505 :C7B4950C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21796 osp_idx=22 osp_ver=826495 osp_pg=0 (spawn #549144) 2025-09-01 21:52:31.125506 :C7B4950D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:52:31.125507 :C7B4950E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=224 time=1149202424 2025-09-01 21:52:31.125507 :C7B4950F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:52:31.125507 :C7B49510:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:52:31.125508 :C7B49511:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:52:31.125508 :C7B49512:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:52:31.125508 :C7B49513:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:52:31.125990 :C7B49514: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-09-01 21:53:01.235131 :C7B49F26:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:53:31.207139 :C7B4A8FA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:53:31.196 2025-09-01 21:53:31.207141 :C7B4A8FB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22264 pso_num=25 pso_serial#=227 2025-09-01 21:53:31.207266 :C7B4A8FC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22264 osp_idx=22 osp_ver=826496 osp_pg=0 (spawn #549146) 2025-09-01 21:53:31.207267 :C7B4A8FD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:53:31.207267 :C7B4A8FE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=225 time=1149262502 2025-09-01 21:53:31.207268 :C7B4A8FF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:53:31.207268 :C7B4A900:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:53:31.207269 :C7B4A901:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:53:31.207269 :C7B4A902:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:53:31.207270 :C7B4A903:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:53:31.207799 :C7B4A904: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-09-01 21:54:01.278303 :C7B4B2EC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:54:31.289836 :C7B4BC82:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:54:31.278 2025-09-01 21:54:31.289839 :C7B4BC83:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12948 pso_num=25 pso_serial#=228 2025-09-01 21:54:31.289976 :C7B4BC84:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12948 osp_idx=22 osp_ver=826497 osp_pg=0 (spawn #549148) 2025-09-01 21:54:31.289977 :C7B4BC85:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:54:31.289977 :C7B4BC86:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=226 time=1149322580 2025-09-01 21:54:31.289978 :C7B4BC87:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:54:31.289978 :C7B4BC88:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:54:31.289978 :C7B4BC89:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:54:31.289978 :C7B4BC8A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:54:31.289979 :C7B4BC8B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:54:31.290484 :C7B4BC8C: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-09-01 21:55:01.394846 :C7B4C772:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:55:31.387397 :C7B4D179:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:55:31.376 2025-09-01 21:55:31.387399 :C7B4D17A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23304 pso_num=25 pso_serial#=229 2025-09-01 21:55:31.387535 :C7B4D17B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23304 osp_idx=22 osp_ver=826498 osp_pg=0 (spawn #549150) 2025-09-01 21:55:31.387536 :C7B4D17C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:55:31.387536 :C7B4D17D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=227 time=1149382690 2025-09-01 21:55:31.387537 :C7B4D17E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:55:31.387537 :C7B4D17F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:55:31.387537 :C7B4D180:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:55:31.387538 :C7B4D181:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:55:31.387538 :C7B4D182:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:55:31.388024 :C7B4D183: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-09-01 21:56:01.450788 :C7B4DB56:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:56:31.498661 :C7B4E551:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:56:31.487 2025-09-01 21:56:31.498663 :C7B4E552:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22696 pso_num=25 pso_serial#=230 2025-09-01 21:56:31.498794 :C7B4E553:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22696 osp_idx=22 osp_ver=826499 osp_pg=0 (spawn #549152) 2025-09-01 21:56:31.498795 :C7B4E554:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:56:31.498796 :C7B4E555:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=228 time=1149442799 2025-09-01 21:56:31.498796 :C7B4E556:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:56:31.498797 :C7B4E557:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:56:31.498797 :C7B4E558:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:56:31.498797 :C7B4E559:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:56:31.498798 :C7B4E55A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:56:31.499279 :C7B4E55B: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-09-01 21:57:01.612021 :C7B4EF1F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:57:31.582223 :C7B4F8BB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:57:31.571 2025-09-01 21:57:31.582225 :C7B4F8BC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23352 pso_num=25 pso_serial#=231 2025-09-01 21:57:31.582373 :C7B4F8BD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23352 osp_idx=22 osp_ver=826500 osp_pg=0 (spawn #549154) 2025-09-01 21:57:31.582374 :C7B4F8BE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:57:31.582375 :C7B4F8BF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=229 time=1149502877 2025-09-01 21:57:31.582376 :C7B4F8C0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:57:31.582376 :C7B4F8C1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:57:31.582376 :C7B4F8C2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:57:31.582377 :C7B4F8C3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:57:31.582377 :C7B4F8C4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:57:31.582858 :C7B4F8C5: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-09-01 21:58:01.671170 :C7B502DF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:58:16.635905 :C7B50813:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:58:16.624 2025-09-01 21:58:16.635907 :C7B50814:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17248 pso_num=25 pso_serial#=232 2025-09-01 21:58:16.636046 :C7B50815:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17248 osp_idx=22 osp_ver=826501 osp_pg=0 (spawn #549156) 2025-09-01 21:58:16.636047 :C7B50816:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:58:16.636047 :C7B50817:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=230 time=1149547940 2025-09-01 21:58:16.636048 :C7B50818:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:58:16.636048 :C7B50819:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:58:16.636048 :C7B5081A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:58:16.636049 :C7B5081B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:58:16.636049 :C7B5081C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:58:16.636537 :C7B5081D: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-09-01 21:58:31.659249 :C7B50CFA: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-09-01 21:59:01.752866 :C7B516C3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 21:59:31.736899 :C7B520F1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 21:59:31.726 2025-09-01 21:59:31.736901 :C7B520F2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20172 pso_num=25 pso_serial#=233 2025-09-01 21:59:31.737017 :C7B520F3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20172 osp_idx=22 osp_ver=826502 osp_pg=0 (spawn #549158) 2025-09-01 21:59:31.737017 :C7B520F4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 21:59:31.737018 :C7B520F5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=231 time=1149623033 2025-09-01 21:59:31.737018 :C7B520F6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 21:59:31.737019 :C7B520F7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 21:59:31.737019 :C7B520F8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 21:59:31.737019 :C7B520F9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 21:59:31.737019 :C7B520FA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 21:59:31.737494 :C7B520FB: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-09-01 22:00:01.794424 :C7B52C8B: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-09-01 22:00:01.794424*:C7B52C9A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEB898 estart=1756735200 eid=16787199 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-01 22:00:01.794424*:C7B52C9B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 pubTabIdxCnt=0 2025-09-01 22:00:01.794424*:C7B52C9C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEB898 2025-09-01 22:00:01.794424*:C7B52C9D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEB898 2025-09-01 22:00:01.794424*:C7B52C9E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 xsc->flg4=65792 flags=2 2025-09-01 22:00:01.794424*:C7B52C9F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEB898 2025-09-01 22:00:01.799893 :C7B52CAA: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-09-01 22:00:01.799937 :C7B52CAD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-09-01 22:00:01.812345 :C7B52D2E:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-09-01 22:00:01.812928 :C7B52D2F:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 34a5120000000000 2025-09-01 22:00:01.824351 :C7B52DD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-01 22:00:01.824430 :C7B52DE6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-01 22:00:01.824438 :C7B52DE9:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-09-01 22:00:01.824443 :C7B52DEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:00:01.824474 :C7B52DF9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-01 22:00:01.825337 :C7B52E31:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 236226407) 2025-09-01 22:00:01.825342 :C7B52E36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:00:01.825374 :C7B52E42:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-01 22:00:01.825382 :C7B52E4F:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65561 1) 2025-09-01 22:00:01.825385 :C7B52E50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:00:01.825420 :C7B52E5E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-01 22:00:01.825423 :C7B52E64:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65561) 2025-09-01 22:00:01.825426 :C7B52E67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:00:01.825464 :C7B52E8D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-01 22:00:01.825969 :C7B52F53:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-01 22:00:01.825989 :C7B52F66:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-09-01 22:00:01.826861 :C7B52F98:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 35a5120000000000 2025-09-01 22:00:01.827244 :C7B52F9A:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 236226407) 2025-09-01 22:00:01.827250 :C7B52F9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-01 22:00:01.827274 :C7B52FA6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-01 22:00:01.837609 :C7B52FC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-09-01 22:00:01.839386 :C7B52FC5: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-09-01 22:00:01.839446 :C7B52FC8: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-09-01 22:00:01.839594 :C7B52FD5: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-09-01 22:00:01.919257 :C7B52FEE: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-09-01 22:00:04.007465 :C7B53449: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-09-01 22:00:04.007465*:C7B5344A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1756735203 eid=16787205 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-01 22:00:04.007465*:C7B5344B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-09-01 22:00:04.007465*:C7B5344C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-09-01 22:00:04.007465*:C7B5344D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-09-01 22:00:04.007465*:C7B5344E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-09-01 22:00:04.007465*:C7B5344F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-09-01 22:00:04.012713 :C7B53450: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-09-01 22:00:04.012778 :C7B53453:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-09-01 22:00:04.040931 :C7B53459: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-09-01 22:00:04.041754 :C7B53461: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-09-01 22:00:04.042785 :C7B53469: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-09-01 22:00:04.042996 :C7B53471:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-09-01 22:00:04.044519 :C7B53472: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-09-01 22:00:04.044558 :C7B53475: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-09-01 22:00:04.044635 :C7B53482: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: 20172, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de