Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250916220008\orcl_j003_25216_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250916220008\orcl_j003_25216_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:35952M/63366M, Ph+PgF:40243M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 25216, image: ORACLE.EXE (J003) *** 2025-09-16 22:00:08.973 *** SESSION ID:(233.35581) 2025-09-16 22:00:08.973 *** 2025-09-16 22:00:08.973 Process diagnostic dump for ORACLE.EXE (J003), OS id=25216, pid: 29, proc_ser: 118, sid: 233, sess_ser: 35581 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 25216 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_13093, hash value=2356249172 Current Wait Stack: Not in wait; last wait ended 0.915543 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.915567 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=148 seq_num=149 snap_id=1 wait times: snap=0.000247 sec, exc=0.000247 sec, total=0.000247 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'log file sequential read' log#=0x2a85, block#=0x0, blocks=0x0 wait_id=147 seq_num=148 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.055814 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2a85, block#=0x0, blocks=0x0 wait_id=146 seq_num=147 snap_id=1 wait times: snap=0.000019 sec, exc=0.000019 sec, total=0.000019 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.420981 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x5000, blocks=0x800 wait_id=145 seq_num=146 snap_id=1 wait times: snap=0.003627 sec, exc=0.003627 sec, total=0.003627 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000014 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x4800, blocks=0x800 wait_id=144 seq_num=145 snap_id=1 wait times: snap=0.003348 sec, exc=0.003348 sec, total=0.003348 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000009 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x4000, blocks=0x800 wait_id=143 seq_num=144 snap_id=1 wait times: snap=0.000644 sec, exc=0.000644 sec, total=0.000644 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000156 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0x3800, blocks=0x800 wait_id=142 seq_num=143 snap_id=1 wait times: snap=0.002865 sec, exc=0.002865 sec, total=0.002865 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.045560 sec of elapsed time 7: waited for 'log file sequential read' log#=0x2a85, block#=0x1000, blocks=0x800 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000160 sec, exc=0.000160 sec, total=0.000160 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002220 sec of elapsed time 8: waited for 'log file sequential read' log#=0x2a85, block#=0x800, blocks=0x800 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000718 sec, exc=0.000718 sec, total=0.000718 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002413 sec of elapsed time 9: waited for 'log file sequential read' log#=0x2a85, block#=0x0, blocks=0x0 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000164 sec of elapsed time Sampled Session History of session 233 serial 35581 --------------------------------------------------- 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] not in wait at each sample [1 sample, 22:00:01] 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-09-16 22:00:08.973 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 25216, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-16 04:01:52.206808 :CE0800FB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-16 05:01:59.850523 :CE0CC010:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-16 05:01:59.846 2025-09-16 05:01:59.850525 :CE0CC011:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22584 pso_num=29 pso_serial#=110 2025-09-16 05:01:59.850713 :CE0CC012:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22584 osp_idx=27 osp_ver=17704 osp_pg=0 (spawn #596831) 2025-09-16 05:01:59.850714 :CE0CC013:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 05:01:59.850715 :CE0CC014:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=89 time=2384571143 2025-09-16 05:01:59.850716 :CE0CC015:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 05:01:59.850716 :CE0CC016:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 05:01:59.850717 :CE0CC017:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 05:01:59.850717 :CE0CC018:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 05:01:59.850718 :CE0CC019:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 05:01:59.851482 :CE0CC01D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 05:01:59.851537 :CE0CC023:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 05:01:59.860716 :CE0CC038:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-16 06:02:07.692305 :CE117F9A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-16 06:02:07.687 2025-09-16 06:02:07.692306 :CE117F9B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22732 pso_num=29 pso_serial#=111 2025-09-16 06:02:07.692466 :CE117F9C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22732 osp_idx=27 osp_ver=17705 osp_pg=0 (spawn #596970) 2025-09-16 06:02:07.692468 :CE117F9D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 06:02:07.692468 :CE117F9E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=90 time=2388178987 2025-09-16 06:02:07.692469 :CE117F9F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 06:02:07.692470 :CE117FA0:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 06:02:07.692470 :CE117FA1:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 06:02:07.692470 :CE117FA2:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 06:02:07.692471 :CE117FA3:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 06:02:07.693031 :CE117FA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 06:02:07.693078 :CE117FAA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 06:02:07.702376 :CE117FC2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-16 07:02:15.892325 :CE163F9F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-16 07:02:15.887 2025-09-16 07:02:15.892326 :CE163FA0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22576 pso_num=29 pso_serial#=112 2025-09-16 07:02:15.892498 :CE163FA1:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22576 osp_idx=27 osp_ver=17706 osp_pg=0 (spawn #597108) 2025-09-16 07:02:15.892499 :CE163FA2:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 07:02:15.892500 :CE163FA3:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=91 time=2391787174 2025-09-16 07:02:15.892501 :CE163FA4:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 07:02:15.892501 :CE163FA5:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 07:02:15.892502 :CE163FA6:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 07:02:15.892502 :CE163FA7:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 07:02:15.892503 :CE163FA8:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 07:02:15.893062 :CE163FA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 07:02:15.893118 :CE163FAF:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 07:02:15.902695 :CE163FC7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-16 08:02:23.836370 :CE1AFF2B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-16 08:02:23.833 2025-09-16 08:02:23.836371 :CE1AFF2C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21948 pso_num=29 pso_serial#=113 2025-09-16 08:02:23.836505 :CE1AFF2D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21948 osp_idx=27 osp_ver=17707 osp_pg=0 (spawn #597247) 2025-09-16 08:02:23.836506 :CE1AFF2E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:02:23.836506 :CE1AFF2F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=92 time=2395395127 2025-09-16 08:02:23.836507 :CE1AFF30:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:02:23.836507 :CE1AFF31:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:02:23.836507 :CE1AFF32:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:02:23.836508 :CE1AFF33:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:02:23.836508 :CE1AFF34:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:02:23.837045 :CE1AFF35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 08:02:23.837090 :CE1AFF3B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 08:02:23.846310 :CE1AFF64:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-16 09:02:31.406406 :CE202508:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-16 09:02:31.403 2025-09-16 09:02:31.406407 :CE202509:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24380 pso_num=29 pso_serial#=114 2025-09-16 09:02:31.406635 :CE20250A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=24380 osp_idx=27 osp_ver=17708 osp_pg=0 (spawn #597391) 2025-09-16 09:02:31.406636 :CE20250B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 09:02:31.406637 :CE20250C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=93 time=2399002690 2025-09-16 09:02:31.406638 :CE20250D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 09:02:31.406638 :CE20250E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 09:02:31.406639 :CE20250F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 09:02:31.406639 :CE202510:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 09:02:31.406640 :CE202511:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 09:02:31.407199 :CE202512:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 09:02:31.407246 :CE202518:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 09:02:31.416349 :CE202540:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-16 10:02:38.653652 :CE24E4EB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-16 10:02:38.649 2025-09-16 10:02:38.653653 :CE24E4EC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23724 pso_num=29 pso_serial#=115 2025-09-16 10:02:38.653791 :CE24E4ED:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23724 osp_idx=27 osp_ver=17709 osp_pg=0 (spawn #597530) 2025-09-16 10:02:38.653791 :CE24E4EE:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 10:02:38.653792 :CE24E4EF:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=94 time=2402609940 2025-09-16 10:02:38.653792 :CE24E4F0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 10:02:38.653793 :CE24E4F1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 10:02:38.653793 :CE24E4F2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 10:02:38.653793 :CE24E4F3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 10:02:38.653794 :CE24E4F4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 10:02:38.654483 :CE24E4F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 10:02:38.654534 :CE24E4FB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 10:02:38.663615 :CE24E513:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-16 11:02:46.461766 :CE299FD7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-16 11:02:46.457 2025-09-16 11:02:46.461767 :CE299FD8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22256 pso_num=29 pso_serial#=116 2025-09-16 11:02:46.461921 :CE299FD9:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22256 osp_idx=27 osp_ver=17710 osp_pg=0 (spawn #597668) 2025-09-16 11:02:46.461922 :CE299FDA:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 11:02:46.461922 :CE299FDB:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=95 time=2406217752 2025-09-16 11:02:46.461923 :CE299FDC:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 11:02:46.461923 :CE299FDD:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 11:02:46.461923 :CE299FDE:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 11:02:46.461924 :CE299FDF:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 11:02:46.461924 :CE299FE0:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 11:02:46.462481 :CE299FE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 11:02:46.462524 :CE299FE7:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 11:02:46.471692 :CE299FFF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-16 16:00:27.246236 :CE411B29:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-16 16:00:27.244 2025-09-16 16:00:27.246239 :CE411B2A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23100 pso_num=29 pso_serial#=117 2025-09-16 16:00:27.246372 :CE411B2B:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23100 osp_idx=27 osp_ver=17711 osp_pg=0 (spawn #598352) 2025-09-16 16:00:27.246372 :CE411B2C:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 16:00:27.246373 :CE411B2D:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=96 time=2424078533 2025-09-16 16:00:27.246373 :CE411B2E:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 16:00:27.246373 :CE411B2F:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 16:00:27.246374 :CE411B30:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 16:00:27.246374 :CE411B31:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 16:00:27.246374 :CE411B32:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 16:00:27.246878 :CE411B33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.246920 :CE411B39:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 16:00:27.254441 :CE411B47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.255547 :CE411B4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.256035 :CE411B57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.256479 :CE411B5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.256988 :CE411B67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.259468 :CE411B6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.260557 :CE411B77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.312526 :CE411B7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.313414 :CE411B87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.313654 :CE411B8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.313874 :CE411B97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 16:00:27.315698 :CE411B9F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-16 22:00:02.089964 :CE5D7EEF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-16 22:00:02.080 2025-09-16 22:00:02.089965 :CE5D7EF0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=25216 pso_num=29 pso_serial#=118 2025-09-16 22:00:02.090121 :CE5D7EF9:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=25216 osp_idx=27 osp_ver=17712 osp_pg=0 (spawn #599186) 2025-09-16 22:00:02.090121 :CE5D7EFA:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 22:00:02.090122 :CE5D7EFB:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=97 time=2445653393 2025-09-16 22:00:02.090122 :CE5D7EFC:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 22:00:02.090123 :CE5D7EFD:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 22:00:02.090123 :CE5D7EFE:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 22:00:02.090123 :CE5D7EFF:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 22:00:02.090124 :CE5D7F00:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 22:00:02.090835 :CE5D7F01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.096318 :CE5D7F1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.096346 :CE5D7F21:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-09-16 22:00:02.114389 :CE5D7FA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.114738 :CE5D7FB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.114906 :CE5D7FB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.115070 :CE5D7FC1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.115229 :CE5D7FC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.115386 :CE5D7FD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.115560 :CE5D7FD9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.115724 :CE5D7FE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.115902 :CE5D7FE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.116052 :CE5D7FF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.116200 :CE5D7FF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.116367 :CE5D8001:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.116515 :CE5D8009:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.116664 :CE5D8011:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.116883 :CE5D8019:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.117057 :CE5D8021:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.117208 :CE5D8029:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.117370 :CE5D8031:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.117737 :CE5D8039:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.117912 :CE5D8041:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.118131 :CE5D8049:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.118432 :CE5D8051:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.118689 :CE5D8059:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.118946 :CE5D8061:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.119601 :CE5D8069:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.146782 :CE5D807E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.439795*:CE5D8479:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC83AD0 estart=1758031202 eid=22467609 ctx=0x0000000777968158 cwh=0x000000002B460AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-09-16 22:00:03.439795*:CE5D847A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC83AD0 curCtx=0x000000006042C4B8 pubTabIdxCnt=0 2025-09-16 22:00:03.439795*:CE5D847B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC83AD0 2025-09-16 22:00:03.439795*:CE5D847C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC83AD0 2025-09-16 22:00:03.439795*:CE5D847D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DCBAAF8 estart=1758031201 eid=16777592 ctx=0x000000077B54EAC8 cwh=0x000000002B460608 cpu=1062500 buffg=58558 interb=196608 r=24 rb=196608 w=0 wb=0 2025-09-16 22:00:03.439795*:CE5D847E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DCBAAF8 curCtx=0x000000005EE41240 pubTabIdxCnt=0 2025-09-16 22:00:03.439795*:CE5D847F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DCBAAF8 2025-09-16 22:00:03.439795*:CE5D8480:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DCBAAF8 2025-09-16 22:00:03.439795*:CE5D8485:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC83AD0 curCtx=0x000000006042C4B8 xsc->flg4=65792 flags=1 2025-09-16 22:00:03.439795*:CE5D8486:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC83AD0 2025-09-16 22:00:03.877309*:CE5D84F4:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DCBAAF8 curCtx=0x000000005EE41240 xsc->flg4=65792 flags=2 2025-09-16 22:00:03.877309*:CE5D84F5:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DCBAAF8 2025-09-16 22:00:03.878774 :CE5D84F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.898889 :CE5D8518:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.900819 :CE5D8520:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-09-16 22:00:03.901031 :CE5D8521:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-09-16 22:00:03.901967 :CE5D8522:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.902051 :CE5D8525:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.902909 :CE5D8537:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.904441 :CE5D853F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.904737 :CE5D8547:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.033559*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-09-16 22:00:04.033559*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-16 22:00:04.033559*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-16 22:00:04.033559*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-16 22:00:04.033559*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-16 22:00:04.033559*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-16 22:00:04.033559*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-16 22:00:04.108680 :CE5D8655:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:05.996063 :CE5D886E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:05.996079 :CE5D886F:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.00d.0001df55 2025-09-16 22:00:08.517716 :CE5D8A4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:08.519247 :CE5D8A53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:08.530034 :CE5D8A5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:08.560093 :CE5D8A63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 25216, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de