Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250803180911\orcl_j000_19204_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:35775M/63366M, Ph+PgF:41408M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 19204, image: ORACLE.EXE (J000) *** 2025-08-03 18:09:11.981 *** SESSION ID:(193.32795) 2025-08-03 18:09:11.981 *** 2025-08-03 18:09:11.981 Process diagnostic dump for ORACLE.EXE (J000), OS id=19204, pid: 24, proc_ser: 102, sid: 193, sess_ser: 32795 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19204 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12864, hash value=83162448 Current Wait Stack: Not in wait; last wait ended 2.550280 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.550306 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.000156 sec, exc=0.000156 sec, total=0.000156 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.052263 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.000008 sec, exc=0.000008 sec, total=0.000008 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.041439 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.000157 sec, exc=0.000157 sec, total=0.000157 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.711854 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.025100 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.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.013112 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.000156 sec, exc=0.000156 sec, total=0.000156 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.052958 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.008551 sec, exc=0.008551 sec, total=0.008551 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007817 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.000120 sec, exc=0.000120 sec, total=0.000120 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.261402 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.004110 sec, exc=0.004110 sec, total=0.004110 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000176 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.000120 sec, exc=0.000120 sec, total=0.000120 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007870 sec of elapsed time Sampled Session History of session 193 serial 32795 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 18:09:08 - 18:09:11] not in wait at each sample [1 sample, 18:09:07] idle wait at each sample [session created at: 18:09:07] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-03 18:09:11.981 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 19204, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-03 18:08:37.222545 :BAE0A8E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.222590 :BAE0A8E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.222614 :BAE0A8E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.222642 :BAE0A8E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.222907 :BAE0A8E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.222937 :BAE0A8E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.222943 :BAE0A8E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.222968 :BAE0A8E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223076 :BAE0A8E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.223102 :BAE0A8E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223107 :BAE0A8EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.223132 :BAE0A8EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223233 :BAE0A8EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.223261 :BAE0A8ED:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223267 :BAE0A8EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.223293 :BAE0A8EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223394 :BAE0A8F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.223420 :BAE0A8F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223425 :BAE0A8F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.223450 :BAE0A8F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223551 :BAE0A8F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.223577 :BAE0A8F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223581 :BAE0A8F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.223606 :BAE0A8F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223707 :BAE0A8F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.223733 :BAE0A8F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.223765 :BAE0A8FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.224010 :BAE0A8FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.224047 :BAE0A8FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.235943 :BAE0A904:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.236017 :BAE0A907:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-03 18:08:37.236049 :BAE0A908:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-03 18:08:37.244010 :BAE0A90E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.244071 :BAE0A911:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-03 18:08:37.244103 :BAE0A912:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-03 18:08:37.246058 :BAE0A918:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.246126 :BAE0A919:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.246163 :BAE0A91A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.246208 :BAE0A91B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.246547 :BAE0A91C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.246583 :BAE0A91D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.247079 :BAE0A91E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.247112 :BAE0A91F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.247248 :BAE0A920:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.247278 :BAE0A921:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.247378 :BAE0A922:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.247407 :BAE0A923:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.247505 :BAE0A924:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.247534 :BAE0A925:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.247644 :BAE0A926:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.247672 :BAE0A927:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.247711 :BAE0A928:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.247954 :BAE0A929:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.247991 :BAE0A92C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.254242 :BAE0A932:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.254303 :BAE0A935:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-03 18:08:37.254335 :BAE0A936:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-03 18:08:37.256120 :BAE0A93C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.256163 :BAE0A93D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.256293 :BAE0A93E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.256322 :BAE0A93F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.256347 :BAE0A940:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.256579 :BAE0A941:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.256614 :BAE0A944:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.261139 :BAE0A94A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.261195 :BAE0A94D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-03 18:08:37.261225 :BAE0A94E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-03 18:08:37.261532 :BAE0A954:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-03 18:08:37.261857 :BAE0A955:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-03 18:08:37.261981 :BAE0A956:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.262198 :BAE0A957:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.262222 :BAE0A95A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.263889 :BAE0A960:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:08:37.264128 :BAE0A961:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.264159 :BAE0A964:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:08:37.265052 :BAE0A96A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:08:37.265176 :BAE0A973:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-03 18:09:07.352713 :BAE0B333:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-03 18:09:07.560286 :BAE0B375:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-03 18:09:07.555 2025-08-03 18:09:07.560288 :BAE0B376:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4196 pso_num=24 pso_serial#=101 2025-08-03 18:09:07.560424 :BAE0B377:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4196 osp_idx=22 osp_ver=666426 osp_pg=0 (spawn #451956) 2025-08-03 18:09:07.560425 :BAE0B378:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 18:09:07.560426 :BAE0B379:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=107 time=2925216861 2025-08-03 18:09:07.560426 :BAE0B37A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 18:09:07.560426 :BAE0B37B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 18:09:07.560427 :BAE0B37C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 18:09:07.560427 :BAE0B37D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 18:09:07.560427 :BAE0B37E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 18:09:07.560921 :BAE0B37F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.560967 :BAE0B385:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-03 18:09:07.565083 :BAE0B397:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-03 18:09:07.565135 :BAE0B398:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-03 18:09:07.565390 :BAE0B399:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-03 18:09:07.565405 :BAE0B39A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-03 18:09:07.565623 :BAE0B39B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-03 18:09:07.565636 :BAE0B39C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-03 18:09:07.565842 :BAE0B39D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-03 18:09:07.565855 :BAE0B39E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-03 18:09:07.566060 :BAE0B39F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-03 18:09:07.566072 :BAE0B3A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-03 18:09:07.566278 :BAE0B3A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-03 18:09:07.566290 :BAE0B3A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-03 18:09:07.568719 :BAE0B3D0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-03 18:09:07.657342 :BAE0B4BA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-03 18:09:07.649 2025-08-03 18:09:07.657346 :BAE0B4BB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19204 pso_num=24 pso_serial#=102 2025-08-03 18:09:07.657753 :BAE0B4BC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19204 osp_idx=22 osp_ver=666427 osp_pg=0 (spawn #451958) 2025-08-03 18:09:07.657755 :BAE0B4BD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 18:09:07.657757 :BAE0B4BE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=108 time=2925216954 2025-08-03 18:09:07.657757 :BAE0B4BF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 18:09:07.657759 :BAE0B4C0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 18:09:07.657760 :BAE0B4C1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 18:09:07.657760 :BAE0B4C2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 18:09:07.657761 :BAE0B4C3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 18:09:07.658591 :BAE0B4C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.663683 :BAE0B4E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.663724 :BAE0B4E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-03 18:09:07.680942 :BAE0B4FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.681145 :BAE0B504:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.681310 :BAE0B50C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.681461 :BAE0B514:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.681619 :BAE0B51C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.681776 :BAE0B524:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.681928 :BAE0B52C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.682078 :BAE0B534:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.682222 :BAE0B53C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.682362 :BAE0B544:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.682511 :BAE0B54C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.682655 :BAE0B554:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.682802 :BAE0B55C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.683006 :BAE0B564:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.683155 :BAE0B56C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.683469 :BAE0B574:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.683780 :BAE0B57C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:07.702184 :BAE0B584:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:09.118497*:BAE0B644:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026E73AD0 estart=1754215749 eid=21064158 ctx=0x0000000777968158 cwh=0x00000000270A0068 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-03 18:09:09.118497*:BAE0B645:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026E73AD0 curCtx=0x00000000271619A8 pubTabIdxCnt=0 2025-08-03 18:09:09.118497*:BAE0B646:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026E73AD0 2025-08-03 18:09:09.118497*:BAE0B647:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026E73AD0 2025-08-03 18:09:09.118497*:BAE0B648:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026EEB820 estart=1754215747 eid=16777499 ctx=0x000000077B54EAC8 cwh=0x000000000FD80608 cpu=1218750 buffg=67656 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-03 18:09:09.118497*:BAE0B649:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026EEB820 curCtx=0x0000000027169FF0 pubTabIdxCnt=0 2025-08-03 18:09:09.118497*:BAE0B64A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026EEB820 2025-08-03 18:09:09.118497*:BAE0B64B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026EEB820 2025-08-03 18:09:09.118497*:BAE0B64C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026E73AD0 curCtx=0x00000000271619A8 xsc->flg4=65792 flags=1 2025-08-03 18:09:09.118497*:BAE0B64D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026E73AD0 2025-08-03 18:09:09.352881*:BAE0B6F6:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026EEB820 curCtx=0x0000000027169FF0 xsc->flg4=65792 flags=2 2025-08-03 18:09:09.352881*:BAE0B6F7:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026EEB820 2025-08-03 18:09:09.362828 :BAE0B6F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:09.383150 :BAE0B732:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:09.385311 :BAE0B750:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-03 18:09:09.385657 :BAE0B751:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-03 18:09:09.386723 :BAE0B752:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:09.386810 :BAE0B755:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:09.387672 :BAE0B767:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:09.389220 :BAE0B76F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:09.389500 :BAE0B777:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:09.431005*: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-03 18:09:09.431005*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-03 18:09:09.431005*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-03 18:09:09.431005*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-03 18:09:09.431005*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-03 18:09:09.431005*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-03 18:09:09.431005*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-03 18:09:09.506147 :BAE0B78B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:11.411576 :BAE0B83A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-03 18:09:11.411591 :BAE0B83B:db_trace:ktur.c@3079:ktuabt(): [10444:24:193] ABORT TRANSACTION - xid: 0x0006.017.0000c807 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 19204, J000)