Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250712060009\orcl_j003_17004_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:36391M/63366M, Ph+PgF:42421M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 17004, image: ORACLE.EXE (J003) *** 2025-07-12 06:00:09.130 *** SESSION ID:(233.28641) 2025-07-12 06:00:09.130 *** 2025-07-12 06:00:09.130 Process diagnostic dump for ORACLE.EXE (J003), OS id=17004, pid: 29, proc_ser: 245, sid: 233, sess_ser: 28641 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17004 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12735, hash value=3903275747 Current Wait Stack: Not in wait; last wait ended 0.517868 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.517893 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=143 seq_num=144 snap_id=1 wait times: snap=0.000149 sec, exc=0.000149 sec, total=0.000149 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.460245 sec of elapsed time 1: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=142 seq_num=143 snap_id=1 wait times: snap=0.000221 sec, exc=0.000221 sec, total=0.000221 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 2: waited for 'log file sequential read' log#=0x28ff, block#=0x0, blocks=0x0 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.100952 sec of elapsed time 3: waited for 'log file sequential read' log#=0x28ff, block#=0x0, blocks=0x0 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000020 sec, exc=0.000020 sec, total=0.000020 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.496534 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x12800, blocks=0x800 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000403 sec, exc=0.000403 sec, total=0.000403 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x12000, blocks=0x800 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000291 sec, exc=0.000291 sec, total=0.000291 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000085 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0x11800, blocks=0x800 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000384 sec, exc=0.000384 sec, total=0.000384 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.083464 sec of elapsed time 7: waited for 'log file sequential read' log#=0x28ff, block#=0x800, blocks=0x800 wait_id=136 seq_num=137 snap_id=1 wait times: snap=0.000207 sec, exc=0.000207 sec, total=0.000207 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002614 sec of elapsed time 8: waited for 'log file sequential read' log#=0x28ff, block#=0x0, blocks=0x0 wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000128 sec of elapsed time 9: waited for 'log file sequential read' log#=0x28ff, block#=0x2, blocks=0x7fe wait_id=134 seq_num=135 snap_id=1 wait times: snap=0.000206 sec, exc=0.000206 sec, total=0.000206 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000150 sec of elapsed time Sampled Session History of session 233 serial 28641 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 06:00:02 - 06:00:08] not in wait at each sample [1 sample, 06:00:01] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-12 06:00:09.130 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 17004, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-12 00:03:30.152591 :B0C16CAF: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-12 00:03:30.152759 :B0C16CB8: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-12 00:03:30.153548 :B0C16CB9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:03:30.937987 :B0C16CC8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:03:30.929 2025-07-12 00:03:30.937989 :B0C16CC9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18388 pso_num=29 pso_serial#=228 2025-07-12 00:03:30.938128 :B0C16CCA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18388 (legacy spawn) 2025-07-12 00:03:30.944646 :B0C16CCB: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-12 00:03:30.944812 :B0C16CD0: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-12 00:03:30.945632 :B0C16CD5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:03:37.618692 :B0C16F57:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:03:37.613 2025-07-12 00:03:37.618695 :B0C16F58:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16212 pso_num=29 pso_serial#=229 2025-07-12 00:03:37.618833 :B0C16F59:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16212 osp_idx=27 osp_ver=14253 osp_pg=0 (spawn #376112) 2025-07-12 00:03:37.618834 :B0C16F5A: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-12 00:03:37.618834 :B0C16F5B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=140 time=959393517 2025-07-12 00:03:37.618835 :B0C16F5C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 00:03:37.618835 :B0C16F5D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 00:03:37.618835 :B0C16F5E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 00:03:37.618835 :B0C16F5F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 00:03:37.618836 :B0C16F60:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 00:03:37.619344 :B0C16F61: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-12 00:03:37.619390 :B0C16F67: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-12 00:03:37.627303 :B0C16F76:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:03:39.493276 :B0C1702B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:03:39.488 2025-07-12 00:03:39.493278 :B0C1702C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17004 pso_num=29 pso_serial#=230 2025-07-12 00:03:39.493423 :B0C1702D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17004 (legacy spawn) 2025-07-12 00:03:39.500044 :B0C1702E: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-12 00:03:39.500222 :B0C17034: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-12 00:03:39.501064 :B0C17038:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:03:39.510085 :B0C1703B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:03:39.504 2025-07-12 00:03:39.510087 :B0C1703C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12464 pso_num=29 pso_serial#=231 2025-07-12 00:03:39.510222 :B0C1703D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12464 (legacy spawn) 2025-07-12 00:03:39.516847 :B0C1703E: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-12 00:03:39.517023 :B0C17047: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-12 00:03:39.517867 :B0C17048:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:03:42.690245 :B0C17130:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:03:42.677 2025-07-12 00:03:42.690247 :B0C17131:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8928 pso_num=29 pso_serial#=232 2025-07-12 00:03:42.690390 :B0C17132:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=8928 (legacy spawn) 2025-07-12 00:03:42.697157 :B0C17133: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-12 00:03:42.697329 :B0C17138: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-12 00:03:42.698208 :B0C1713D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:03:42.707112 :B0C17140:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:03:42.692 2025-07-12 00:03:42.707115 :B0C17141:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15484 pso_num=29 pso_serial#=233 2025-07-12 00:03:42.707253 :B0C17142:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15484 (legacy spawn) 2025-07-12 00:03:42.714073 :B0C17143: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-12 00:03:42.714244 :B0C1714C: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-12 00:03:42.715184 :B0C1714D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:04:39.168080 :B0C183A5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:04:39.167 2025-07-12 00:04:39.168082 :B0C183A6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15316 pso_num=29 pso_serial#=234 2025-07-12 00:04:39.168225 :B0C183A7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15316 (legacy spawn) 2025-07-12 00:04:39.174649 :B0C183A8: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-12 00:04:39.174831 :B0C183B0: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-12 00:04:39.175631 :B0C183B2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:04:51.437823 :B0C18757:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:04:51.429 2025-07-12 00:04:51.437826 :B0C18758:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17932 pso_num=29 pso_serial#=235 2025-07-12 00:04:51.437966 :B0C18759:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17932 (legacy spawn) 2025-07-12 00:04:51.444454 :B0C1875A: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-12 00:04:51.444620 :B0C18763: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-12 00:04:51.445455 :B0C18764:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:04:51.991019 :B0C18773:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:04:51.976 2025-07-12 00:04:51.991021 :B0C18774:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=1220 pso_num=29 pso_serial#=236 2025-07-12 00:04:51.991155 :B0C18775:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=1220 (legacy spawn) 2025-07-12 00:04:51.997785 :B0C18776: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-12 00:04:51.997958 :B0C1877B: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-12 00:04:51.998813 :B0C18780:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:04:52.007744 :B0C18783:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:04:52.007 2025-07-12 00:04:52.007746 :B0C18784:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16852 pso_num=29 pso_serial#=237 2025-07-12 00:04:52.007896 :B0C18785:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16852 (legacy spawn) 2025-07-12 00:04:52.014701 :B0C18786: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-12 00:04:52.014863 :B0C1878F: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-12 00:04:52.015746 :B0C18790:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:04:55.182514 :B0C1888A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:04:55.168 2025-07-12 00:04:55.182516 :B0C1888B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17152 pso_num=29 pso_serial#=238 2025-07-12 00:04:55.182669 :B0C1888C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17152 (legacy spawn) 2025-07-12 00:04:55.189486 :B0C1888D: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-12 00:04:55.189694 :B0C18892: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-12 00:04:55.190540 :B0C18897:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:04:55.199627 :B0C1889A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:04:55.199 2025-07-12 00:04:55.199629 :B0C1889B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12272 pso_num=29 pso_serial#=239 2025-07-12 00:04:55.199766 :B0C1889C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12272 (legacy spawn) 2025-07-12 00:04:55.206601 :B0C1889D: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-12 00:04:55.206772 :B0C188A6: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-12 00:04:55.207719 :B0C188A7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:05:31.422016 :B0C19497:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:05:31.416 2025-07-12 00:05:31.422018 :B0C19498:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16944 pso_num=29 pso_serial#=240 2025-07-12 00:05:31.422183 :B0C19499:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16944 (legacy spawn) 2025-07-12 00:05:31.429212 :B0C1949A: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-12 00:05:31.429393 :B0C1949F: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-12 00:05:31.430296 :B0C194A4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:05:31.439327 :B0C194A7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:05:31.432 2025-07-12 00:05:31.439329 :B0C194A8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17060 pso_num=29 pso_serial#=241 2025-07-12 00:05:31.439485 :B0C194A9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17060 (legacy spawn) 2025-07-12 00:05:31.446402 :B0C194AA: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-12 00:05:31.446592 :B0C194B1: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-12 00:05:31.447455 :B0C194B4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:05:47.234842 :B0C19B2D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:05:47.220 2025-07-12 00:05:47.234844 :B0C19B2E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15292 pso_num=29 pso_serial#=242 2025-07-12 00:05:47.234981 :B0C19B2F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15292 (legacy spawn) 2025-07-12 00:05:47.241434 :B0C19B30: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-12 00:05:47.241600 :B0C19B39: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-12 00:05:47.242374 :B0C19B3A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 00:05:53.632134 :B0C19D34:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 00:05:53.624 2025-07-12 00:05:53.632137 :B0C19D35:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17808 pso_num=29 pso_serial#=243 2025-07-12 00:05:53.632274 :B0C19D36:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17808 (legacy spawn) 2025-07-12 00:05:53.638702 :B0C19D37: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-12 00:05:53.638881 :B0C19D40: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-12 00:05:53.639700 :B0C19D41:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 01:00:04.230625 :B0C5D6BE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 01:00:04.224 2025-07-12 01:00:04.230627 :B0C5D6BF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9112 pso_num=29 pso_serial#=244 2025-07-12 01:00:04.230819 :B0C5D6C0:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=9112 osp_idx=27 osp_ver=14268 osp_pg=0 (spawn #376242) 2025-07-12 01:00:04.230820 :B0C5D6C1: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-12 01:00:04.230820 :B0C5D6C2:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=141 time=962780126 2025-07-12 01:00:04.230820 :B0C5D6C3:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 01:00:04.230821 :B0C5D6C4:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 01:00:04.230822 :B0C5D6C5:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 01:00:04.230822 :B0C5D6C6:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 01:00:04.230822 :B0C5D6C7:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 01:00:04.231351 :B0C5D6C8: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-12 01:00:04.231979 :B0C5D6D2: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-12 01:00:04.248462 :B0C5D6E4: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-12 01:00:04.248618 :B0C5D6ED: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-12 01:00:34.324883 :B0C5E186:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-12 06:00:02.077297 :B0DD6D38:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-12 06:00:02.070 2025-07-12 06:00:02.077298 :B0DD6D39:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17004 pso_num=29 pso_serial#=245 2025-07-12 06:00:02.077447 :B0DD6D3F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17004 osp_idx=27 osp_ver=14269 osp_pg=0 (spawn #376933) 2025-07-12 06:00:02.077449 :B0DD6D40: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-12 06:00:02.077449 :B0DD6D41:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=142 time=980777970 2025-07-12 06:00:02.077450 :B0DD6D42:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 06:00:02.077450 :B0DD6D43:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 06:00:02.077451 :B0DD6D44:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 06:00:02.077452 :B0DD6D45:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 06:00:02.077452 :B0DD6D46:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 06:00:02.078056 :B0DD6D4F: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-12 06:00:02.083102 :B0DD6D78: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-12 06:00:02.083131 :B0DD6D7B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-12 06:00:02.100882 :B0DD6F6A: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-12 06:00:02.101111 :B0DD6F72: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-12 06:00:02.101282 :B0DD6F7A: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-12 06:00:02.101505 :B0DD6F82: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-12 06:00:02.101669 :B0DD6F8A: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-12 06:00:02.101821 :B0DD6F92: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-12 06:00:02.101967 :B0DD6F9A: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-12 06:00:02.102119 :B0DD6FA2: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-12 06:00:02.102268 :B0DD6FAA: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-12 06:00:02.102414 :B0DD6FB2: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-12 06:00:02.102562 :B0DD6FBA: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-12 06:00:02.102754 :B0DD6FC2: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-12 06:00:02.102998 :B0DD6FCA: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-12 06:00:02.103152 :B0DD6FD2: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-12 06:00:02.103301 :B0DD6FDA: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-12 06:00:02.103455 :B0DD6FE2: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-12 06:00:02.122899 :B0DD6FEA: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-12 06:00:03.757916*:B0DD748B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003264B6F0 estart=1752271201 eid=16777446 ctx=0x000000077B54EAC8 cwh=0x0000000032600518 cpu=1437500 buffg=78563 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-12 06:00:03.757916*:B0DD748C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003264B6F0 curCtx=0x000000004CE187D0 pubTabIdxCnt=0 2025-07-12 06:00:03.757916*:B0DD748D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003264B6F0 2025-07-12 06:00:03.757916*:B0DD748E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003264B6F0 2025-07-12 06:00:03.773545*:B0DD7499:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003264B6F0 curCtx=0x000000004CE187D0 xsc->flg4=65792 flags=2 2025-07-12 06:00:03.773545*:B0DD749A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003264B6F0 2025-07-12 06:00:03.779934 :B0DD749B: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-12 06:00:03.800037 :B0DD74AF: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-12 06:00:03.801980 :B0DD74B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-12 06:00:03.802160 :B0DD74B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-12 06:00:03.802992 :B0DD74B9: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-12 06:00:03.803083 :B0DD74BC: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-12 06:00:03.803902 :B0DD74CE: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-12 06:00:03.805577 :B0DD74D6: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-12 06:00:03.805851 :B0DD74DE: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-12 06:00:03.867294*: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-12 06:00:03.867294*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-12 06:00:03.867294*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-12 06:00:03.898538*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-12 06:00:03.898538*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-12 06:00:03.898538*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-12 06:00:03.898538*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-12 06:00:03.967170 :B0DD74E6: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-12 06:00:05.868543 :B0DD75B5: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-12 06:00:05.868553 :B0DD75B6:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x000a.010.0017272a 2025-07-12 06:00:08.613411 :B0DD7726: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-12 06:00:08.614876 :B0DD772E: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-12 06:00:08.623913 :B0DD7736:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 10, ext 1, nex 3 2025-07-12 06:00:08.623921 :B0DD7737:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008127be where: kduwh01: kdusru 2025-07-12 06:00:08.625837 :B0DD7738: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-12 06:00:08.651862 :B0DD7750:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 17004, J003)