Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250531220508\orcl_j001_14724_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250531220508\orcl_j001_14724_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:37203M/63366M, Ph+PgF:44064M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 14724, image: ORACLE.EXE (J001) *** 2025-05-31 22:05:08.950 *** SESSION ID:(209.11319) 2025-05-31 22:05:08.950 *** 2025-05-31 22:05:08.950 Process diagnostic dump for ORACLE.EXE (J001), OS id=14724, pid: 26, proc_ser: 142, sid: 209, sess_ser: 11319 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14724 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.477365 sec, exc=0.477365 sec, total=0.477365 sec wait times: max=0.500000 sec, heur=5.487281 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500010 sec, exc=0.500010 sec, total=0.500010 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.510102 sec, exc=0.510102 sec, total=0.510102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: 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.000012 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510105 sec, exc=0.510105 sec, total=0.510105 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.505095 sec, exc=0.505095 sec, total=0.505095 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.484519 sec, exc=0.484519 sec, total=0.484519 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 209 serial 11319 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 22:05:03 - 22:05:08] idle wait at each sample [session created at: 22:05:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-31 22:05:08.950 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 14724, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-31 21:55:40.688278 :9E9B4E00:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 21:55:40.688751 :9E9B4E01: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-31 21:56:10.734058 :9E9B57E5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-31 21:56:40.749484 :9E9B61C7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 21:56:40.738 2025-05-31 21:56:40.749486 :9E9B61C8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13332 pso_num=26 pso_serial#=134 2025-05-31 21:56:40.749622 :9E9B61C9:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13332 osp_idx=24 osp_ver=364784 osp_pg=0 (spawn #239145) 2025-05-31 21:56:40.749623 :9E9B61CA: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-31 21:56:40.749623 :9E9B61CB:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=152 time=1704359391 2025-05-31 21:56:40.749624 :9E9B61CC:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 21:56:40.749624 :9E9B61CD:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 21:56:40.749624 :9E9B61CE:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 21:56:40.749625 :9E9B61CF:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 21:56:40.749625 :9E9B61D0:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 21:56:40.750091 :9E9B61D1: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-31 21:57:10.798757 :9E9B6B89:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-31 21:57:40.849149 :9E9B753D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 21:57:40.838 2025-05-31 21:57:40.849151 :9E9B753E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6844 pso_num=26 pso_serial#=135 2025-05-31 21:57:40.849290 :9E9B753F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=6844 osp_idx=24 osp_ver=364785 osp_pg=0 (spawn #239147) 2025-05-31 21:57:40.849291 :9E9B7540: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-31 21:57:40.849291 :9E9B7541:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=153 time=1704419485 2025-05-31 21:57:40.849291 :9E9B7542:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 21:57:40.849292 :9E9B7543:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 21:57:40.849292 :9E9B7544:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 21:57:40.849292 :9E9B7545:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 21:57:40.849293 :9E9B7546:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 21:57:40.849781 :9E9B7547: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-31 21:58:10.910165 :9E9B7F39:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-31 21:58:40.943790 :9E9B8947:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 21:58:40.932 2025-05-31 21:58:40.943793 :9E9B8948:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8784 pso_num=26 pso_serial#=136 2025-05-31 21:58:40.943921 :9E9B8949:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8784 osp_idx=24 osp_ver=364786 osp_pg=0 (spawn #239149) 2025-05-31 21:58:40.943921 :9E9B894A: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-31 21:58:40.943922 :9E9B894B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=154 time=1704479579 2025-05-31 21:58:40.943922 :9E9B894C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 21:58:40.943922 :9E9B894D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 21:58:40.943923 :9E9B894E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 21:58:40.943923 :9E9B894F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 21:58:40.943923 :9E9B8950:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 21:58:40.944397 :9E9B8951: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-31 21:59:11.011897 :9E9B9331:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-31 21:59:15.991025 :9E9B9509:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 21:59:15.979 2025-05-31 21:59:15.991027 :9E9B950A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14952 pso_num=26 pso_serial#=137 2025-05-31 21:59:15.991153 :9E9B950B:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14952 osp_idx=24 osp_ver=364787 osp_pg=0 (spawn #239151) 2025-05-31 21:59:15.991154 :9E9B950C: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-31 21:59:15.991155 :9E9B950D:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=155 time=1704514625 2025-05-31 21:59:15.991155 :9E9B950E:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 21:59:15.991156 :9E9B950F:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 21:59:15.991156 :9E9B9510:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 21:59:15.991157 :9E9B9511:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 21:59:15.991157 :9E9B9512:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 21:59:15.991729 :9E9B9513: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-31 21:59:41.029106 :9E9B9D2A: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-31 22:00:04.013806 :9E9BA645: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-31 22:00:34.100563 :9E9BB009:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-31 22:00:41.124051 :9E9BB29A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 22:00:41.113 2025-05-31 22:00:41.124053 :9E9BB29B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10976 pso_num=26 pso_serial#=138 2025-05-31 22:00:41.124182 :9E9BB29C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10976 osp_idx=24 osp_ver=364788 osp_pg=0 (spawn #239156) 2025-05-31 22:00:41.124183 :9E9BB29D: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-31 22:00:41.124184 :9E9BB29E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=156 time=1704599766 2025-05-31 22:00:41.124184 :9E9BB29F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 22:00:41.124184 :9E9BB2A0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 22:00:41.124185 :9E9BB2A1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 22:00:41.124185 :9E9BB2A2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 22:00:41.124185 :9E9BB2A3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 22:00:41.124695 :9E9BB2AC: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-31 22:01:11.205130 :9E9BBD22:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-31 22:01:41.215772 :9E9BC6ED:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 22:01:41.204 2025-05-31 22:01:41.215775 :9E9BC6EE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13028 pso_num=26 pso_serial#=139 2025-05-31 22:01:41.215897 :9E9BC6EF:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13028 osp_idx=24 osp_ver=364789 osp_pg=0 (spawn #239158) 2025-05-31 22:01:41.215897 :9E9BC6F0: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-31 22:01:41.215898 :9E9BC6F1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=157 time=1704659860 2025-05-31 22:01:41.215898 :9E9BC6F2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 22:01:41.215899 :9E9BC6F3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 22:01:41.215899 :9E9BC6F4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 22:01:41.215899 :9E9BC6F5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 22:01:41.215900 :9E9BC6F6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 22:01:41.216400 :9E9BC6F7: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-31 22:02:11.294724 :9E9BD0CE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-31 22:02:41.347117 :9E9BDA9F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 22:02:41.335 2025-05-31 22:02:41.347119 :9E9BDAA0:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13572 pso_num=26 pso_serial#=140 2025-05-31 22:02:41.347251 :9E9BDAA1:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13572 osp_idx=24 osp_ver=364790 osp_pg=0 (spawn #239160) 2025-05-31 22:02:41.347252 :9E9BDAA2: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-31 22:02:41.347252 :9E9BDAA3:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=158 time=1704719985 2025-05-31 22:02:41.347253 :9E9BDAA4:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 22:02:41.347253 :9E9BDAA5:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 22:02:41.347253 :9E9BDAA6:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 22:02:41.347253 :9E9BDAA7:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 22:02:41.347254 :9E9BDAA8:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 22:02:41.347727 :9E9BDAA9: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-31 22:03:11.426185 :9E9BE4CC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-31 22:03:41.413554 :9E9BEE35:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 22:03:41.402 2025-05-31 22:03:41.413556 :9E9BEE36:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10624 pso_num=26 pso_serial#=141 2025-05-31 22:03:41.413673 :9E9BEE37:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10624 osp_idx=24 osp_ver=364791 osp_pg=0 (spawn #239163) 2025-05-31 22:03:41.413674 :9E9BEE38: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-31 22:03:41.413674 :9E9BEE39:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=159 time=1704780047 2025-05-31 22:03:41.413675 :9E9BEE3A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 22:03:41.413675 :9E9BEE3B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 22:03:41.413675 :9E9BEE3C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 22:03:41.413676 :9E9BEE3D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 22:03:41.413676 :9E9BEE3E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 22:03:41.414210 :9E9BEE3F: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-31 22:04:11.494688 :9E9BF821:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-31 22:04:41.497321 :9E9C01E0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 22:04:41.486 2025-05-31 22:04:41.497323 :9E9C01E1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14724 pso_num=26 pso_serial#=142 2025-05-31 22:04:41.497472 :9E9C01E2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14724 osp_idx=24 osp_ver=364792 osp_pg=0 (spawn #239165) 2025-05-31 22:04:41.497473 :9E9C01E3: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-31 22:04:41.497473 :9E9C01E4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=160 time=1704840141 2025-05-31 22:04:41.497474 :9E9C01E5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 22:04:41.497474 :9E9C01E6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 22:04:41.497475 :9E9C01E7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 22:04:41.497475 :9E9C01E8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 22:04:41.497475 :9E9C01E9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 22:04:41.497959 :9E9C01EA: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-31 22:05:01.700352 :9E9C0AE6: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-31 22:05:01.799905 :9E9C0E32: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-31 22:05:01.799905*:9E9C0E42:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D1C718 estart=1748700301 eid=16781480 ctx=0x000000077FD6A000 cwh=0x0000000024D10068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-31 22:05:01.799905*:9E9C0E43:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D1C718 curCtx=0x0000000024D18938 pubTabIdxCnt=0 2025-05-31 22:05:01.799905*:9E9C0E44:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D1C718 2025-05-31 22:05:01.799905*:9E9C0E45:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D1C718 2025-05-31 22:05:01.799905*:9E9C0E46:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D1C718 curCtx=0x0000000024D18938 xsc->flg4=65792 flags=2 2025-05-31 22:05:01.799905*:9E9C0E47:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D1C718 2025-05-31 22:05:01.805088 :9E9C0E52: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-31 22:05:01.805126 :9E9C0E55:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-05-31 22:05:01.809003 :9E9C0E65: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-31 22:05:01.914930 :9E9C0E6D: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-31 22:05:02.043663 :9E9C0E75: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-31 22:05:02.175783 :9E9C0EAD: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-31 22:05:03.287129 :9E9C0ECC: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-31 22:05:03.290478 :9E9C0ED4: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-31 22:05:03.291498 :9E9C0EDC: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-31 22:05:03.293447 :9E9C0EE4: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-31 22:05:03.295026 :9E9C0EEC: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-31 22:05:03.296645 :9E9C0EF4: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-31 22:05:03.300063 :9E9C0EFC: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-31 22:05:03.315963 :9E9C0F17: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-31 22:05:03.316630 :9E9C0F1F: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-31 22:05:03.317169 :9E9C0F27: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-31 22:05:03.317768 :9E9C0F2F: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-31 22:05:03.320470 :9E9C0F37: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-31 22:05:03.321373 :9E9C0F3F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-31 22:05:03.322243 :9E9C0F40:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-31 22:05:03.323021 :9E9C0F41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-31 22:05:03.323457 :9E9C0F42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-31 22:05:03.323464 :9E9C0F43:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-31 22:05:03.324087 :9E9C0F44:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-31 22:05:03.324587 :9E9C0F45:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-31 22:05:03.324901 :9E9C0F46:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-31 22:05:03.326036 :9E9C0F47:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-31 22:05:03.330317*:9E9C0F52:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299B2018 estart=1748700302 eid=16777954 ctx=0x000000077B458BE8 cwh=0x0000000024D6BFD0 cpu=0 buffg=33 interb=90112 r=11 rb=90112 w=0 wb=0 2025-05-31 22:05:03.330317*:9E9C0F53:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299B2018 curCtx=0x0000000029A7FF28 pubTabIdxCnt=0 2025-05-31 22:05:03.330317*:9E9C0F54:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299B2018 2025-05-31 22:05:03.330317*:9E9C0F55:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299B2018 2025-05-31 22:05:03.345942*:9E9C0F56:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299B2018 curCtx=0x0000000029A7FF28 xsc->flg4=65792 flags=2 2025-05-31 22:05:03.345942*:9E9C0F57:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299B2018 2025-05-31 22:05:03.360007 :9E9C0F58: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-31 22:05:03.360061 :9E9C0F5B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-31 22:05:03.361011 :9E9C0F61: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-31 22:05:03.373038 :9E9C0F69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-31 22:05:03.373556 :9E9C0F6A: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-31 22:05:03.393689 :9E9C0F72: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-31 22:05:03.393742 :9E9C0F75:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-31 22:05:03.394633 :9E9C0F7B: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-31 22:05:03.395527 :9E9C0F83:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-31 22:05:03.396090 :9E9C0F84: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-31 22:05:03.415864 :9E9C0F8C: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-31 22:05:03.415920 :9E9C0F8F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-31 22:05:03.416814 :9E9C0F95: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-31 22:05:03.417689 :9E9C0F9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-31 22:05:03.418158 :9E9C0F9E: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-31 22:05:03.439239 :9E9C0FA6: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-31 22:05:03.439292 :9E9C0FA9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-31 22:05:03.440234 :9E9C0FAF: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-31 22:05:03.441152 :9E9C0FB7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-31 22:05:03.441604 :9E9C0FB8: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-31 22:05:03.461520 :9E9C0FCA: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-31 22:05:03.461590 :9E9C0FCD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-31 22:05:03.462531 :9E9C0FD3: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-31 22:05:03.463037 :9E9C0FDB: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-31 22:05:03.463409 :9E9C0FE3: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-31 22:05:03.463589 :9E9C0FEB: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-31 22:05:03.463727 :9E9C0FF3: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-31 22:05:03.463871 :9E9C0FFB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-05-31 22:05:03.467914 :9E9C0FFC: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-31 22:05:03.468004 :9E9C0FFF: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-31 22:05:03.468099 :9E9C100C: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: 14724, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de