Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250514220008\orcl_j001_9660_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250514220008\orcl_j001_9660_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:37412M/63366M, Ph+PgF:44697M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 9660, image: ORACLE.EXE (J001) *** 2025-05-14 22:00:08.668 *** SESSION ID:(193.37571) 2025-05-14 22:00:08.668 *** 2025-05-14 22:00:08.668 Process diagnostic dump for ORACLE.EXE (J001), OS id=9660, pid: 24, proc_ser: 132, sid: 193, sess_ser: 37571 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9660 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.170514 sec, exc=0.170514 sec, total=0.170514 sec wait times: max=0.500000 sec, heur=4.657653 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000007 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.501089 sec, exc=0.501089 sec, total=0.501089 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=5 seq_num=6 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.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.485981 sec, exc=0.485981 sec, total=0.485981 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 37571 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:00:04 - 22:00:08] 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-05-14 22:00:08.668 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 9660, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-14 21:48:44.177015 :971F2C32:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:48:44.177015 :971F2C33:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:48:44.177016 :971F2C34:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:48:44.177016 :971F2C35:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:48:44.177016 :971F2C36:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:48:44.177499 :971F2C37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:49:14.274970 :971F35DA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:49:39.274127 :971F3E41:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:49:39.262 2025-05-14 21:49:39.274128 :971F3E42:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9532 pso_num=24 pso_serial#=122 2025-05-14 21:49:39.274262 :971F3E43:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9532 osp_idx=22 osp_ver=271186 osp_pg=0 (spawn #182465) 2025-05-14 21:49:39.274263 :971F3E44:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:49:39.274263 :971F3E45:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=30 time=235189141 2025-05-14 21:49:39.274263 :971F3E46:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:49:39.274264 :971F3E47:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:49:39.274264 :971F3E48:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:49:39.274264 :971F3E49:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:49:39.274265 :971F3E4A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:49:39.274736 :971F3E4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:49:44.279385 :971F3FC1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-14 21:50:14.344800 :971F4A0A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:50:44.379164 :971F53C4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:50:44.368 2025-05-14 21:50:44.379166 :971F53C5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13236 pso_num=24 pso_serial#=123 2025-05-14 21:50:44.379337 :971F53C6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13236 osp_idx=22 osp_ver=271187 osp_pg=0 (spawn #182467) 2025-05-14 21:50:44.379337 :971F53C7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:50:44.379338 :971F53C8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=31 time=235254235 2025-05-14 21:50:44.379338 :971F53C9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:50:44.379338 :971F53CA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:50:44.379339 :971F53CB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:50:44.379339 :971F53CC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:50:44.379340 :971F53CD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:50:44.379835 :971F53CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:51:14.457045 :971F5D9A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:51:44.467711 :971F6765:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:51:44.456 2025-05-14 21:51:44.467713 :971F6766:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11432 pso_num=24 pso_serial#=124 2025-05-14 21:51:44.467885 :971F6767:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11432 osp_idx=22 osp_ver=271188 osp_pg=0 (spawn #182469) 2025-05-14 21:51:44.467886 :971F6768:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:51:44.467886 :971F6769:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=32 time=235314329 2025-05-14 21:51:44.467887 :971F676A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:51:44.467887 :971F676B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:51:44.467887 :971F676C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:51:44.467888 :971F676D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:51:44.467888 :971F676E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:51:44.468404 :971F676F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:52:14.551437 :971F70DB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:52:44.541667 :971F7AA8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:52:44.530 2025-05-14 21:52:44.541668 :971F7AA9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12660 pso_num=24 pso_serial#=125 2025-05-14 21:52:44.541810 :971F7AAA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12660 osp_idx=22 osp_ver=271189 osp_pg=0 (spawn #182471) 2025-05-14 21:52:44.541811 :971F7AAB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:52:44.541811 :971F7AAC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=33 time=235374407 2025-05-14 21:52:44.541812 :971F7AAD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:52:44.541812 :971F7AAE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:52:44.541812 :971F7AAF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:52:44.541813 :971F7AB0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:52:44.541813 :971F7AB1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:52:44.542308 :971F7AB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:53:14.640527 :971F84A1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:53:44.675705 :971F8E6C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:53:44.665 2025-05-14 21:53:44.675707 :971F8E6D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7888 pso_num=24 pso_serial#=126 2025-05-14 21:53:44.675833 :971F8E6E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7888 osp_idx=22 osp_ver=271190 osp_pg=0 (spawn #182473) 2025-05-14 21:53:44.675834 :971F8E6F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:53:44.675835 :971F8E70:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=34 time=235434547 2025-05-14 21:53:44.675835 :971F8E71:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:53:44.675835 :971F8E72:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:53:44.675836 :971F8E73:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:53:44.675836 :971F8E74:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:53:44.675836 :971F8E75:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:53:44.676391 :971F8E76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:54:14.762926 :971F988F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:54:44.774883 :971FA227:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:54:44.764 2025-05-14 21:54:44.774885 :971FA228:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1112 pso_num=24 pso_serial#=127 2025-05-14 21:54:44.775026 :971FA229:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=1112 osp_idx=22 osp_ver=271191 osp_pg=0 (spawn #182476) 2025-05-14 21:54:44.775027 :971FA22A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:54:44.775028 :971FA22B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=35 time=235494641 2025-05-14 21:54:44.775028 :971FA22C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:54:44.775029 :971FA22D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:54:44.775029 :971FA22E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:54:44.775029 :971FA22F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:54:44.775030 :971FA230:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:54:44.775522 :971FA231:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:55:14.871687 :971FACD5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:55:44.900626 :971FB681:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:55:44.889 2025-05-14 21:55:44.900628 :971FB682:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13284 pso_num=24 pso_serial#=128 2025-05-14 21:55:44.900760 :971FB683:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13284 osp_idx=22 osp_ver=271192 osp_pg=0 (spawn #182478) 2025-05-14 21:55:44.900761 :971FB684:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:55:44.900761 :971FB685:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=36 time=235554766 2025-05-14 21:55:44.900762 :971FB686:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:55:44.900762 :971FB687:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:55:44.900763 :971FB688:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:55:44.900763 :971FB689:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:55:44.900763 :971FB68A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:55:44.901251 :971FB68B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:56:14.972783 :971FC01A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:56:44.969377 :971FC9B4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:56:44.958 2025-05-14 21:56:44.969379 :971FC9B5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11000 pso_num=24 pso_serial#=129 2025-05-14 21:56:44.969510 :971FC9B6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11000 osp_idx=22 osp_ver=271193 osp_pg=0 (spawn #182480) 2025-05-14 21:56:44.969511 :971FC9B7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:56:44.969511 :971FC9B8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=37 time=235614829 2025-05-14 21:56:44.969512 :971FC9B9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:56:44.969512 :971FC9BA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:56:44.969512 :971FC9BB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:56:44.969512 :971FC9BC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:56:44.969513 :971FC9BD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:56:44.970001 :971FC9BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:57:15.061677 :971FD378:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:57:45.045063 :971FDD3B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:57:45.033 2025-05-14 21:57:45.045065 :971FDD3C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1156 pso_num=24 pso_serial#=130 2025-05-14 21:57:45.045184 :971FDD3D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=1156 osp_idx=22 osp_ver=271194 osp_pg=0 (spawn #182482) 2025-05-14 21:57:45.045185 :971FDD3E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:57:45.045186 :971FDD3F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=38 time=235674907 2025-05-14 21:57:45.045186 :971FDD40:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:57:45.045186 :971FDD41:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:57:45.045187 :971FDD42:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:57:45.045187 :971FDD43:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:57:45.045188 :971FDD44:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:57:45.045680 :971FDD45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:58:15.136150 :971FE725:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:58:45.130337 :971FF0F5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:58:45.119 2025-05-14 21:58:45.130339 :971FF0F6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12936 pso_num=24 pso_serial#=131 2025-05-14 21:58:45.130452 :971FF0F7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12936 osp_idx=22 osp_ver=271195 osp_pg=0 (spawn #182484) 2025-05-14 21:58:45.130453 :971FF0F8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:58:45.130453 :971FF0F9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=39 time=235735000 2025-05-14 21:58:45.130454 :971FF0FA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:58:45.130454 :971FF0FB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:58:45.130454 :971FF0FC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:58:45.130455 :971FF0FD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:58:45.130455 :971FF0FE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:58:45.130960 :971FF0FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:59:15.183582 :971FFAF6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-14 21:59:40.191054 :97200331:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-14 21:59:40.180 2025-05-14 21:59:40.191056 :97200332:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9660 pso_num=24 pso_serial#=132 2025-05-14 21:59:40.191188 :97200333:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9660 osp_idx=22 osp_ver=271196 osp_pg=0 (spawn #182487) 2025-05-14 21:59:40.191188 :97200334:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 21:59:40.191189 :97200335:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=40 time=235790047 2025-05-14 21:59:40.191189 :97200336:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 21:59:40.191190 :97200337:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 21:59:40.191190 :97200338:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 21:59:40.191191 :97200339:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 21:59:40.191191 :9720033A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 21:59:40.191675 :9720033B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-14 21:59:45.196785 :972004F7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-14 22:00:00.198650 :97200BFF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-14 22:00:00.198650*:97200C0E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495C718 estart=1747231199 eid=16780430 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-14 22:00:00.198650*:97200C11:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495C718 curCtx=0x0000000024958938 pubTabIdxCnt=0 2025-05-14 22:00:00.198650*:97200C13:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495C718 2025-05-14 22:00:00.198650*:97200C15:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495C718 2025-05-14 22:00:00.203316 :97200C17:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=22 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-14 22:00:00.203343 :97200C1A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=22 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-14 22:00:00.203343*:97200C1D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495C718 curCtx=0x0000000024958938 xsc->flg4=65792 flags=2 2025-05-14 22:00:00.203343*:97200C1E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495C718 2025-05-14 22:00:00.204098 :97200C34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.204126 :97200C3D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-14 22:00:00.215303 :97200C95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.220252 :97200DEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.220726 :97200DF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.221047 :97200DFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.226256 :97200E1E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=30 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-14 22:00:00.226923 :97200E25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.227164 :97200E34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.228030 :97200E3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.228244 :97200E45:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-05-14 22:00:00.228946 :97200E49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.229866 :97200E59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.229900 :97200E5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.229978 :97200E69:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-14 22:00:00.323472 :97200F4F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-14 22:00:00.325724 :97200F53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.325775 :97200F56:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-14 22:00:00.328317 :97200F5C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-05-14 22:00:00.331723 :97200F5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.331805 :97200F62:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-14 22:00:00.331945 :97200F6F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-14 22:00:04.011199 :972014B0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-14 22:00:04.011199*:972014B4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495C718 estart=1747231203 eid=16780435 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=3 interb=0 r=0 rb=0 w=0 wb=0 2025-05-14 22:00:04.011199*:972014B5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495C718 curCtx=0x0000000024958938 pubTabIdxCnt=0 2025-05-14 22:00:04.011199*:972014B6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495C718 2025-05-14 22:00:04.011199*:972014B7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495C718 2025-05-14 22:00:04.011199*:972014B8:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495C718 curCtx=0x0000000024958938 xsc->flg4=65792 flags=2 2025-05-14 22:00:04.011199*:972014B9:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495C718 2025-05-14 22:00:04.013427 :972014BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:04.013463 :972014BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-14 22:00:04.020318 :972014C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:04.021195 :972014CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:04.022014 :972014D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:04.022227 :972014DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-05-14 22:00:04.023724 :972014DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-14 22:00:04.023765 :972014DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-14 22:00:04.023865 :972014EC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 9660, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de