Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250725220008\orcl_j001_3448_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250725220008\orcl_j001_3448_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:36018M/63366M, Ph+PgF:41780M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 3448, image: ORACLE.EXE (J001) *** 2025-07-25 22:00:08.302 *** SESSION ID:(201.9361) 2025-07-25 22:00:08.302 *** 2025-07-25 22:00:08.302 Process diagnostic dump for ORACLE.EXE (J001), OS id=3448, pid: 25, proc_ser: 54, sid: 201, sess_ser: 9361 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 3448 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.273921 sec, exc=0.273921 sec, total=0.273921 sec wait times: max=0.500000 sec, heur=6.255703 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.000006 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.510113 sec, exc=0.510113 sec, total=0.510113 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000012 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000015 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.504282 sec, exc=0.504282 sec, total=0.504282 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.360206 sec, exc=0.360206 sec, total=0.360206 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000008 sec of elapsed time Sampled Session History of session 201 serial 9361 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 22:00:02 - 22:00:08] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-25 22:00:08.302 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 3448, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-25 21:47:46.173096 :B6F1727E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:47:46.173096 :B6F1727F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:47:46.173581 :B6F17280: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-07-25 21:48:11.172744 :B6F17B67: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-07-25 21:48:41.248292 :B6F18533:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:48:46.213245 :B6F186EA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:48:46.202 2025-07-25 21:48:46.213247 :B6F186EB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8860 pso_num=25 pso_serial#=43 2025-07-25 21:48:46.213386 :B6F186EC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8860 osp_idx=23 osp_ver=211805 osp_pg=0 (spawn #422456) 2025-07-25 21:48:46.213386 :B6F186ED:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:48:46.213386 :B6F186EE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=196 time=2160902095 2025-07-25 21:48:46.213387 :B6F186EF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:48:46.213387 :B6F186F0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:48:46.213388 :B6F186F1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:48:46.213388 :B6F186F2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:48:46.213388 :B6F186F3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:48:46.213883 :B6F186F4: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-07-25 21:49:16.304895 :B6F190EC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:49:46.278229 :B6F19A9C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:49:46.267 2025-07-25 21:49:46.278231 :B6F19A9D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16236 pso_num=25 pso_serial#=44 2025-07-25 21:49:46.278362 :B6F19A9E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16236 osp_idx=23 osp_ver=211806 osp_pg=0 (spawn #422458) 2025-07-25 21:49:46.278363 :B6F19A9F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:49:46.278363 :B6F19AA0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=197 time=2160962173 2025-07-25 21:49:46.278364 :B6F19AA1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:49:46.278364 :B6F19AA2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:49:46.278364 :B6F19AA3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:49:46.278365 :B6F19AA4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:49:46.278365 :B6F19AA5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:49:46.278841 :B6F19AA6: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-07-25 21:50:16.355776 :B6F1A44C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:50:46.354047 :B6F1AE89:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:50:46.343 2025-07-25 21:50:46.354049 :B6F1AE8A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12464 pso_num=25 pso_serial#=45 2025-07-25 21:50:46.354179 :B6F1AE8B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12464 osp_idx=23 osp_ver=211807 osp_pg=0 (spawn #422460) 2025-07-25 21:50:46.354180 :B6F1AE8C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:50:46.354180 :B6F1AE8D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=198 time=2161022236 2025-07-25 21:50:46.354181 :B6F1AE8E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:50:46.354181 :B6F1AE8F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:50:46.354181 :B6F1AE90:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:50:46.354182 :B6F1AE91:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:50:46.354182 :B6F1AE92:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:50:46.354658 :B6F1AE93: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-07-25 21:51:16.429453 :B6F1B886:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:51:46.430749 :B6F1C259:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:51:46.419 2025-07-25 21:51:46.430751 :B6F1C25A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18548 pso_num=25 pso_serial#=46 2025-07-25 21:51:46.430901 :B6F1C25B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18548 osp_idx=23 osp_ver=211808 osp_pg=0 (spawn #422462) 2025-07-25 21:51:46.430902 :B6F1C25C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:51:46.430902 :B6F1C25D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=199 time=2161082314 2025-07-25 21:51:46.430903 :B6F1C25E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:51:46.430903 :B6F1C25F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:51:46.430904 :B6F1C260:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:51:46.430904 :B6F1C261:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:51:46.430905 :B6F1C262:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:51:46.431379 :B6F1C263: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-07-25 21:52:16.493692 :B6F1CC4B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:52:46.474018 :B6F1D638:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:52:46.463 2025-07-25 21:52:46.474020 :B6F1D639:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13808 pso_num=25 pso_serial#=47 2025-07-25 21:52:46.474167 :B6F1D63A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13808 osp_idx=23 osp_ver=211809 osp_pg=0 (spawn #422466) 2025-07-25 21:52:46.474168 :B6F1D63B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:52:46.474169 :B6F1D63C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=200 time=2161142361 2025-07-25 21:52:46.474169 :B6F1D63D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:52:46.474170 :B6F1D63E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:52:46.474170 :B6F1D63F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:52:46.474171 :B6F1D640:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:52:46.474171 :B6F1D641:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:52:46.474695 :B6F1D642: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-07-25 21:53:16.575805 :B6F1E13A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:53:46.567676 :B6F1EB2E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:53:46.556 2025-07-25 21:53:46.567678 :B6F1EB2F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17868 pso_num=25 pso_serial#=48 2025-07-25 21:53:46.567812 :B6F1EB30:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17868 osp_idx=23 osp_ver=211810 osp_pg=0 (spawn #422468) 2025-07-25 21:53:46.567813 :B6F1EB31:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:53:46.567813 :B6F1EB32:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=201 time=2161202454 2025-07-25 21:53:46.567814 :B6F1EB33:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:53:46.567814 :B6F1EB34:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:53:46.567814 :B6F1EB35:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:53:46.567815 :B6F1EB36:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:53:46.567815 :B6F1EB37:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:53:46.568318 :B6F1EB38: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-07-25 21:54:16.658694 :B6F1F525:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:54:46.701949 :B6F1FF21:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:54:46.702 2025-07-25 21:54:46.701952 :B6F1FF22:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16256 pso_num=25 pso_serial#=49 2025-07-25 21:54:46.702083 :B6F1FF23:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16256 osp_idx=23 osp_ver=211811 osp_pg=0 (spawn #422470) 2025-07-25 21:54:46.702083 :B6F1FF24:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:54:46.702084 :B6F1FF25:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=202 time=2161262579 2025-07-25 21:54:46.702084 :B6F1FF26:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:54:46.702084 :B6F1FF27:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:54:46.702085 :B6F1FF28:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:54:46.702085 :B6F1FF29:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:54:46.702085 :B6F1FF2A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:54:46.702583 :B6F1FF2B: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-07-25 21:55:16.804336 :B6F208E5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:55:46.821113 :B6F21298:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:55:46.810 2025-07-25 21:55:46.821115 :B6F21299:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12108 pso_num=25 pso_serial#=50 2025-07-25 21:55:46.821259 :B6F2129A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12108 osp_idx=23 osp_ver=211812 osp_pg=0 (spawn #422472) 2025-07-25 21:55:46.821260 :B6F2129B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:55:46.821260 :B6F2129C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=203 time=2161322704 2025-07-25 21:55:46.821260 :B6F2129D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:55:46.821261 :B6F2129E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:55:46.821261 :B6F2129F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:55:46.821261 :B6F212A0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:55:46.821262 :B6F212A1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:55:46.821757 :B6F212A2: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-07-25 21:56:16.853055 :B6F21CD3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:56:46.874157 :B6F226BF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:56:46.863 2025-07-25 21:56:46.874160 :B6F226C0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18960 pso_num=25 pso_serial#=51 2025-07-25 21:56:46.874286 :B6F226C1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18960 osp_idx=23 osp_ver=211813 osp_pg=0 (spawn #422474) 2025-07-25 21:56:46.874287 :B6F226C2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:56:46.874287 :B6F226C3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=204 time=2161382767 2025-07-25 21:56:46.874288 :B6F226C4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:56:46.874288 :B6F226C5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:56:46.874289 :B6F226C6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:56:46.874290 :B6F226C7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:56:46.874290 :B6F226C8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:56:46.874791 :B6F226C9: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-07-25 21:57:16.970336 :B6F2308A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:57:46.942455 :B6F23A9E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:57:46.930 2025-07-25 21:57:46.942457 :B6F23A9F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17416 pso_num=25 pso_serial#=52 2025-07-25 21:57:46.942577 :B6F23AA0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17416 osp_idx=23 osp_ver=211814 osp_pg=0 (spawn #422476) 2025-07-25 21:57:46.942577 :B6F23AA1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:57:46.942578 :B6F23AA2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=205 time=2161442829 2025-07-25 21:57:46.942578 :B6F23AA3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:57:46.942579 :B6F23AA4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:57:46.942580 :B6F23AA5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:57:46.942580 :B6F23AA6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:57:46.942580 :B6F23AA7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:57:46.943143 :B6F23AA8: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-07-25 21:58:11.963423 :B6F242F3: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-07-25 21:58:42.036116 :B6F24CB9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:58:47.016938 :B6F24E91:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:58:47.006 2025-07-25 21:58:47.016939 :B6F24E92:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17716 pso_num=25 pso_serial#=53 2025-07-25 21:58:47.017066 :B6F24E93:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17716 osp_idx=23 osp_ver=211815 osp_pg=0 (spawn #422478) 2025-07-25 21:58:47.017067 :B6F24E94:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:58:47.017067 :B6F24E95:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=206 time=2161502908 2025-07-25 21:58:47.017068 :B6F24E96:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:58:47.017068 :B6F24E97:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:58:47.017068 :B6F24E98:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:58:47.017069 :B6F24E99:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:58:47.017069 :B6F24E9A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:58:47.017561 :B6F24E9B: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-07-25 21:59:17.091309 :B6F25892:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-25 21:59:47.099120 :B6F262F5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-25 21:59:47.088 2025-07-25 21:59:47.099122 :B6F262F6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3448 pso_num=25 pso_serial#=54 2025-07-25 21:59:47.099271 :B6F262F7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3448 osp_idx=23 osp_ver=211816 osp_pg=0 (spawn #422481) 2025-07-25 21:59:47.099272 :B6F262F8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 21:59:47.099273 :B6F262F9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=207 time=2161562986 2025-07-25 21:59:47.099274 :B6F262FA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 21:59:47.099274 :B6F262FB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 21:59:47.099274 :B6F262FC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 21:59:47.099274 :B6F262FD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 21:59:47.099275 :B6F262FE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 21:59:47.099754 :B6F262FF: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-07-25 22:00:02.027401 :B6F26944: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-07-25 22:00:02.027401*:B6F26953:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEB898 estart=1753452001 eid=16784851 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-25 22:00:02.027401*:B6F26954:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 pubTabIdxCnt=0 2025-07-25 22:00:02.027401*:B6F26955:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEB898 2025-07-25 22:00:02.027401*:B6F26956:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEB898 2025-07-25 22:00:02.027401*:B6F26957:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 xsc->flg4=65792 flags=2 2025-07-25 22:00:02.027401*:B6F26958:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEB898 2025-07-25 22:00:02.033001 :B6F26967: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-07-25 22:00:02.033065 :B6F2696A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-07-25 22:00:02.038259 :B6F26992:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-25 22:00:02.038854 :B6F269A6:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 7859120000000000 2025-07-25 22:00:02.040684 :B6F269BC: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-07-25 22:00:02.040815 :B6F269C9: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-07-25 22:00:02.040825 :B6F269D0:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-07-25 22:00:02.040830 :B6F269D1: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-07-25 22:00:02.040859 :B6F269DD: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-07-25 22:00:02.041548 :B6F269E9:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 232456274) 2025-07-25 22:00:02.041553 :B6F269EA: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-07-25 22:00:02.041577 :B6F269F5: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-07-25 22:00:02.041585 :B6F26A01:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65561 1) 2025-07-25 22:00:02.041587 :B6F26A02: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-07-25 22:00:02.041614 :B6F26A0A: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-07-25 22:00:02.041618 :B6F26A11:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65561) 2025-07-25 22:00:02.041620 :B6F26A19: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-07-25 22:00:02.041669 :B6F26A49: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-07-25 22:00:02.042077 :B6F26AB9: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-07-25 22:00:02.042094 :B6F26AD0:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-25 22:00:02.042947 :B6F26AD7:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 7959120000000000 2025-07-25 22:00:02.043337 :B6F26AD8:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 232456274) 2025-07-25 22:00:02.043344 :B6F26AD9: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-07-25 22:00:02.043373 :B6F26AE4: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-07-25 22:00:02.051978 :B6F26B56:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-07-25 22:00:02.053683 :B6F26B57: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-07-25 22:00:02.053742 :B6F26B5A: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-07-25 22:00:02.053867 :B6F26B68: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-07-25 22:00:02.152516 :B6F26CAE: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-07-25 22:00:04.012007 :B6F270E6: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 3448, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de