Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250612222434\orcl_m002_15128_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250612222434\orcl_m002_15128_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:36737M/63366M, Ph+PgF:43242M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 15128, image: ORACLE.EXE (M002) *** 2025-06-12 22:24:34.515 *** SESSION ID:(233.25303) 2025-06-12 22:24:34.515 *** 2025-06-12 22:24:34.515 Process diagnostic dump for ORACLE.EXE (M002), OS id=15128, pid: 29, proc_ser: 122, sid: 233, sess_ser: 25303 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: Not in wait; last wait ended 0.000049 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000074 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x1d4a, blocks=0x1 wait_id=1277 seq_num=1278 snap_id=1 wait times: snap=0.000083 sec, exc=0.000083 sec, total=0.000083 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000321 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x1b8a, blocks=0x1 wait_id=1276 seq_num=1277 snap_id=1 wait times: snap=0.002959 sec, exc=0.002959 sec, total=0.002959 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000142 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x7b2, blocks=0x1 wait_id=1275 seq_num=1276 snap_id=1 wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000134 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x1d3a, blocks=0x1 wait_id=1274 seq_num=1275 snap_id=1 wait times: snap=0.000053 sec, exc=0.000053 sec, total=0.000053 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000145 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x1a2a, blocks=0x1 wait_id=1273 seq_num=1274 snap_id=1 wait times: snap=0.000065 sec, exc=0.000065 sec, total=0.000065 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000293 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1c5a, blocks=0x1 wait_id=1272 seq_num=1273 snap_id=1 wait times: snap=0.001876 sec, exc=0.001876 sec, total=0.001876 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000282 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1baa, blocks=0x1 wait_id=1271 seq_num=1272 snap_id=1 wait times: snap=0.002531 sec, exc=0.002531 sec, total=0.002531 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000139 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x1d2a, blocks=0x1 wait_id=1270 seq_num=1271 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.000152 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x1b2a, blocks=0x1 wait_id=1269 seq_num=1270 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000165 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x7ca, blocks=0x1 wait_id=1268 seq_num=1269 snap_id=1 wait times: snap=0.000087 sec, exc=0.000087 sec, total=0.000087 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000338 sec of elapsed time Sampled Session History of session 233 serial 25303 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:24:34] idle wait at each sample [session created at: 22:24:34] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-12 22:24:34.515 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 15128, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-12 01:00:23.416864 :A37BC7A3: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-06-12 01:00:23.417030 :A37BC7A8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-12 01:00:23.417870 :A37BC7AD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 01:00:23.436924 :A37BC7B0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 01:00:23.428 2025-06-12 01:00:23.436926 :A37BC7B1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15036 pso_num=29 pso_serial#=111 2025-06-12 01:00:23.437064 :A37BC7B2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15036 (legacy spawn) 2025-06-12 01:00:23.443595 :A37BC7B3: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-06-12 01:00:23.443761 :A37BC7BA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-12 01:00:23.444614 :A37BC7BD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 18:00:06.484321 :A3CA740D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 18:00:06.482 2025-06-12 18:00:06.484323 :A3CA740E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17280 pso_num=29 pso_serial#=112 2025-06-12 18:00:06.484466 :A3CA740F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17280 osp_idx=27 osp_ver=12606 osp_pg=0 (spawn #278583) 2025-06-12 18:00:06.484467 :A3CA7410:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 18:00:06.484468 :A3CA7411:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=170 time=2726949657 2025-06-12 18:00:06.484468 :A3CA7412:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 18:00:06.484468 :A3CA7413:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 18:00:06.484469 :A3CA7414:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 18:00:06.484469 :A3CA7415:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 18:00:06.484469 :A3CA7416:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 18:00:06.484962 :A3CA7417: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-06-12 18:00:06.485005 :A3CA741D: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-06-12 18:00:06.493093 :A3CA743F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 19:00:13.555288 :A3CF0EC3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 19:00:13.552 2025-06-12 19:00:13.555289 :A3CF0EC4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15660 pso_num=29 pso_serial#=113 2025-06-12 19:00:13.555426 :A3CF0EC5:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15660 osp_idx=27 osp_ver=12607 osp_pg=0 (spawn #278722) 2025-06-12 19:00:13.555427 :A3CF0EC6:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 19:00:13.555427 :A3CF0EC7:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=171 time=2730556735 2025-06-12 19:00:13.555428 :A3CF0EC8:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 19:00:13.555428 :A3CF0EC9:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 19:00:13.555429 :A3CF0ECA:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 19:00:13.555429 :A3CF0ECB:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 19:00:13.555429 :A3CF0ECC:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 19:00:13.555942 :A3CF0ECD: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-06-12 19:00:13.555986 :A3CF0ED3: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-06-12 19:00:13.564704 :A3CF0EF5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 20:00:21.075417 :A3D3A72A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 20:00:21.073 2025-06-12 20:00:21.075418 :A3D3A72B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15524 pso_num=29 pso_serial#=114 2025-06-12 20:00:21.075555 :A3D3A72C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15524 osp_idx=27 osp_ver=12608 osp_pg=0 (spawn #278859) 2025-06-12 20:00:21.075556 :A3D3A72D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 20:00:21.075556 :A3D3A72E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=172 time=2734164250 2025-06-12 20:00:21.075557 :A3D3A72F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 20:00:21.075557 :A3D3A730:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 20:00:21.075558 :A3D3A731:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 20:00:21.075558 :A3D3A732:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 20:00:21.075558 :A3D3A733:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 20:00:21.076048 :A3D3A734: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-06-12 20:00:21.076094 :A3D3A73A: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-06-12 20:00:21.084345 :A3D3A74C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 22:00:02.051608 :A3DCCCCD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 22:00:02.044 2025-06-12 22:00:02.051609 :A3DCCCCE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13828 pso_num=29 pso_serial#=115 2025-06-12 22:00:02.051751 :A3DCCCCF:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13828 osp_idx=27 osp_ver=12609 osp_pg=0 (spawn #279137) 2025-06-12 22:00:02.051752 :A3DCCCD0:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 22:00:02.051752 :A3DCCCD1:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=173 time=2741345235 2025-06-12 22:00:02.051752 :A3DCCCD2:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 22:00:02.051753 :A3DCCCD3:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 22:00:02.051754 :A3DCCCD4:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 22:00:02.051754 :A3DCCCD5:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 22:00:02.051755 :A3DCCCD6:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 22:00:02.052347 :A3DCCCD7: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-06-12 22:00:02.057658 :A3DCCCFF: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-06-12 22:00:02.057706 :A3DCCD0A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-06-12 22:00:02.075775 :A3DCCEEC: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-06-12 22:00:02.076092 :A3DCCEF4: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-06-12 22:00:02.076279 :A3DCCEFC: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-06-12 22:00:02.076450 :A3DCCF04: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-06-12 22:00:02.076624 :A3DCCF0C: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-06-12 22:00:02.076795 :A3DCCF14: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-06-12 22:00:02.076962 :A3DCCF1C: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-06-12 22:00:02.077114 :A3DCCF24: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-06-12 22:00:02.077277 :A3DCCF2C: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-06-12 22:00:02.077436 :A3DCCF34: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-06-12 22:00:02.077592 :A3DCCF3C: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-06-12 22:00:02.077742 :A3DCCF44: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-06-12 22:00:02.077895 :A3DCCF4C: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-06-12 22:00:02.078064 :A3DCCF54: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-06-12 22:00:02.078229 :A3DCCF5C: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-06-12 22:00:02.078381 :A3DCCF64: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-06-12 22:00:02.078541 :A3DCCF6C: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-06-12 22:00:02.078690 :A3DCCF74: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-06-12 22:00:02.078840 :A3DCCF7C: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-06-12 22:00:02.078989 :A3DCCF84: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-06-12 22:00:02.079147 :A3DCCF8C: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-06-12 22:00:02.079296 :A3DCCF94: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-06-12 22:00:02.079446 :A3DCCF9C: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-06-12 22:00:02.080080 :A3DCCFA4: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-06-12 22:00:02.107516 :A3DCCFAC: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-06-12 22:00:03.278823*:A3DCD2FA:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC03AD0 estart=1749736803 eid=19323313 ctx=0x0000000777968158 cwh=0x00000000447D0158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-12 22:00:03.278823*:A3DCD2FB:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC03AD0 curCtx=0x00000000446D1BF8 pubTabIdxCnt=0 2025-06-12 22:00:03.278823*:A3DCD2FC:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC03AD0 2025-06-12 22:00:03.278823*:A3DCD2FD:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC03AD0 2025-06-12 22:00:03.278823*:A3DCD2FE:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC7B6F0 estart=1749736801 eid=16777384 ctx=0x000000077B54EAC8 cwh=0x000000002DC70068 cpu=984375 buffg=55838 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-12 22:00:03.278823*:A3DCD2FF:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC7B6F0 curCtx=0x00000000444C7FD8 pubTabIdxCnt=0 2025-06-12 22:00:03.278823*:A3DCD300:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC7B6F0 2025-06-12 22:00:03.278823*:A3DCD301:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC7B6F0 2025-06-12 22:00:03.278823*:A3DCD302:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC03AD0 curCtx=0x00000000446D1BF8 xsc->flg4=65792 flags=1 2025-06-12 22:00:03.278823*:A3DCD303:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC03AD0 2025-06-12 22:00:03.747616*:A3DCD398:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC7B6F0 curCtx=0x00000000444C7FD8 xsc->flg4=65792 flags=2 2025-06-12 22:00:03.747616*:A3DCD399:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC7B6F0 2025-06-12 22:00:03.762154 :A3DCD39A: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-06-12 22:00:03.782161 :A3DCD3C3: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-06-12 22:00:03.784118 :A3DCD3CB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-06-12 22:00:03.784333 :A3DCD3CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-06-12 22:00:03.785215 :A3DCD3CD: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-06-12 22:00:03.785312 :A3DCD3D0: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-06-12 22:00:03.786151 :A3DCD3E2: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-06-12 22:00:03.787673 :A3DCD3EA: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-06-12 22:00:03.787975 :A3DCD3F2: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-06-12 22:00:03.841327*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-12 22:00:03.841327*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-12 22:00:03.841327*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-12 22:00:03.856953*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-12 22:00:03.856953*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-12 22:00:03.856953*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-12 22:00:03.856953*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-12 22:00:03.928053 :A3DCD47C: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-06-12 22:00:05.815402 :A3DCD5B7: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-06-12 22:00:05.815417 :A3DCD5B8:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0008.00e.0000cc13 2025-06-12 22:00:08.218077 :A3DCD7C7: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-06-12 22:00:08.219410 :A3DCD7CF: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-06-12 22:00:08.229785 :A3DCD7D7: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-06-12 22:00:08.254340 :A3DCD7DF: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-06-12 22:00:09.417307 :A3DCD8CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-06-12 22:00:09.427857 :A3DCD8CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-12 22:00:09.429476 :A3DCD8D6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 22:00:19.195477 :A3DCDC6E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 22:00:19.187 2025-06-12 22:00:19.195479 :A3DCDC6F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12700 pso_num=29 pso_serial#=116 2025-06-12 22:00:19.195623 :A3DCDC70:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12700 (legacy spawn) 2025-06-12 22:00:19.202985 :A3DCDC71: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-06-12 22:00:19.203144 :A3DCDC76:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-12 22:00:19.203957 :A3DCDC7B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 22:00:26.867544 :A3DCDED1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 22:00:26.857 2025-06-12 22:00:26.867546 :A3DCDED2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16580 pso_num=29 pso_serial#=117 2025-06-12 22:00:26.867706 :A3DCDED3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16580 (legacy spawn) 2025-06-12 22:00:26.874262 :A3DCDED4: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-06-12 22:00:26.874437 :A3DCDED9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-12 22:00:26.875245 :A3DCDEDE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 22:00:32.310424 :A3DCE07D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 22:00:32.296 2025-06-12 22:00:32.310427 :A3DCE07E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4800 pso_num=29 pso_serial#=118 2025-06-12 22:00:32.310571 :A3DCE07F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4800 (legacy spawn) 2025-06-12 22:00:32.317025 :A3DCE080: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-06-12 22:00:32.317197 :A3DCE089:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-12 22:00:32.318015 :A3DCE08A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 22:00:32.326623 :A3DCE08D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 22:00:32.312 2025-06-12 22:00:32.326625 :A3DCE08E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17200 pso_num=29 pso_serial#=119 2025-06-12 22:00:32.326807 :A3DCE08F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17200 (legacy spawn) 2025-06-12 22:00:32.333243 :A3DCE090: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-06-12 22:00:32.333416 :A3DCE099:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-12 22:00:32.334231 :A3DCE09A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 22:00:35.440202 :A3DCE236:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 22:00:35.437 2025-06-12 22:00:35.440203 :A3DCE237:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17376 pso_num=29 pso_serial#=120 2025-06-12 22:00:35.440350 :A3DCE238:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17376 osp_idx=27 osp_ver=12614 osp_pg=0 (spawn #279142) 2025-06-12 22:00:35.440351 :A3DCE239:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 22:00:35.440351 :A3DCE23A:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=174 time=2741378610 2025-06-12 22:00:35.440352 :A3DCE23B:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 22:00:35.440353 :A3DCE23C:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 22:00:35.440353 :A3DCE23D:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 22:00:35.440354 :A3DCE23E:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 22:00:35.440354 :A3DCE23F:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 22:00:35.440891 :A3DCE240: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-06-12 22:00:35.440940 :A3DCE246: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-06-12 22:00:35.449970 :A3DCE266:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 22:24:32.935065 :A3DEFC23:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 22:24:32.921 2025-06-12 22:24:32.935068 :A3DEFC24:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16700 pso_num=29 pso_serial#=121 2025-06-12 22:24:32.935224 :A3DEFC25:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16700 osp_idx=27 osp_ver=12615 osp_pg=0 (spawn #279197) 2025-06-12 22:24:32.935224 :A3DEFC26:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 22:24:32.935225 :A3DEFC27:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=175 time=2742816110 2025-06-12 22:24:32.935225 :A3DEFC28:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 22:24:32.935225 :A3DEFC29:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 22:24:32.935226 :A3DEFC2A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 22:24:32.935226 :A3DEFC2B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 22:24:32.935227 :A3DEFC2C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 22:24:32.936004 :A3DEFC2D: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-06-12 22:24:32.936053 :A3DEFC33: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-06-12 22:24:33.039616 :A3DEFC65:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 22:24:34.238858 :A3DEFCB1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 22:24:34.234 2025-06-12 22:24:34.238860 :A3DEFCB2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15128 pso_num=29 pso_serial#=122 2025-06-12 22:24:34.238994 :A3DEFCB3:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15128 osp_idx=27 osp_ver=12616 osp_pg=0 (spawn #279199) 2025-06-12 22:24:34.238995 :A3DEFCB4:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 22:24:34.238995 :A3DEFCB5:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=176 time=2742817407 2025-06-12 22:24:34.238996 :A3DEFCB6:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 22:24:34.238996 :A3DEFCB7:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 22:24:34.238996 :A3DEFCB8:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 22:24:34.238997 :A3DEFCB9:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 22:24:34.238997 :A3DEFCBA:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 22:24:34.239517 :A3DEFCBB: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-06-12 22:24:34.239567 :A3DEFCC1: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 15128, M002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de