Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250814220009\orcl_j003_14192_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:35472M/63366M, Ph+PgF:40786M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 14192, image: ORACLE.EXE (J003) *** 2025-08-14 22:00:09.294 *** SESSION ID:(233.31881) 2025-08-14 22:00:09.294 *** 2025-08-14 22:00:09.294 Process diagnostic dump for ORACLE.EXE (J003), OS id=14192, pid: 29, proc_ser: 66, sid: 233, sess_ser: 31881 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14192 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12914, hash value=2179050657 Current Wait Stack: Not in wait; last wait ended 1.454501 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.454526 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000216 sec, exc=0.000216 sec, total=0.000216 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 1: waited for 'log file sequential read' log#=0x29c3, block#=0x0, blocks=0x0 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.057992 sec of elapsed time 2: waited for 'log file sequential read' log#=0x29c3, block#=0x0, blocks=0x0 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000018 sec, exc=0.000018 sec, total=0.000018 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.543224 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x6000, blocks=0x800 wait_id=136 seq_num=137 snap_id=1 wait times: snap=0.000290 sec, exc=0.000290 sec, total=0.000290 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000078 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x5800, blocks=0x800 wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000319 sec, exc=0.000319 sec, total=0.000319 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.017717 sec of elapsed time 5: waited for 'log file sequential read' log#=0x29c3, block#=0x800, blocks=0x800 wait_id=134 seq_num=135 snap_id=1 wait times: snap=0.000211 sec, exc=0.000211 sec, total=0.000211 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002639 sec of elapsed time 6: waited for 'log file sequential read' log#=0x29c3, block#=0x0, blocks=0x0 wait_id=133 seq_num=134 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000124 sec of elapsed time 7: waited for 'log file sequential read' log#=0x29c3, block#=0x2, blocks=0x7fe wait_id=132 seq_num=133 snap_id=1 wait times: snap=0.000196 sec, exc=0.000196 sec, total=0.000196 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000160 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=131 seq_num=132 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000003 sec of elapsed time 9: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x0, filetype=0x3 wait_id=130 seq_num=131 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.000132 sec of elapsed time Sampled Session History of session 233 serial 31881 --------------------------------------------------- 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, 22:00:02 - 22:00:08] 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-14 22:00:09.294 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 14192, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-14 00:04:51.682228 :BF626E0C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14192 pso_num=29 pso_serial#=58 2025-08-14 00:04:51.682363 :BF626E0D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14192 osp_idx=27 osp_ver=15867 osp_pg=0 (spawn #486110) 2025-08-14 00:04:51.682364 :BF626E0E: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-14 00:04:51.682364 :BF626E0F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=239 time=3810560986 2025-08-14 00:04:51.682365 :BF626E10:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 00:04:51.682365 :BF626E11:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 00:04:51.682366 :BF626E12:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 00:04:51.682366 :BF626E13:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 00:04:51.682366 :BF626E14:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 00:04:51.682888 :BF626E15: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-14 00:05:21.777271 :BF627CF5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-14 00:05:51.781758 :BF6286B4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-14 00:05:51.770 2025-08-14 00:05:51.781760 :BF6286B5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20192 pso_num=29 pso_serial#=59 2025-08-14 00:05:51.781878 :BF6286B6:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20192 osp_idx=27 osp_ver=15868 osp_pg=0 (spawn #486112) 2025-08-14 00:05:51.781878 :BF6286B7: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-14 00:05:51.781879 :BF6286B8:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=240 time=3810621079 2025-08-14 00:05:51.781879 :BF6286B9:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 00:05:51.781880 :BF6286BA:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 00:05:51.781880 :BF6286BB:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 00:05:51.781880 :BF6286BC:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 00:05:51.781881 :BF6286BD:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 00:05:51.782385 :BF6286BE: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-14 00:06:21.877140 :BF6291EA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-14 01:00:01.841547 :BF66B27E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-14 01:00:01.828 2025-08-14 01:00:01.841550 :BF66B27F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19460 pso_num=29 pso_serial#=60 2025-08-14 01:00:01.841715 :BF66B280:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19460 (legacy spawn) 2025-08-14 01:00:01.848171 :BF66B281: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-14 01:00:01.848351 :BF66B28A: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-14 01:00:01.849157 :BF66B28B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-14 01:00:10.170702 :BF66B4FE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-14 01:00:10.156 2025-08-14 01:00:10.170704 :BF66B4FF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20152 pso_num=29 pso_serial#=61 2025-08-14 01:00:10.170846 :BF66B500:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20152 (legacy spawn) 2025-08-14 01:00:10.177287 :BF66B501: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-14 01:00:10.177455 :BF66B507: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-14 01:00:10.178234 :BF66B50B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-14 01:00:10.186847 :BF66B50E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-14 01:00:10.171 2025-08-14 01:00:10.186849 :BF66B50F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20264 pso_num=29 pso_serial#=62 2025-08-14 01:00:10.187014 :BF66B510:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20264 (legacy spawn) 2025-08-14 01:00:10.204810 :BF66B511: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-14 01:00:10.204994 :BF66B519: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-14 01:00:10.205848 :BF66B51B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-14 01:00:12.165329 :BF66B5BF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-14 01:00:12.160 2025-08-14 01:00:12.165332 :BF66B5C0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20656 pso_num=29 pso_serial#=63 2025-08-14 01:00:12.165466 :BF66B5C1:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20656 osp_idx=27 osp_ver=15872 osp_pg=0 (spawn #486236) 2025-08-14 01:00:12.165466 :BF66B5C2: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-14 01:00:12.165467 :BF66B5C3:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=241 time=3813881470 2025-08-14 01:00:12.165467 :BF66B5C4:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 01:00:12.165468 :BF66B5C5:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 01:00:12.165468 :BF66B5C6:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 01:00:12.165468 :BF66B5C7:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 01:00:12.165469 :BF66B5C8:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 01:00:12.165976 :BF66B5C9: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-14 01:00:12.166025 :BF66B5CF: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-14 01:00:12.170447 :BF66B5E2: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-14 01:00:12.194167 :BF66B5EA: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-14 01:00:12.200890 :BF66B5F2: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-14 01:00:12.200904 :BF66B5F3:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x000a.010.00177be0 2025-08-14 01:00:12.201480 :BF66B5FB:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 10, ext 1, nex 3 2025-08-14 01:00:12.201486 :BF66B5FC:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00811d5f where: kdiwh187: kdisprobe 2025-08-14 01:00:12.210172 :BF66B5FD: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-14 01:00:12.241839 :BF66B605: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-14 01:00:12.245494 :BF66B60D: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-14 01:00:12.245811 :BF66B615: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-14 01:00:12.249517 :BF66B61D: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-14 01:00:12.252125 :BF66B625: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-14 01:00:12.253802 :BF66B639: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-14 01:00:12.254917 :BF66B641: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-14 01:00:12.258549 :BF66B649: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-14 01:00:12.282002 :BF66B651:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 1, nex 3 2025-08-14 01:00:12.282011 :BF66B652:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00812c92 where: ktsphwh45_: ktsptrm_bmb 2025-08-14 01:00:12.308194 :BF66B653:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 2, nex 4 2025-08-14 01:00:12.308202 :BF66B654:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00812cea where: ktsphwh45_: ktsptrm_bmb 2025-08-14 01:00:12.871327 :BF66B656: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-14 01:00:12.871341 :BF66B657:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0007.020.0000c9e5 2025-08-14 01:00:12.871655 :BF66B65A:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 3, nex 5 2025-08-14 01:00:12.871661 :BF66B65B:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00818065 where: ktspbwh1: ktspfsrch 2025-08-14 01:00:13.472973 :BF66B704: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-14 01:00:13.516191 :BF66B70C: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-14 01:00:13.516480 :BF66B714: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-14 01:00:13.516755 :BF66B71C: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-14 01:00:13.516771 :BF66B71F: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-14 01:00:13.516829 :BF66B72B: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-14 01:00:13.519509 :BF66B739:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-14 01:00:20.648670 :BF66B999:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-14 01:00:20.640 2025-08-14 01:00:20.648672 :BF66B99A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16496 pso_num=29 pso_serial#=64 2025-08-14 01:00:20.648815 :BF66B99B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16496 (legacy spawn) 2025-08-14 01:00:20.655339 :BF66B99C: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-14 01:00:20.655525 :BF66B9A1: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-14 01:00:20.656322 :BF66B9A6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-14 16:00:12.783242 :BFAC3B4F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-14 16:00:12.768 2025-08-14 16:00:12.783244 :BFAC3B50:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17716 pso_num=29 pso_serial#=65 2025-08-14 16:00:12.783370 :BFAC3B51:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17716 osp_idx=27 osp_ver=15874 osp_pg=0 (spawn #488313) 2025-08-14 16:00:12.783371 :BFAC3B52: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-14 16:00:12.783371 :BFAC3B53:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=242 time=3867882079 2025-08-14 16:00:12.783372 :BFAC3B54:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 16:00:12.783372 :BFAC3B55:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 16:00:12.783373 :BFAC3B56:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 16:00:12.783373 :BFAC3B57:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 16:00:12.783374 :BFAC3B58:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 16:00:12.783847 :BFAC3B59: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-14 16:00:12.783895 :BFAC3B5F: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-14 16:00:12.791737 :BFAC3B6D: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-14 16:00:12.792781 :BFAC3B75: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-14 16:00:12.793260 :BFAC3B7D: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-14 16:00:12.793694 :BFAC3B85: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-14 16:00:12.794199 :BFAC3B8D: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-14 16:00:12.796661 :BFAC3B95: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-14 16:00:12.797717 :BFAC3B9D: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-14 16:00:12.847939 :BFAC3BA5: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-14 16:00:12.848834 :BFAC3BAD: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-14 16:00:12.849071 :BFAC3BB5: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-14 16:00:12.849284 :BFAC3BBD: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-14 16:00:12.851149 :BFAC3BC5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-14 22:00:02.037281 :BFC81BD9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-14 22:00:02.026 2025-08-14 22:00:02.037282 :BFC81BDA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14192 pso_num=29 pso_serial#=66 2025-08-14 22:00:02.037414 :BFC81BDC:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14192 osp_idx=27 osp_ver=15875 osp_pg=0 (spawn #489145) 2025-08-14 22:00:02.037415 :BFC81BDE: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-14 22:00:02.037415 :BFC81BDF:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=243 time=3889471329 2025-08-14 22:00:02.037416 :BFC81BE0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 22:00:02.037416 :BFC81BE1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 22:00:02.037417 :BFC81BE2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 22:00:02.037417 :BFC81BE3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 22:00:02.037417 :BFC81BE4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 22:00:02.037959 :BFC81BEC: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-14 22:00:02.042961 :BFC81C05: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-14 22:00:02.043027 :BFC81C10:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-14 22:00:02.059994 :BFC81DD0: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-14 22:00:02.060183 :BFC81DD8: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-14 22:00:02.060349 :BFC81DE0: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-14 22:00:02.060510 :BFC81DE8: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-14 22:00:02.060667 :BFC81DF0: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-14 22:00:02.060852 :BFC81DF8: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-14 22:00:02.061070 :BFC81E00: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-14 22:00:02.061295 :BFC81E08: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-14 22:00:02.061456 :BFC81E10: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-14 22:00:02.061620 :BFC81E18: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-14 22:00:02.061793 :BFC81E20: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-14 22:00:02.061952 :BFC81E28: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-14 22:00:02.062108 :BFC81E30: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-14 22:00:02.062338 :BFC81E38: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-14 22:00:02.062509 :BFC81E40: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-14 22:00:02.062663 :BFC81E48: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-14 22:00:02.062827 :BFC81E50: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-14 22:00:02.062982 :BFC81E58: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-14 22:00:02.063132 :BFC81E60: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-14 22:00:02.063292 :BFC81E68: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-14 22:00:02.063448 :BFC81E70: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-14 22:00:02.063605 :BFC81E78: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-14 22:00:02.063759 :BFC81E80: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-14 22:00:02.064375 :BFC81E88: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-14 22:00:02.064606 :BFC81E90: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-14 22:00:02.093202 :BFC81E98: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-14 22:00:03.542164*:BFC820D5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031233AD0 estart=1755180003 eid=21367393 ctx=0x0000000777968158 cwh=0x00000000573B0158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-14 22:00:03.542164*:BFC820D6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031233AD0 curCtx=0x00000000572B1BF8 pubTabIdxCnt=0 2025-08-14 22:00:03.542164*:BFC820D7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031233AD0 2025-08-14 22:00:03.542164*:BFC820D8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031233AD0 2025-08-14 22:00:03.542164*:BFC820D9:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000312AB6F0 estart=1755180001 eid=16777519 ctx=0x000000077B54EAC8 cwh=0x00000000312A0068 cpu=1265625 buffg=68903 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-14 22:00:03.542164*:BFC820DA:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000312AB6F0 curCtx=0x00000000570A7FD8 pubTabIdxCnt=0 2025-08-14 22:00:03.542164*:BFC820DB:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000312AB6F0 2025-08-14 22:00:03.542164*:BFC820DC:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000312AB6F0 2025-08-14 22:00:03.542164*:BFC820DD:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031233AD0 curCtx=0x00000000572B1BF8 xsc->flg4=65792 flags=1 2025-08-14 22:00:03.542164*:BFC820DE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031233AD0 2025-08-14 22:00:03.745297*:BFC82151:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000312AB6F0 curCtx=0x00000000570A7FD8 xsc->flg4=65792 flags=2 2025-08-14 22:00:03.745297*:BFC82152:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000312AB6F0 2025-08-14 22:00:03.760465 :BFC82153: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-14 22:00:03.780685 :BFC8215E: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-14 22:00:03.782810 :BFC82166:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.783010 :BFC82167:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.784223 :BFC82168: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-14 22:00:03.784322 :BFC8216B: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-14 22:00:03.785224 :BFC8217D: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-14 22:00:03.786676 :BFC82185: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-14 22:00:03.786942 :BFC8218D: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-14 22:00:03.839040*: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-14 22:00:03.839040*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-14 22:00:03.839040*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-14 22:00:03.854665*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-14 22:00:03.854665*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-14 22:00:03.854665*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-14 22:00:03.854665*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-14 22:00:03.927356 :BFC82225: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-14 22:00:05.865238 :BFC823BD: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-14 22:00:05.865250 :BFC823BE:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0007.015.0000c9f5 2025-08-14 22:00:08.303376 :BFC8258E: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-14 22:00:08.307609 :BFC82596: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-14 22:00:08.318081 :BFC8259E: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-14 22:00:08.346352 :BFC825A6: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: 14192, J003)