Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250812220302\orcl_m002_18696_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:35553M/63366M, Ph+PgF:40950M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 18696, image: ORACLE.EXE (M002) *** 2025-08-12 22:03:02.482 *** SESSION ID:(201.65455) 2025-08-12 22:03:02.482 *** 2025-08-12 22:03:02.482 Process diagnostic dump for ORACLE.EXE (M002), OS id=18696, pid: 25, proc_ser: 50, sid: 201, sess_ser: 65455 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.000198 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000225 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x1b8a, blocks=0x1 wait_id=1220 seq_num=1221 snap_id=1 wait times: snap=0.005017 sec, exc=0.005017 sec, total=0.005017 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000146 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x7b2, blocks=0x1 wait_id=1219 seq_num=1220 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.000147 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x1d3a, blocks=0x1 wait_id=1218 seq_num=1219 snap_id=1 wait times: snap=0.000074 sec, exc=0.000074 sec, total=0.000074 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000253 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x1a2a, blocks=0x1 wait_id=1217 seq_num=1218 snap_id=1 wait times: snap=0.000082 sec, exc=0.000082 sec, total=0.000082 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000330 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x1c5a, blocks=0x1 wait_id=1216 seq_num=1217 snap_id=1 wait times: snap=0.001745 sec, exc=0.001745 sec, total=0.001745 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000401 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1baa, blocks=0x1 wait_id=1215 seq_num=1216 snap_id=1 wait times: snap=0.006469 sec, exc=0.006469 sec, total=0.006469 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000140 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1d2a, blocks=0x1 wait_id=1214 seq_num=1215 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.000143 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x1b2a, blocks=0x1 wait_id=1213 seq_num=1214 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.000178 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x7ca, blocks=0x1 wait_id=1212 seq_num=1213 snap_id=1 wait times: snap=0.000075 sec, exc=0.000075 sec, total=0.000075 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000460 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x1d4a, blocks=0x1 wait_id=1221 seq_num=1222 snap_id=1 wait times: snap=0.000067 sec, exc=0.000067 sec, total=0.000067 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000459 sec of elapsed time Sampled Session History of session 201 serial 65455 --------------------------------------------------- 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:03:02] waited for 'ADR block file read', seq_num: 471 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 22:03:01] idle wait at each sample [session created at: 22:03:02] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 22:03:02 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-12 22:03:02.482 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 18696, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-12 22:00:02.040994 :BEE795C2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=218 time=3716671345 2025-08-12 22:00:02.040994 :BEE795C3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-12 22:00:02.040994 :BEE795C4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-12 22:00:02.040995 :BEE795C5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-12 22:00:02.040995 :BEE795C6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-12 22:00:02.040995 :BEE795C7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-12 22:00:02.041483 :BEE795C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.046873 :BEE795E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.046914 :BEE795F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-12 22:00:02.058249 :BEE79650:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.063477 :BEE79675:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.064282 :BEE79690:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.069828 :BEE7981F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.069975 :BEE79836:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.070749 :BEE79848:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.070952 :BEE79850:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-12 22:00:02.072544 :BEE79851:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.072592 :BEE79854:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.072669 :BEE79861:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:00:02.176385 :BEE7991E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-12 22:00:02.178435 :BEE79922:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.178475 :BEE79925:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-12 22:00:02.180931 :BEE7992B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-12 22:00:02.184136 :BEE7992C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.184216 :BEE7992F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-12 22:00:02.184307 :BEE7993C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:00:04.009346 :BEE79D5F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-12 22:00:04.011548 :BEE79D63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:04.011589 :BEE79D66:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-12 22:00:04.021888 :BEE79D6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:04.022688 :BEE79D74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:04.023451 :BEE79D7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:04.023719 :BEE79D84:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-12 22:00:04.025280 :BEE79D85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:00:04.025324 :BEE79D88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-12 22:00:04.025427 :BEE79D95:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:00:29.349129 :BEE7A843:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-12 22:00:59.430657 :BEE7B3D2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:01:00.685355 :BEE7B41A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:01:00.670 2025-08-12 22:01:00.685357 :BEE7B41B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21308 pso_num=25 pso_serial#=35 2025-08-12 22:01:00.685495 :BEE7B41C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21308 (legacy spawn) 2025-08-12 22:01:00.692030 :BEE7B41D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:01:00.692197 :BEE7B422:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:01:00.692979 :BEE7B427:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:01:00.701814 :BEE7B42A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:01:00.701 2025-08-12 22:01:00.701816 :BEE7B42B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20384 pso_num=25 pso_serial#=36 2025-08-12 22:01:00.701959 :BEE7B42C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20384 (legacy spawn) 2025-08-12 22:01:00.708463 :BEE7B42D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:01:00.708492 :BEE7B430:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:01:00.708635 :BEE7B437:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:01:00.709439 :BEE7B444:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:01:03.802930 :BEE7B52B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:01:03.790 2025-08-12 22:01:03.802932 :BEE7B52C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21112 pso_num=25 pso_serial#=37 2025-08-12 22:01:03.803073 :BEE7B52D:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21112 (legacy spawn) 2025-08-12 22:01:03.809616 :BEE7B52E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:01:03.809803 :BEE7B533:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:01:03.810636 :BEE7B538:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:01:03.819361 :BEE7B53B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:01:03.805 2025-08-12 22:01:03.819363 :BEE7B53C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19984 pso_num=25 pso_serial#=38 2025-08-12 22:01:03.819497 :BEE7B53D:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19984 (legacy spawn) 2025-08-12 22:01:03.825935 :BEE7B53E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:01:03.826100 :BEE7B547:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:01:03.826893 :BEE7B548:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:01:20.940878 :BEE7BAD3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:01:20.935 2025-08-12 22:01:20.940880 :BEE7BAD4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6008 pso_num=25 pso_serial#=39 2025-08-12 22:01:20.941036 :BEE7BAD5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6008 osp_idx=23 osp_ver=237811 osp_pg=0 (spawn #482493) 2025-08-12 22:01:20.941037 :BEE7BAD6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-12 22:01:20.941037 :BEE7BAD7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=219 time=3716750251 2025-08-12 22:01:20.941037 :BEE7BAD8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-12 22:01:20.941038 :BEE7BAD9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-12 22:01:20.941038 :BEE7BADA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-12 22:01:20.941039 :BEE7BADB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-12 22:01:20.941039 :BEE7BADC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-12 22:01:20.941575 :BEE7BADD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-12 22:01:20.941622 :BEE7BAE3:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-12 22:01:20.949992 :BEE7BAF2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:01:29.049061 :BEE7BD69:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:01:29.038 2025-08-12 22:01:29.049063 :BEE7BD6A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19852 pso_num=25 pso_serial#=40 2025-08-12 22:01:29.049212 :BEE7BD6B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19852 (legacy spawn) 2025-08-12 22:01:29.056156 :BEE7BD6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:01:29.056370 :BEE7BD71:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:01:29.057311 :BEE7BD76:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:01:29.451835 :BEE7BDA7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:01:29.446 2025-08-12 22:01:29.451837 :BEE7BDA8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20004 pso_num=25 pso_serial#=41 2025-08-12 22:01:29.451977 :BEE7BDA9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20004 osp_idx=23 osp_ver=237813 osp_pg=0 (spawn #482494) 2025-08-12 22:01:29.451978 :BEE7BDAA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-12 22:01:29.451978 :BEE7BDAB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=220 time=3716758751 2025-08-12 22:01:29.451978 :BEE7BDAC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-12 22:01:29.451979 :BEE7BDAD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-12 22:01:29.451979 :BEE7BDAE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-12 22:01:29.451979 :BEE7BDAF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-12 22:01:29.451979 :BEE7BDB0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-12 22:01:29.452468 :BEE7BDB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-12 22:01:29.453110 :BEE7BDC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:01:29.460694 :BEE7BDE2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-12 22:01:29.461065 :BEE7BDE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:01:29.461101 :BEE7BDE6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-12 22:01:29.462042 :BEE7BDEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:01:29.462161 :BEE7BDF5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:01:59.540242 :BEE7C7D9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:02:03.320669 :BEE7C8F5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:02:03.312 2025-08-12 22:02:03.320671 :BEE7C8F6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19848 pso_num=25 pso_serial#=42 2025-08-12 22:02:03.320807 :BEE7C8F7:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19848 (legacy spawn) 2025-08-12 22:02:03.327327 :BEE7C8F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:03.327501 :BEE7C8FD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:02:03.328317 :BEE7C910:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:02:13.193065 :BEE7CBE1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:02:13.179 2025-08-12 22:02:13.193066 :BEE7CBE2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20804 pso_num=25 pso_serial#=43 2025-08-12 22:02:13.193202 :BEE7CBE3:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20804 (legacy spawn) 2025-08-12 22:02:13.199762 :BEE7CBE4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:13.199938 :BEE7CBE9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:02:13.200766 :BEE7CBEE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:02:13.209874 :BEE7CBF1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:02:13.195 2025-08-12 22:02:13.209876 :BEE7CBF2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19516 pso_num=25 pso_serial#=44 2025-08-12 22:02:13.210038 :BEE7CBF3:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19516 (legacy spawn) 2025-08-12 22:02:13.216716 :BEE7CBF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:13.216888 :BEE7CBFC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:02:13.217771 :BEE7CBFE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:02:16.287851 :BEE7CCF0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:02:16.274 2025-08-12 22:02:16.287853 :BEE7CCF1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19116 pso_num=25 pso_serial#=45 2025-08-12 22:02:16.287991 :BEE7CCF2:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19116 (legacy spawn) 2025-08-12 22:02:16.294532 :BEE7CCF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:16.294701 :BEE7CCFB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:02:16.295477 :BEE7CCFD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:02:16.314711 :BEE7CD00:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:02:16.305 2025-08-12 22:02:16.314714 :BEE7CD01:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18072 pso_num=25 pso_serial#=46 2025-08-12 22:02:16.314847 :BEE7CD02:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=18072 (legacy spawn) 2025-08-12 22:02:16.321262 :BEE7CD03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:16.321292 :BEE7CD06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:16.321433 :BEE7CD0D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:02:16.322226 :BEE7CD1A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:02:16.532911 :BEE7CD1D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:02:16.524 2025-08-12 22:02:16.532913 :BEE7CD1E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19584 pso_num=25 pso_serial#=47 2025-08-12 22:02:16.533055 :BEE7CD1F:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19584 (legacy spawn) 2025-08-12 22:02:16.539476 :BEE7CD20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:16.539656 :BEE7CD25:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:02:16.540489 :BEE7CD5A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:02:29.527608 :BEE7D182:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:02:29.521 2025-08-12 22:02:29.527610 :BEE7D183:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20752 pso_num=25 pso_serial#=48 2025-08-12 22:02:29.527752 :BEE7D184:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20752 osp_idx=23 osp_ver=237820 osp_pg=0 (spawn #482496) 2025-08-12 22:02:29.527753 :BEE7D185:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-12 22:02:29.527753 :BEE7D186:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=221 time=3716818829 2025-08-12 22:02:29.527753 :BEE7D187:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-12 22:02:29.527754 :BEE7D188:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-12 22:02:29.527755 :BEE7D189:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-12 22:02:29.527755 :BEE7D18A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-12 22:02:29.527755 :BEE7D18B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-12 22:02:29.528245 :BEE7D18C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-12 22:02:29.528889 :BEE7D1A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:29.539841 :BEE7D1BD:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-12 22:02:29.540299 :BEE7D1BE:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-12 22:02:29.542645 :BEE7D1BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-12 22:02:29.542979 :BEE7D1C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:29.543018 :BEE7D1C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-12 22:02:29.543908 :BEE7D1C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 22:02:29.544036 :BEE7D1D2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-12 22:02:59.597551 :BEE8134F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:03:00.911428 :BEE8154E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:03:00.904 2025-08-12 22:03:00.911430 :BEE8154F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18048 pso_num=25 pso_serial#=49 2025-08-12 22:03:00.911592 :BEE81550:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18048 osp_idx=23 osp_ver=237821 osp_pg=0 (spawn #482499) 2025-08-12 22:03:00.911593 :BEE81551:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-12 22:03:00.911594 :BEE81552:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=222 time=3716850220 2025-08-12 22:03:00.911594 :BEE81553:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-12 22:03:00.911595 :BEE81554:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-12 22:03:00.911595 :BEE81555:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-12 22:03:00.911595 :BEE81556:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-12 22:03:00.911595 :BEE81557:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-12 22:03:00.912421 :BEE81558:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-12 22:03:00.912464 :BEE8155E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-12 22:03:01.012651 :BEE81590:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-12 22:03:02.213222 :BEE81628:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-12 22:03:02.201 2025-08-12 22:03:02.213224 :BEE81629:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18696 pso_num=25 pso_serial#=50 2025-08-12 22:03:02.213358 :BEE8162A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18696 osp_idx=23 osp_ver=237822 osp_pg=0 (spawn #482501) 2025-08-12 22:03:02.213358 :BEE8162B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-12 22:03:02.213359 :BEE8162C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=223 time=3716851517 2025-08-12 22:03:02.213359 :BEE8162D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-12 22:03:02.213360 :BEE8162E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-12 22:03:02.213360 :BEE8162F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-12 22:03:02.213360 :BEE81630:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-12 22:03:02.213360 :BEE81631:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-12 22:03:02.213860 :BEE81632:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-12 22:03:02.213904 :BEE81638:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 18696, M002)