Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706060009\orcl_j001_14192_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706060009\orcl_j001_14192_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:37124M/63366M, Ph+PgF:43097M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14192, image: ORACLE.EXE (J001) *** 2025-07-06 06:00:09.346 *** SESSION ID:(201.15621) 2025-07-06 06:00:09.346 *** 2025-07-06 06:00:09.346 Process diagnostic dump for ORACLE.EXE (J001), OS id=14192, pid: 25, proc_ser: 235, sid: 201, sess_ser: 15621 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14192 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.210443 sec, exc=0.210443 sec, total=0.210443 sec wait times: max=0.500000 sec, heur=7.198792 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=13 seq_num=14 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.501094 sec, exc=0.501094 sec, total=0.501094 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=11 seq_num=12 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: 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.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500175 sec, exc=0.500175 sec, total=0.500175 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500130 sec, exc=0.500130 sec, total=0.500130 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 201 serial 15621 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 06:00:02 - 06:00:09] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-06 06:00:09.346 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14192, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-06 05:47:41.299639 :AE2D8BD6: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-06 05:47:41.299639 :AE2D8BD7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=164 time=461637189 2025-07-06 05:47:41.299640 :AE2D8BD8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:47:41.299640 :AE2D8BD9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:47:41.299641 :AE2D8BDA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:47:41.299641 :AE2D8BDB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:47:41.299641 :AE2D8BDC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:47:41.300152 :AE2D8BDD: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-06 05:48:11.395075 :AE2D9620:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:48:41.390616 :AE2DA022:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:48:41.379 2025-07-06 05:48:41.390618 :AE2DA023:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4688 pso_num=25 pso_serial#=224 2025-07-06 05:48:41.390741 :AE2DA024:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4688 osp_idx=23 osp_ver=184701 osp_pg=0 (spawn #356916) 2025-07-06 05:48:41.390741 :AE2DA025: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-06 05:48:41.390742 :AE2DA026:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=165 time=461697283 2025-07-06 05:48:41.390743 :AE2DA027:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:48:41.390743 :AE2DA028:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:48:41.390743 :AE2DA029:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:48:41.390744 :AE2DA02A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:48:41.390744 :AE2DA02B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:48:41.391219 :AE2DA02C: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-06 05:49:11.452234 :AE2DAA14:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:49:41.507113 :AE2DB430:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:49:41.494 2025-07-06 05:49:41.507116 :AE2DB431:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12464 pso_num=25 pso_serial#=225 2025-07-06 05:49:41.507276 :AE2DB432:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12464 osp_idx=23 osp_ver=184702 osp_pg=0 (spawn #356918) 2025-07-06 05:49:41.507277 :AE2DB433: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-06 05:49:41.507278 :AE2DB434:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=166 time=461757392 2025-07-06 05:49:41.507278 :AE2DB435:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:49:41.507278 :AE2DB436:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:49:41.507278 :AE2DB437:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:49:41.507279 :AE2DB438:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:49:41.507279 :AE2DB439:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:49:41.507846 :AE2DB43A: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-06 05:50:11.569692 :AE2DBEAD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:50:41.611823 :AE2DC8A6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:50:41.601 2025-07-06 05:50:41.611825 :AE2DC8A7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17560 pso_num=25 pso_serial#=226 2025-07-06 05:50:41.611951 :AE2DC8A8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17560 osp_idx=23 osp_ver=184703 osp_pg=0 (spawn #356922) 2025-07-06 05:50:41.611951 :AE2DC8A9: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-06 05:50:41.611952 :AE2DC8AA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=167 time=461817501 2025-07-06 05:50:41.611952 :AE2DC8AB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:50:41.611952 :AE2DC8AC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:50:41.611953 :AE2DC8AD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:50:41.611953 :AE2DC8AE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:50:41.611953 :AE2DC8AF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:50:41.612453 :AE2DC8B0: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-06 05:51:11.689500 :AE2DD29D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:51:41.681312 :AE2DDCAE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:51:41.670 2025-07-06 05:51:41.681314 :AE2DDCAF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17996 pso_num=25 pso_serial#=227 2025-07-06 05:51:41.681452 :AE2DDCB0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17996 osp_idx=23 osp_ver=184704 osp_pg=0 (spawn #356924) 2025-07-06 05:51:41.681453 :AE2DDCB1: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-06 05:51:41.681454 :AE2DDCB2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=168 time=461877564 2025-07-06 05:51:41.681456 :AE2DDCB3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:51:41.681456 :AE2DDCB4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:51:41.681457 :AE2DDCB5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:51:41.681457 :AE2DDCB6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:51:41.681457 :AE2DDCB7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:51:41.681959 :AE2DDCB8: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-06 05:52:11.773548 :AE2DE6B0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:52:41.796070 :AE2DF0B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:52:41.785 2025-07-06 05:52:41.796072 :AE2DF0B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7880 pso_num=25 pso_serial#=228 2025-07-06 05:52:41.796207 :AE2DF0B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7880 osp_idx=23 osp_ver=184705 osp_pg=0 (spawn #356926) 2025-07-06 05:52:41.796208 :AE2DF0B9: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-06 05:52:41.796208 :AE2DF0BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=169 time=461937689 2025-07-06 05:52:41.796208 :AE2DF0BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:52:41.796209 :AE2DF0BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:52:41.796209 :AE2DF0BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:52:41.796210 :AE2DF0BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:52:41.796210 :AE2DF0BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:52:41.796784 :AE2DF0C0: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-06 05:53:11.872992 :AE2DFA8C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:53:41.870262 :AE2E046E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:53:41.859 2025-07-06 05:53:41.870264 :AE2E046F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16756 pso_num=25 pso_serial#=229 2025-07-06 05:53:41.870380 :AE2E0470:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16756 osp_idx=23 osp_ver=184706 osp_pg=0 (spawn #356928) 2025-07-06 05:53:41.870381 :AE2E0471: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-06 05:53:41.870381 :AE2E0472:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=170 time=461997751 2025-07-06 05:53:41.870381 :AE2E0473:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:53:41.870382 :AE2E0474:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:53:41.870382 :AE2E0475:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:53:41.870382 :AE2E0476:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:53:41.870383 :AE2E0477:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:53:41.870858 :AE2E0478: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-06 05:54:06.918857 :AE2E0CD1: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-06 05:54:37.005561 :AE2E1789:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:54:41.974586 :AE2E1977:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:54:41.963 2025-07-06 05:54:41.974588 :AE2E1978:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4904 pso_num=25 pso_serial#=230 2025-07-06 05:54:41.974704 :AE2E1979:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4904 osp_idx=23 osp_ver=184707 osp_pg=0 (spawn #356930) 2025-07-06 05:54:41.974705 :AE2E197A: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-06 05:54:41.974705 :AE2E197B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=171 time=462057861 2025-07-06 05:54:41.974705 :AE2E197C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:54:41.974706 :AE2E197D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:54:41.974706 :AE2E197E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:54:41.974706 :AE2E197F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:54:41.974706 :AE2E1980:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:54:41.975189 :AE2E1981: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-06 05:55:12.063365 :AE2E245D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:55:42.053502 :AE2E2E63:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:55:42.042 2025-07-06 05:55:42.053504 :AE2E2E64:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11308 pso_num=25 pso_serial#=231 2025-07-06 05:55:42.053653 :AE2E2E65:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11308 osp_idx=23 osp_ver=184708 osp_pg=0 (spawn #356932) 2025-07-06 05:55:42.053654 :AE2E2E66: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-06 05:55:42.053654 :AE2E2E67:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=172 time=462117939 2025-07-06 05:55:42.053654 :AE2E2E68:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:55:42.053655 :AE2E2E69:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:55:42.053655 :AE2E2E6A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:55:42.053655 :AE2E2E6B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:55:42.053656 :AE2E2E6C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:55:42.054154 :AE2E2E6D: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-06 05:56:12.129556 :AE2E3828:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:56:42.139511 :AE2E4233:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:56:42.128 2025-07-06 05:56:42.139514 :AE2E4234:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17628 pso_num=25 pso_serial#=232 2025-07-06 05:56:42.139629 :AE2E4235:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17628 osp_idx=23 osp_ver=184709 osp_pg=0 (spawn #356934) 2025-07-06 05:56:42.139630 :AE2E4236: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-06 05:56:42.139630 :AE2E4237:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=173 time=462178033 2025-07-06 05:56:42.139631 :AE2E4238:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:56:42.139631 :AE2E4239:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:56:42.139631 :AE2E423A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:56:42.139632 :AE2E423B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:56:42.139632 :AE2E423C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:56:42.140138 :AE2E423D: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-06 05:57:12.245019 :AE2E4C37:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:57:42.251875 :AE2E5665:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:57:42.241 2025-07-06 05:57:42.251877 :AE2E5666:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12004 pso_num=25 pso_serial#=233 2025-07-06 05:57:42.252012 :AE2E5667:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12004 osp_idx=23 osp_ver=184710 osp_pg=0 (spawn #356937) 2025-07-06 05:57:42.252012 :AE2E5668: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-06 05:57:42.252013 :AE2E5669:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=174 time=462238142 2025-07-06 05:57:42.252013 :AE2E566A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:57:42.252014 :AE2E566B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:57:42.252014 :AE2E566C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:57:42.252014 :AE2E566D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:57:42.252015 :AE2E566E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:57:42.252493 :AE2E566F: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-06 05:58:12.369304 :AE2E6099:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:58:42.358703 :AE2E6A7B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:58:42.347 2025-07-06 05:58:42.358705 :AE2E6A7C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15216 pso_num=25 pso_serial#=234 2025-07-06 05:58:42.358844 :AE2E6A7D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15216 osp_idx=23 osp_ver=184711 osp_pg=0 (spawn #356939) 2025-07-06 05:58:42.358845 :AE2E6A7E: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-06 05:58:42.358845 :AE2E6A7F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=175 time=462298251 2025-07-06 05:58:42.358846 :AE2E6A80:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:58:42.358846 :AE2E6A81:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:58:42.358847 :AE2E6A82:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:58:42.358847 :AE2E6A83:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:58:42.358847 :AE2E6A84:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:58:42.359322 :AE2E6A85: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-06 05:59:12.414198 :AE2E747F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-06 05:59:42.409797 :AE2E7EA6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 05:59:42.398 2025-07-06 05:59:42.409799 :AE2E7EA7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14192 pso_num=25 pso_serial#=235 2025-07-06 05:59:42.409920 :AE2E7EA8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14192 osp_idx=23 osp_ver=184712 osp_pg=0 (spawn #356941) 2025-07-06 05:59:42.409921 :AE2E7EA9: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-06 05:59:42.409921 :AE2E7EAA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=176 time=462358298 2025-07-06 05:59:42.409922 :AE2E7EAB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 05:59:42.409922 :AE2E7EAC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 05:59:42.409922 :AE2E7EAD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 05:59:42.409923 :AE2E7EAE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 05:59:42.409923 :AE2E7EAF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 05:59:42.410417 :AE2E7EB0: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-06 06:00:02.032156 :AE2E8776: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-06 06:00:02.032156*:AE2E8785:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEB898 estart=1751752801 eid=16783651 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-06 06:00:02.032156*:AE2E8786:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 pubTabIdxCnt=0 2025-07-06 06:00:02.032156*:AE2E8787:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEB898 2025-07-06 06:00:02.032156*:AE2E8788:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEB898 2025-07-06 06:00:02.032156*:AE2E8789:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 xsc->flg4=65792 flags=2 2025-07-06 06:00:02.032156*:AE2E878A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEB898 2025-07-06 06:00:02.037850 :AE2E8799: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-06 06:00:02.037894 :AE2E87A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-07-06 06:00:02.094408 :AE2E8950:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-06 06:00:02.094970 :AE2E8951:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 5732120000000000 2025-07-06 06:00:02.104371 :AE2E8952: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-06 06:00:02.104438 :AE2E895F: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-06 06:00:02.104447 :AE2E8966: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-06 06:00:02.104451 :AE2E8967: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-06 06:00:02.104477 :AE2E8973: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-06 06:00:02.105394 :AE2E897E:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 230512297) 2025-07-06 06:00:02.105399 :AE2E897F: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-06 06:00:02.105424 :AE2E898C: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-06 06:00:02.105434 :AE2E8997: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-06 06:00:02.105436 :AE2E8998: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-06 06:00:02.105463 :AE2E89A5: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-06 06:00:02.105466 :AE2E89B0: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-06 06:00:02.105469 :AE2E89B1: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-06 06:00:02.105520 :AE2E89D2: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-06 06:00:02.106298 :AE2E8AA6: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-06 06:00:02.106325 :AE2E8AA9:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-06 06:00:02.110490 :AE2E8AAE:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 5832120000000000 2025-07-06 06:00:02.110895 :AE2E8AAF:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 230512297) 2025-07-06 06:00:02.110902 :AE2E8AB0: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-06 06:00:02.110939 :AE2E8ABC: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-06 06:00:02.151358 :AE2E8AC8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-07-06 06:00:02.153170 :AE2E8AC9: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-06 06:00:02.153225 :AE2E8ACC: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-06 06:00:02.153331 :AE2E8ADC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 14192, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de