Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250701220433\orcl_m002_9900_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250701220433\orcl_m002_9900_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:36792M/63366M, Ph+PgF:42826M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 9900, image: ORACLE.EXE (M002) *** 2025-07-01 22:04:33.275 *** SESSION ID:(233.27571) 2025-07-01 22:04:33.275 *** 2025-07-01 22:04:33.275 Process diagnostic dump for ORACLE.EXE (M002), OS id=9900, pid: 29, proc_ser: 227, sid: 233, sess_ser: 27571 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x17e2, blocks=0x1 wait_id=1325 seq_num=1326 snap_id=1 wait times: snap=0.001083 sec, exc=0.001083 sec, total=0.001083 sec wait times: max=infinite, heur=0.001083 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000252 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x17c2, blocks=0x1 wait_id=1324 seq_num=1325 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000132 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x17b2, blocks=0x1 wait_id=1323 seq_num=1324 snap_id=1 wait times: snap=0.000047 sec, exc=0.000047 sec, total=0.000047 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000139 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x17a2, blocks=0x1 wait_id=1322 seq_num=1323 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000368 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x1772, blocks=0x1 wait_id=1321 seq_num=1322 snap_id=1 wait times: snap=0.000056 sec, exc=0.000056 sec, total=0.000056 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000189 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x1762, blocks=0x1 wait_id=1320 seq_num=1321 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000360 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1732, blocks=0x1 wait_id=1319 seq_num=1320 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.000246 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1702, blocks=0x1 wait_id=1318 seq_num=1319 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000141 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x16f2, blocks=0x1 wait_id=1317 seq_num=1318 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000134 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x17f2, blocks=0x1 wait_id=1326 seq_num=1327 snap_id=1 wait times: snap=0.000054 sec, exc=0.000054 sec, total=0.000054 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000185 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x17e2, blocks=0x1 wait_id=1325 seq_num=1326 snap_id=1 wait times: snap=0.001248 sec, exc=0.001248 sec, total=0.001248 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000252 sec of elapsed time Sampled Session History of session 233 serial 27571 --------------------------------------------------- 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:04:33] idle wait at each sample [session created at: 22:04:32] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-01 22:04:33.275 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 9900, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-01 01:00:29.431167 :ABE20F65:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9728 (legacy spawn) 2025-07-01 01:00:29.437617 :ABE20F66: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-07-01 01:00:29.437780 :ABE20F6F: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-07-01 01:00:29.438587 :ABE20F70:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 01:00:32.511953 :ABE21061:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 01:00:32.500 2025-07-01 01:00:32.511956 :ABE21062:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16036 pso_num=29 pso_serial#=216 2025-07-01 01:00:32.512109 :ABE21063:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16036 (legacy spawn) 2025-07-01 01:00:32.518540 :ABE21064: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-07-01 01:00:32.518720 :ABE2106D: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-07-01 01:00:32.519520 :ABE2106E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 01:00:32.528146 :ABE21071:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 01:00:32.515 2025-07-01 01:00:32.528149 :ABE21072:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15660 pso_num=29 pso_serial#=217 2025-07-01 01:00:32.528286 :ABE21073:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15660 (legacy spawn) 2025-07-01 01:00:32.534740 :ABE21074: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-07-01 01:00:32.534904 :ABE2107D: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-07-01 01:00:32.535765 :ABE2107E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 06:04:52.421285 :ABF95FB2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 06:04:52.418 2025-07-01 06:04:52.421287 :ABF95FB3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12620 pso_num=29 pso_serial#=218 2025-07-01 06:04:52.421433 :ABF95FB4:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12620 osp_idx=27 osp_ver=13732 osp_pg=0 (spawn #340306) 2025-07-01 06:04:52.421434 :ABF95FB5:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-01 06:04:52.421434 :ABF95FB6:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=33 time=30668298 2025-07-01 06:04:52.421434 :ABF95FB7:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 06:04:52.421435 :ABF95FB8:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 06:04:52.421435 :ABF95FB9:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 06:04:52.421436 :ABF95FBA:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 06:04:52.421436 :ABF95FBB:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 06:04:52.421974 :ABF95FBC: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-07-01 06:04:52.422020 :ABF95FC2: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-07-01 06:04:52.431130 :ABF95FDA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 16:00:09.962513 :AC26CBD4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 16:00:09.947 2025-07-01 16:00:09.962515 :AC26CBD5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17960 pso_num=29 pso_serial#=219 2025-07-01 16:00:09.962670 :AC26CBD6:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17960 osp_idx=27 osp_ver=13733 osp_pg=0 (spawn #341672) 2025-07-01 16:00:09.962670 :AC26CBD7:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-01 16:00:09.962671 :AC26CBD8:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=34 time=66385845 2025-07-01 16:00:09.962671 :AC26CBD9:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 16:00:09.962671 :AC26CBDA:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 16:00:09.962672 :AC26CBDB:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 16:00:09.962672 :AC26CBDC:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 16:00:09.962672 :AC26CBDD:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 16:00:09.963227 :AC26CBDE: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-07-01 16:00:09.963264 :AC26CBE4: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-07-01 16:00:09.970354 :AC26CBF2: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-07-01 16:00:09.971485 :AC26CBFA: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-07-01 16:00:09.972056 :AC26CC02: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-07-01 16:00:09.972509 :AC26CC0A: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-07-01 16:00:09.972946 :AC26CC12: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-07-01 16:00:09.975455 :AC26CC1A: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-07-01 16:00:09.976576 :AC26CC22: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-07-01 16:00:10.026131 :AC26CC2A: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-07-01 16:00:10.027016 :AC26CC32: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-07-01 16:00:10.027250 :AC26CC3A: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-07-01 16:00:10.027457 :AC26CC42: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-07-01 16:00:10.029344 :AC26CC4A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 22:00:00.498395 :AC42424C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 22:00:00.492 2025-07-01 22:00:00.498396 :AC42424D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12648 pso_num=29 pso_serial#=220 2025-07-01 22:00:00.498549 :AC424252:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12648 osp_idx=27 osp_ver=13734 osp_pg=0 (spawn #342502) 2025-07-01 22:00:00.498550 :AC424253:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-01 22:00:00.498551 :AC424254:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=35 time=87976392 2025-07-01 22:00:00.498551 :AC424255:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 22:00:00.498551 :AC424256:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 22:00:00.498552 :AC424257:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 22:00:00.498553 :AC424258:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 22:00:00.498554 :AC424259:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 22:00:00.499141 :AC424262: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-07-01 22:00:00.504240 :AC42427E: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-07-01 22:00:00.504286 :AC424281:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-01 22:00:00.522201 :AC424443: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-07-01 22:00:00.522439 :AC42444B: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-07-01 22:00:00.522627 :AC424453: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-07-01 22:00:00.522845 :AC42445B: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-07-01 22:00:00.523015 :AC424463: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-07-01 22:00:00.523172 :AC42446B: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-07-01 22:00:00.523334 :AC424473: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-07-01 22:00:00.523496 :AC42447B: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-07-01 22:00:00.523649 :AC424483: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-07-01 22:00:00.524117 :AC42448B: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-07-01 22:00:00.524323 :AC424493: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-07-01 22:00:00.524591 :AC42449B: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-07-01 22:00:00.524753 :AC4244A3: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-07-01 22:00:00.524905 :AC4244AB: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-07-01 22:00:00.525060 :AC4244B3: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-07-01 22:00:00.525211 :AC4244BB: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-07-01 22:00:00.525359 :AC4244C3: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-07-01 22:00:00.525516 :AC4244CB: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-07-01 22:00:00.525676 :AC4244D3: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-07-01 22:00:00.525830 :AC4244DB: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-07-01 22:00:00.525980 :AC4244E3: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-07-01 22:00:00.526129 :AC4244EB: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-07-01 22:00:00.526297 :AC4244F3: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-07-01 22:00:00.526502 :AC4244FB: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-07-01 22:00:00.526727 :AC424503: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-07-01 22:00:00.526900 :AC42450B: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-07-01 22:00:00.554184 :AC424513: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-07-01 22:00:02.147586*:AC4247F9:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031233AD0 estart=1751378402 eid=19978824 ctx=0x0000000777968158 cwh=0x000000004A0E0158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-01 22:00:02.147586*:AC4247FA:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031233AD0 curCtx=0x000000004A10EE70 pubTabIdxCnt=0 2025-07-01 22:00:02.147586*:AC4247FB:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031233AD0 2025-07-01 22:00:02.147586*:AC4247FC:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031233AD0 2025-07-01 22:00:02.147586*:AC4247FD:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000312AB6F0 estart=1751378400 eid=16777427 ctx=0x000000077B54EAC8 cwh=0x00000000312A0068 cpu=1406250 buffg=76793 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-01 22:00:02.147586*:AC4247FE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000312AB6F0 curCtx=0x0000000049FD7FD8 pubTabIdxCnt=0 2025-07-01 22:00:02.147586*:AC4247FF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000312AB6F0 2025-07-01 22:00:02.147586*:AC424800:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000312AB6F0 2025-07-01 22:00:02.147586*:AC424801:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031233AD0 curCtx=0x000000004A10EE70 xsc->flg4=65792 flags=1 2025-07-01 22:00:02.147586*:AC424802:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031233AD0 2025-07-01 22:00:02.194458*:AC424863:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000312AB6F0 curCtx=0x0000000049FD7FD8 xsc->flg4=65792 flags=2 2025-07-01 22:00:02.194458*:AC424864:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000312AB6F0 2025-07-01 22:00:02.204719 :AC424865: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-07-01 22:00:02.224965 :AC42487E: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-07-01 22:00:02.226828 :AC424886:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-01 22:00:02.227085 :AC424887:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-01 22:00:02.227996 :AC424888: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-07-01 22:00:02.228089 :AC42488B: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-07-01 22:00:02.229019 :AC42489D: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-07-01 22:00:02.230522 :AC4248A5: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-07-01 22:00:02.230807 :AC4248AD: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-07-01 22:00:02.288215*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-01 22:00:02.288215*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-01 22:00:02.288215*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-01 22:00:02.288215*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-01 22:00:02.288215*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-01 22:00:02.288215*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-01 22:00:02.288215*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-01 22:00:02.363087 :AC424937: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-07-01 22:00:04.245180 :AC424A7B: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-07-01 22:00:04.245192 :AC424A7C:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0007.01d.0000c631 2025-07-01 22:00:06.687426 :AC424C08: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-07-01 22:00:06.689293 :AC424C10: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-07-01 22:00:06.703420 :AC424C18: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-07-01 22:00:06.732028 :AC424C20: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-07-01 22:00:07.931162 :AC424D01:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-07-01 22:00:07.943385 :AC424D02: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-07-01 22:00:07.945385 :AC424D0A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 22:00:09.107922 :AC424E05:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 22:00:09.094 2025-07-01 22:00:09.107924 :AC424E06:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15556 pso_num=29 pso_serial#=221 2025-07-01 22:00:09.108062 :AC424E07:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15556 (legacy spawn) 2025-07-01 22:00:09.116162 :AC424E08: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-07-01 22:00:09.116324 :AC424E11: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-07-01 22:00:09.117129 :AC424E12:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 22:00:09.133550 :AC424E15:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 22:00:09.125 2025-07-01 22:00:09.133552 :AC424E16:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15876 pso_num=29 pso_serial#=222 2025-07-01 22:00:09.133691 :AC424E17:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15876 (legacy spawn) 2025-07-01 22:00:09.140205 :AC424E18: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-07-01 22:00:09.140365 :AC424E21: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-07-01 22:00:09.141171 :AC424E22:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 22:00:12.201584 :AC424F28:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 22:00:12.188 2025-07-01 22:00:12.201587 :AC424F29:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15304 pso_num=29 pso_serial#=223 2025-07-01 22:00:12.201754 :AC424F2A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15304 (legacy spawn) 2025-07-01 22:00:12.208237 :AC424F2B: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-07-01 22:00:12.208419 :AC424F30: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-07-01 22:00:12.209211 :AC424F35:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 22:00:12.217830 :AC424F38:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 22:00:12.203 2025-07-01 22:00:12.217833 :AC424F39:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11040 pso_num=29 pso_serial#=224 2025-07-01 22:00:12.217968 :AC424F3A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11040 (legacy spawn) 2025-07-01 22:00:12.224422 :AC424F3B: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-07-01 22:00:12.224583 :AC424F44: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-07-01 22:00:12.225359 :AC424F45:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 22:04:31.633290 :AC42DD74:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 22:04:31.618 2025-07-01 22:04:31.633291 :AC42DD75:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13344 pso_num=29 pso_serial#=225 2025-07-01 22:04:31.633459 :AC42DD76:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13344 osp_idx=27 osp_ver=13739 osp_pg=0 (spawn #342518) 2025-07-01 22:04:31.633460 :AC42DD77:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-01 22:04:31.633461 :AC42DD78:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=36 time=88247517 2025-07-01 22:04:31.633461 :AC42DD79:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 22:04:31.633462 :AC42DD7A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 22:04:31.633462 :AC42DD7B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 22:04:31.633462 :AC42DD7C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 22:04:31.633462 :AC42DD7D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 22:04:31.634333 :AC42DD7E: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-07-01 22:04:31.634378 :AC42DD84: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-07-01 22:04:31.750367 :AC42E218:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 22:04:32.876875 :AC42E2A6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 22:04:32.869 2025-07-01 22:04:32.876877 :AC42E2A7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17140 pso_num=29 pso_serial#=226 2025-07-01 22:04:32.877008 :AC42E2A8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17140 (legacy spawn) 2025-07-01 22:04:32.883442 :AC42E2A9: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-07-01 22:04:32.883616 :AC42E2B2: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-07-01 22:04:32.884497 :AC42E2B3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-01 22:04:32.948597 :AC42E2C9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-01 22:04:32.947 2025-07-01 22:04:32.948599 :AC42E2CA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9900 pso_num=29 pso_serial#=227 2025-07-01 22:04:32.948739 :AC42E2CB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=9900 osp_idx=27 osp_ver=13741 osp_pg=0 (spawn #342520) 2025-07-01 22:04:32.948740 :AC42E2CC:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-01 22:04:32.948740 :AC42E2CD:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=37 time=88248829 2025-07-01 22:04:32.948740 :AC42E2CE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 22:04:32.948741 :AC42E2CF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 22:04:32.948741 :AC42E2D0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 22:04:32.948741 :AC42E2D1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 22:04:32.948742 :AC42E2D2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 22:04:32.949244 :AC42E2D3: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-07-01 22:04:32.949295 :AC42E2D9: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: 9900, M002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de