Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250815220006\orcl_j003_18460_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:35466M/63366M, Ph+PgF:40798M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 18460, image: ORACLE.EXE (J003) *** 2025-08-15 22:00:06.582 *** SESSION ID:(233.31975) 2025-08-15 22:00:06.582 *** 2025-08-15 22:00:06.582 Process diagnostic dump for ORACLE.EXE (J003), OS id=18460, pid: 29, proc_ser: 113, sid: 233, sess_ser: 31975 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18460 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12917, hash value=2930936675 Current Wait Stack: Not in wait; last wait ended 2.729899 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.729926 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000157 sec, exc=0.000157 sec, total=0.000157 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.062521 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.024501 sec of elapsed time 2: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000145 sec, exc=0.000145 sec, total=0.000145 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.733713 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.025273 sec of elapsed time 4: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000003 sec, exc=0.000003 sec, total=0.000003 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012927 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000106 sec, exc=0.000106 sec, total=0.000106 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049625 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.008241 sec, exc=0.008241 sec, total=0.008241 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008068 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.000070 sec, exc=0.000070 sec, total=0.000070 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.261549 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.004229 sec, exc=0.004229 sec, total=0.004229 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000171 sec of elapsed time 9: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000111 sec, exc=0.000111 sec, total=0.000111 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008191 sec of elapsed time Sampled Session History of session 233 serial 31975 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:00:02 - 22:00:06] not in wait at each sample [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-15 22:00:06.582 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 18460, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-15 00:06:12.586575 :BFD2AB7E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16572 (legacy spawn) 2025-08-15 00:06:12.593382 :BFD2AB7F: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-08-15 00:06:12.593564 :BFD2AB87: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-08-15 00:06:12.594562 :BFD2AB88:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 00:06:12.603299 :BFD2AB8B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 00:06:12.603 2025-08-15 00:06:12.603301 :BFD2AB8C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19168 pso_num=29 pso_serial#=102 2025-08-15 00:06:12.603440 :BFD2AB8D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19168 (legacy spawn) 2025-08-15 00:06:12.609939 :BFD2AB8E: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-08-15 00:06:12.610105 :BFD2AB97: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-08-15 00:06:12.610960 :BFD2AB98:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 00:06:15.774828 :BFD2ACA2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 00:06:15.762 2025-08-15 00:06:15.774829 :BFD2ACA3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4124 pso_num=29 pso_serial#=103 2025-08-15 00:06:15.774965 :BFD2ACA4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4124 (legacy spawn) 2025-08-15 00:06:15.781509 :BFD2ACA5: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-08-15 00:06:15.781542 :BFD2ACA8: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-08-15 00:06:15.781696 :BFD2ACAE: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-08-15 00:06:15.782504 :BFD2ACBB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 00:06:15.791277 :BFD2ACBE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 00:06:15.777 2025-08-15 00:06:15.791279 :BFD2ACBF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19808 pso_num=29 pso_serial#=104 2025-08-15 00:06:15.791421 :BFD2ACC0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19808 (legacy spawn) 2025-08-15 00:06:15.812732 :BFD2ACC1: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-08-15 00:06:15.812894 :BFD2ACCA: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-08-15 00:06:15.813723 :BFD2ACCB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 01:00:07.620664 :BFD6E66B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 01:00:07.611 2025-08-15 01:00:07.620666 :BFD6E66C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10304 pso_num=29 pso_serial#=105 2025-08-15 01:00:07.620809 :BFD6E66D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10304 (legacy spawn) 2025-08-15 01:00:07.643625 :BFD6E66E: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-08-15 01:00:07.643797 :BFD6E673: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-08-15 01:00:07.644702 :BFD6E678:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 01:00:10.375564 :BFD6E84B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 01:00:10.361 2025-08-15 01:00:10.375566 :BFD6E84C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18800 pso_num=29 pso_serial#=106 2025-08-15 01:00:10.375710 :BFD6E84D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18800 (legacy spawn) 2025-08-15 01:00:10.396139 :BFD6E84E: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-08-15 01:00:10.396315 :BFD6E857: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-08-15 01:00:10.397145 :BFD6E858:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 01:00:10.405875 :BFD6E85B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 01:00:10.392 2025-08-15 01:00:10.405878 :BFD6E85C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19744 pso_num=29 pso_serial#=107 2025-08-15 01:00:10.406024 :BFD6E85D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19744 (legacy spawn) 2025-08-15 01:00:10.412541 :BFD6E85E: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-08-15 01:00:10.412707 :BFD6E866: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-08-15 01:00:10.413503 :BFD6E867:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 01:00:13.527866 :BFD6E970:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 01:00:13.514 2025-08-15 01:00:13.527868 :BFD6E971:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19960 pso_num=29 pso_serial#=108 2025-08-15 01:00:13.528009 :BFD6E972:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19960 (legacy spawn) 2025-08-15 01:00:13.534573 :BFD6E973: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-08-15 01:00:13.534754 :BFD6E978: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-08-15 01:00:13.535571 :BFD6E97D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 01:00:13.544298 :BFD6E980:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 01:00:13.530 2025-08-15 01:00:13.544300 :BFD6E981:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18840 pso_num=29 pso_serial#=109 2025-08-15 01:00:13.544449 :BFD6E982:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18840 (legacy spawn) 2025-08-15 01:00:13.550930 :BFD6E983: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-08-15 01:00:13.551123 :BFD6E98C: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-08-15 01:00:13.551928 :BFD6E98D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 01:00:22.239188 :BFD6EC0D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 01:00:22.234 2025-08-15 01:00:22.239191 :BFD6EC0E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20752 pso_num=29 pso_serial#=110 2025-08-15 01:00:22.239329 :BFD6EC0F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20752 osp_idx=27 osp_ver=15919 osp_pg=0 (spawn #489570) 2025-08-15 01:00:22.239330 :BFD6EC10:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 01:00:22.239330 :BFD6EC11:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=247 time=3900291548 2025-08-15 01:00:22.239331 :BFD6EC12:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 01:00:22.239331 :BFD6EC13:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 01:00:22.239332 :BFD6EC14:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 01:00:22.239332 :BFD6EC15:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 01:00:22.239332 :BFD6EC16:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 01:00:22.239821 :BFD6EC17: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-08-15 01:00:22.239863 :BFD6EC1D: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-08-15 01:00:22.244296 :BFD6EC30: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-08-15 01:00:22.275131 :BFD6EC44: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-08-15 01:00:22.290892 :BFD6EC4C: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-08-15 01:00:22.323450 :BFD6EC69: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-08-15 01:00:22.327062 :BFD6EC71: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-08-15 01:00:22.327380 :BFD6EC79: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-08-15 01:00:22.331165 :BFD6EC81: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-08-15 01:00:22.333753 :BFD6EC89: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-08-15 01:00:22.335506 :BFD6EC91: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-08-15 01:00:22.336595 :BFD6EC99: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-08-15 01:00:22.340269 :BFD6ECA1: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-08-15 01:00:23.553598 :BFD6ED37: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-08-15 01:00:23.598262 :BFD6ED3F: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-08-15 01:00:23.598619 :BFD6ED47: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-08-15 01:00:23.598899 :BFD6ED4F: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-08-15 01:00:23.598917 :BFD6ED52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-15 01:00:23.598997 :BFD6ED63: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-08-15 01:00:23.602868 :BFD6ED6C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 01:00:28.391040 :BFD6EF4E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 01:00:28.377 2025-08-15 01:00:28.391042 :BFD6EF4F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19756 pso_num=29 pso_serial#=111 2025-08-15 01:00:28.391198 :BFD6EF50:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19756 (legacy spawn) 2025-08-15 01:00:28.397976 :BFD6EF51: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-08-15 01:00:28.398165 :BFD6EF56: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-08-15 01:00:28.399091 :BFD6EF5B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 16:00:22.189424 :C01C89BC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 16:00:22.180 2025-08-15 16:00:22.189426 :C01C89BD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10672 pso_num=29 pso_serial#=112 2025-08-15 16:00:22.189608 :C01C89BE:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=10672 osp_idx=27 osp_ver=15921 osp_pg=0 (spawn #491640) 2025-08-15 16:00:22.189608 :C01C89BF:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 16:00:22.189609 :C01C89C0:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=248 time=3954291486 2025-08-15 16:00:22.189609 :C01C89C1:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 16:00:22.189610 :C01C89C2:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 16:00:22.189610 :C01C89C3:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 16:00:22.189610 :C01C89C4:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 16:00:22.189611 :C01C89C5:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 16:00:22.190136 :C01C89C6: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-08-15 16:00:22.190182 :C01C89CC: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-08-15 16:00:22.198182 :C01C89DA: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-08-15 16:00:22.199306 :C01C89E2: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-08-15 16:00:22.199894 :C01C89EA: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-08-15 16:00:22.200350 :C01C89F2: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-08-15 16:00:22.200782 :C01C89FA: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-08-15 16:00:22.203458 :C01C8A02: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-08-15 16:00:22.204609 :C01C8A0A: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-08-15 16:00:22.256375 :C01C8A12: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-08-15 16:00:22.257250 :C01C8A1A: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-08-15 16:00:22.257502 :C01C8A22: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-08-15 16:00:22.257728 :C01C8A2A: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-08-15 16:00:22.259678 :C01C8A32:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-15 22:00:02.068368 :C0387E85:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-15 22:00:02.062 2025-08-15 22:00:02.068369 :C0387E86:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18460 pso_num=29 pso_serial#=113 2025-08-15 22:00:02.068489 :C0387E8C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=18460 osp_idx=27 osp_ver=15922 osp_pg=0 (spawn #492473) 2025-08-15 22:00:02.068489 :C0387E8D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 22:00:02.068490 :C0387E8E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=249 time=3975871376 2025-08-15 22:00:02.068490 :C0387E8F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 22:00:02.068491 :C0387E90:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 22:00:02.068491 :C0387E91:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 22:00:02.068491 :C0387E92:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 22:00:02.068492 :C0387E93:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 22:00:02.068986 :C0387E94: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-08-15 22:00:02.074136 :C0387EB5: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-08-15 22:00:02.074177 :C0387EB8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-15 22:00:02.091984 :C03880D4: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-08-15 22:00:02.092265 :C03880DC: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-08-15 22:00:02.092479 :C03880E4: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-08-15 22:00:02.092695 :C03880EC: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-08-15 22:00:02.092884 :C03880F4: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-08-15 22:00:02.093064 :C03880FC: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-08-15 22:00:02.093229 :C0388104: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-08-15 22:00:02.093386 :C038810C: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-08-15 22:00:02.093602 :C0388114: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-08-15 22:00:02.093781 :C038811C: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-08-15 22:00:02.093933 :C0388124: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-08-15 22:00:02.094093 :C038812C: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-08-15 22:00:02.094251 :C0388134: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-08-15 22:00:02.094405 :C038813C: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-08-15 22:00:02.094557 :C0388144: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-08-15 22:00:02.094716 :C038814C: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-08-15 22:00:02.094867 :C0388154: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-08-15 22:00:02.095017 :C038815C: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-08-15 22:00:02.095636 :C0388164: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-08-15 22:00:02.096003 :C038816C: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-08-15 22:00:02.096253 :C0388174: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-08-15 22:00:02.096411 :C038817C: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-08-15 22:00:02.096646 :C0388184: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-08-15 22:00:02.096828 :C038818C: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-08-15 22:00:02.096987 :C0388194: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-08-15 22:00:02.124108 :C038819C: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-08-15 22:00:03.415416*:C03883D1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000325D3AD0 estart=1755266402 eid=21380752 ctx=0x0000000777968158 cwh=0x00000000571B0158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-15 22:00:03.415416*:C03883D2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000325D3AD0 curCtx=0x00000000570B1BF8 pubTabIdxCnt=0 2025-08-15 22:00:03.415416*:C03883D3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000325D3AD0 2025-08-15 22:00:03.415416*:C03883D4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000325D3AD0 2025-08-15 22:00:03.415416*:C03883D5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003264B6F0 estart=1755266401 eid=16777520 ctx=0x000000077B54EAC8 cwh=0x0000000032640068 cpu=1078125 buffg=61762 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-15 22:00:03.415416*:C03883D6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003264B6F0 curCtx=0x00000000570A7FD8 pubTabIdxCnt=0 2025-08-15 22:00:03.415416*:C03883D7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003264B6F0 2025-08-15 22:00:03.415416*:C03883D8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003264B6F0 2025-08-15 22:00:03.415416*:C03883D9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000325D3AD0 curCtx=0x00000000570B1BF8 xsc->flg4=65792 flags=1 2025-08-15 22:00:03.415416*:C03883DA:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000325D3AD0 2025-08-15 22:00:03.774279 :C0388417:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 6, ext 1, nex 2 2025-08-15 22:00:03.774287 :C0388418:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0080035f where: kdiwh22: kdifind 2025-08-15 22:00:03.774787*:C0388419:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003264B6F0 curCtx=0x00000000570A7FD8 xsc->flg4=65792 flags=2 2025-08-15 22:00:03.774787*:C038841A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003264B6F0 2025-08-15 22:00:03.780045 :C038841B: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-08-15 22:00:03.800411 :C0388453: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-08-15 22:00:03.802246 :C038845B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-15 22:00:03.802435 :C038845C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-15 22:00:03.803294 :C038845D: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-08-15 22:00:03.803382 :C0388460: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-08-15 22:00:03.804197 :C0388472: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-08-15 22:00:03.805716 :C038847A: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-08-15 22:00:03.806003 :C0388482: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-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-15 22:00:03.868544*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-15 22:00:03.937676 :C0388523: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-08-15 22:00:05.835850 :C0388872: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-08-15 22:00:05.835861 :C0388873:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0008.007.0000d1b6 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 18460, J003)