Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250425220009\orcl_j003_10720_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250425220009\orcl_j003_10720_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:37583M/63366M, Ph+PgF:45262M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 10720, image: ORACLE.EXE (J003) *** 2025-04-25 22:00:09.346 *** SESSION ID:(233.19287) 2025-04-25 22:00:09.346 *** 2025-04-25 22:00:09.346 Process diagnostic dump for ORACLE.EXE (J003), OS id=10720, pid: 29, proc_ser: 192, sid: 233, sess_ser: 19287 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10720 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12325, hash value=2932483388 Current Wait Stack: Not in wait; last wait ended 1.525839 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.525864 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=143 seq_num=144 snap_id=1 wait times: snap=0.000201 sec, exc=0.000201 sec, total=0.000201 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000002 sec of elapsed time 1: waited for 'log file sequential read' log#=0x273d, block#=0x0, blocks=0x0 wait_id=142 seq_num=143 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.001088 sec of elapsed time 2: waited for 'log file sequential read' log#=0x273d, block#=0x0, blocks=0x0 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000016 sec, exc=0.000016 sec, total=0.000016 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.574892 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0xf800, blocks=0x800 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000291 sec, exc=0.000291 sec, total=0.000291 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000011 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0xf000, blocks=0x800 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000339 sec, exc=0.000339 sec, total=0.000339 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.066173 sec of elapsed time 5: waited for 'log file sequential read' log#=0x273d, block#=0x800, blocks=0x800 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000206 sec, exc=0.000206 sec, total=0.000206 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.001003 sec of elapsed time 6: waited for 'log file sequential read' log#=0x273d, block#=0x0, blocks=0x0 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000132 sec of elapsed time 7: waited for 'log file sequential read' log#=0x273d, block#=0x2, blocks=0x7fe wait_id=136 seq_num=137 snap_id=1 wait times: snap=0.000210 sec, exc=0.000210 sec, total=0.000210 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000169 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 9: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x0, filetype=0x3 wait_id=134 seq_num=135 snap_id=1 wait times: snap=0.000214 sec, exc=0.000214 sec, total=0.000214 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000129 sec of elapsed time Sampled Session History of session 233 serial 19287 --------------------------------------------------- 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, 22:00:02 - 22:00:09] 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-04-25 22:00:09.346 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 10720, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-25 13:03:09.833170 :8E914F77:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=7 time=2856991609 2025-04-25 13:03:09.833171 :8E914F78:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 13:03:09.833172 :8E914F79:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 13:03:09.833172 :8E914F7A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 13:03:09.833173 :8E914F7B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 13:03:09.833174 :8E914F7C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 13:03:09.833707 :8E914F7D: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-04-25 13:03:09.833768 :8E914F83: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-04-25 13:03:09.842926 :8E914F9B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-04-25 14:03:17.323409 :8E96198B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 04-25 14:03:17.319 2025-04-25 14:03:17.323410 :8E96198C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11044 pso_num=29 pso_serial#=184 2025-04-25 14:03:17.323549 :8E96198D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=11044 osp_idx=27 osp_ver=9618 osp_pg=0 (spawn #117078) 2025-04-25 14:03:17.323550 :8E96198E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 14:03:17.323550 :8E96198F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=8 time=2860599109 2025-04-25 14:03:17.323551 :8E961990:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 14:03:17.323551 :8E961991:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 14:03:17.323551 :8E961992:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 14:03:17.323551 :8E961993:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 14:03:17.323552 :8E961994:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 14:03:17.324114 :8E961995: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-04-25 14:03:17.324164 :8E96199B: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-04-25 14:03:17.333191 :8E9619B3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-04-25 15:03:25.476250 :8E9AE234:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 04-25 15:03:25.472 2025-04-25 15:03:25.476251 :8E9AE235:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7712 pso_num=29 pso_serial#=185 2025-04-25 15:03:25.476386 :8E9AE236:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=7712 osp_idx=27 osp_ver=9619 osp_pg=0 (spawn #117219) 2025-04-25 15:03:25.476387 :8E9AE237:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 15:03:25.476388 :8E9AE238:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=9 time=2864207250 2025-04-25 15:03:25.476388 :8E9AE239:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 15:03:25.476388 :8E9AE23A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 15:03:25.476389 :8E9AE23B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 15:03:25.476389 :8E9AE23C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 15:03:25.476390 :8E9AE23D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 15:03:25.476896 :8E9AE23E: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-04-25 15:03:25.476942 :8E9AE244: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-04-25 15:03:25.485986 :8E9AE25C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-04-25 16:03:33.009257 :8E9FABFD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 04-25 16:03:33.006 2025-04-25 16:03:33.009258 :8E9FABFE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9764 pso_num=29 pso_serial#=186 2025-04-25 16:03:33.009391 :8E9FABFF:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=9764 osp_idx=27 osp_ver=9620 osp_pg=0 (spawn #117360) 2025-04-25 16:03:33.009392 :8E9FAC00:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 16:03:33.009393 :8E9FAC01:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=10 time=2867814796 2025-04-25 16:03:33.009393 :8E9FAC02:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 16:03:33.009393 :8E9FAC03:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 16:03:33.009394 :8E9FAC04:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 16:03:33.009394 :8E9FAC05:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=1 secs 2025-04-25 16:03:33.009394 :8E9FAC06:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 16:03:33.009922 :8E9FAC07: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-04-25 16:03:33.009966 :8E9FAC0D: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-04-25 16:03:33.019111 :8E9FAC25:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-04-25 17:03:40.172720 :8EA4773B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 04-25 17:03:40.169 2025-04-25 17:03:40.172722 :8EA4773C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7588 pso_num=29 pso_serial#=187 2025-04-25 17:03:40.172874 :8EA4773D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=7588 osp_idx=27 osp_ver=9621 osp_pg=0 (spawn #117501) 2025-04-25 17:03:40.172875 :8EA4773E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 17:03:40.172876 :8EA4773F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=11 time=2871421953 2025-04-25 17:03:40.172876 :8EA47740:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 17:03:40.172877 :8EA47741:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 17:03:40.172877 :8EA47742:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 17:03:40.172877 :8EA47743:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 17:03:40.172878 :8EA47744:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 17:03:40.173518 :8EA47745: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-04-25 17:03:40.173562 :8EA4774B: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-04-25 17:03:40.182642 :8EA47763:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-04-25 18:03:47.576168 :8EA93C98:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 04-25 18:03:47.572 2025-04-25 18:03:47.576169 :8EA93C99:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4352 pso_num=29 pso_serial#=188 2025-04-25 18:03:47.576314 :8EA93C9A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=4352 osp_idx=27 osp_ver=9622 osp_pg=0 (spawn #117642) 2025-04-25 18:03:47.576314 :8EA93C9B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 18:03:47.576315 :8EA93C9C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=12 time=2875029359 2025-04-25 18:03:47.576315 :8EA93C9D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 18:03:47.576316 :8EA93C9E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 18:03:47.576316 :8EA93C9F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 18:03:47.576316 :8EA93CA0:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 18:03:47.576317 :8EA93CA1:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 18:03:47.576875 :8EA93CA2: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-04-25 18:03:47.576939 :8EA93CA8: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-04-25 18:03:47.586087 :8EA93CBF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-04-25 19:03:55.518097 :8EAE02E2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 04-25 19:03:55.515 2025-04-25 19:03:55.518098 :8EAE02E3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8696 pso_num=29 pso_serial#=189 2025-04-25 19:03:55.518258 :8EAE02E4:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=8696 osp_idx=27 osp_ver=9623 osp_pg=0 (spawn #117783) 2025-04-25 19:03:55.518258 :8EAE02E5:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 19:03:55.518259 :8EAE02E6:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=13 time=2878637296 2025-04-25 19:03:55.518259 :8EAE02E7:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 19:03:55.518260 :8EAE02E8:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 19:03:55.518260 :8EAE02E9:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 19:03:55.518261 :8EAE02EA:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 19:03:55.518261 :8EAE02EB:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 19:03:55.518826 :8EAE02EC: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-04-25 19:03:55.518879 :8EAE02F2: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-04-25 19:03:55.527840 :8EAE030A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-04-25 20:04:02.990251 :8EB2C4A6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 04-25 20:04:02.986 2025-04-25 20:04:02.990252 :8EB2C4A7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7716 pso_num=29 pso_serial#=190 2025-04-25 20:04:02.990385 :8EB2C4A8:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=7716 osp_idx=27 osp_ver=9624 osp_pg=0 (spawn #117923) 2025-04-25 20:04:02.990386 :8EB2C4A9:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 20:04:02.990386 :8EB2C4AA:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=14 time=2882244765 2025-04-25 20:04:02.990386 :8EB2C4AB:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 20:04:02.990387 :8EB2C4AC:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 20:04:02.990387 :8EB2C4AD:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 20:04:02.990387 :8EB2C4AE:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 20:04:02.990388 :8EB2C4AF:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 20:04:02.990912 :8EB2C4B0: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-04-25 20:04:02.990967 :8EB2C4B6: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-04-25 20:04:03.000117 :8EB2C4CE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-04-25 21:04:10.400998 :8EB77E08:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 04-25 21:04:10.397 2025-04-25 21:04:10.401000 :8EB77E09:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10456 pso_num=29 pso_serial#=191 2025-04-25 21:04:10.401142 :8EB77E0A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=10456 osp_idx=27 osp_ver=9625 osp_pg=0 (spawn #118064) 2025-04-25 21:04:10.401142 :8EB77E0B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:04:10.401143 :8EB77E0C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=15 time=2885852187 2025-04-25 21:04:10.401143 :8EB77E0D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:04:10.401143 :8EB77E0E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:04:10.401144 :8EB77E0F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:04:10.401145 :8EB77E10:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:04:10.401145 :8EB77E11:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:04:10.401714 :8EB77E12: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-04-25 21:04:10.401765 :8EB77E18: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-04-25 21:04:10.410728 :8EB77E2F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-04-25 22:00:02.046308 :8EBBE3A7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 04-25 22:00:02.035 2025-04-25 22:00:02.046309 :8EBBE3A8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10720 pso_num=29 pso_serial#=192 2025-04-25 22:00:02.046431 :8EBBE3A9:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=10720 osp_idx=27 osp_ver=9626 osp_pg=0 (spawn #118195) 2025-04-25 22:00:02.046432 :8EBBE3AA:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 22:00:02.046432 :8EBBE3AB:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=16 time=2889203828 2025-04-25 22:00:02.046433 :8EBBE3AC:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 22:00:02.046433 :8EBBE3AD:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 22:00:02.046434 :8EBBE3AE:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 22:00:02.046434 :8EBBE3AF:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 22:00:02.046435 :8EBBE3B0:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 22:00:02.046957 :8EBBE3BA: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-04-25 22:00:02.051956 :8EBBE3D3: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-04-25 22:00:02.051992 :8EBBE3D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-04-25 22:00:02.069109 :8EBBE5BE: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-04-25 22:00:02.069353 :8EBBE5C6: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-04-25 22:00:02.069513 :8EBBE5CE: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-04-25 22:00:02.069677 :8EBBE5D6: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-04-25 22:00:02.069837 :8EBBE5DE: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-04-25 22:00:02.069993 :8EBBE5E6: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-04-25 22:00:02.070154 :8EBBE5EE: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-04-25 22:00:02.070320 :8EBBE5F6: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-04-25 22:00:02.070473 :8EBBE5FE: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-04-25 22:00:02.070626 :8EBBE606: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-04-25 22:00:02.070777 :8EBBE60E: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-04-25 22:00:02.070941 :8EBBE616: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-04-25 22:00:02.071091 :8EBBE61E: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-04-25 22:00:02.071242 :8EBBE626: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-04-25 22:00:02.071429 :8EBBE62E: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-04-25 22:00:02.071685 :8EBBE636: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-04-25 22:00:02.071839 :8EBBE63E: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-04-25 22:00:02.072003 :8EBBE646: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-04-25 22:00:02.072168 :8EBBE64E: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-04-25 22:00:02.072328 :8EBBE656: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-04-25 22:00:02.072498 :8EBBE65E: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-04-25 22:00:02.072663 :8EBBE666: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-04-25 22:00:02.072819 :8EBBE66E: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-04-25 22:00:02.072975 :8EBBE676: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-04-25 22:00:02.073586 :8EBBE67E: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-04-25 22:00:02.102185 :8EBBE686: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-04-25 22:00:03.175427*:8EBBE857:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC13AD0 estart=1745589602 eid=17751270 ctx=0x0000000777968158 cwh=0x0000000036770158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-04-25 22:00:03.175427*:8EBBE858:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC13AD0 curCtx=0x0000000036671BF8 pubTabIdxCnt=0 2025-04-25 22:00:03.175427*:8EBBE859:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC13AD0 2025-04-25 22:00:03.175427*:8EBBE85A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC13AD0 2025-04-25 22:00:03.175427*:8EBBE85B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC8B6F0 estart=1745589602 eid=16777280 ctx=0x000000077B54EAC8 cwh=0x000000002DC80068 cpu=921875 buffg=50924 interb=204800 r=25 rb=204800 w=0 wb=0 2025-04-25 22:00:03.175427*:8EBBE85C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC8B6F0 curCtx=0x0000000034667FD8 pubTabIdxCnt=0 2025-04-25 22:00:03.175427*:8EBBE85D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC8B6F0 2025-04-25 22:00:03.175427*:8EBBE85E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC8B6F0 2025-04-25 22:00:03.175427*:8EBBE85F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC13AD0 curCtx=0x0000000036671BF8 xsc->flg4=65792 flags=1 2025-04-25 22:00:03.175427*:8EBBE860:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC13AD0 2025-04-25 22:00:03.747734 :8EBBE92F:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 9, ext 1, nex 3 2025-04-25 22:00:03.747746 :8EBBE930:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008191c2 where: kdiwh22: kdifind 2025-04-25 22:00:03.748029*:8EBBE931:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC8B6F0 curCtx=0x0000000034667FD8 xsc->flg4=65792 flags=2 2025-04-25 22:00:03.748029*:8EBBE932:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC8B6F0 2025-04-25 22:00:03.749233 :8EBBE933: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-04-25 22:00:03.771627 :8EBBE94B: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-04-25 22:00:03.774033 :8EBBE953:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-04-25 22:00:03.774283 :8EBBE954:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-04-25 22:00:03.775425 :8EBBE958: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-04-25 22:00:03.776538 :8EBBE96A: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-04-25 22:00:03.778446 :8EBBE97A: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-04-25 22:00:03.778794 :8EBBE982: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-04-25 22:00:03.841777*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-04-25 22:00:03.841777*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-04-25 22:00:03.841777*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-04-25 22:00:03.841777*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-04-25 22:00:03.841777*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-04-25 22:00:03.841777*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-04-25 22:00:03.841777*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-04-25 22:00:03.921135 :8EBBEA46: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-04-25 22:00:05.803910 :8EBBEBF4: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-04-25 22:00:05.803925 :8EBBEBF5:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0007.002.0000c078 2025-04-25 22:00:08.273222 :8EBBEDFA: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-04-25 22:00:08.274543 :8EBBEE02: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-04-25 22:00:08.284684 :8EBBEE0A: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-04-25 22:00:08.306294 :8EBBEE12: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: 10720, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de