Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250427140924\orcl_j000_1112_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:37593M/63366M, Ph+PgF:45289M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 1112, image: ORACLE.EXE (J000) *** 2025-04-27 14:09:24.189 *** SESSION ID:(177.37019) 2025-04-27 14:09:24.189 *** 2025-04-27 14:09:24.189 Process diagnostic dump for ORACLE.EXE (J000), OS id=1112, pid: 22, proc_ser: 196, sid: 177, sess_ser: 37019 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 1112 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12344, hash value=3948969051 Current Wait Stack: Not in wait; last wait ended 2.792945 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.792970 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.000150 sec, exc=0.000150 sec, total=0.000150 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.044107 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.754058 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=38 seq_num=39 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.025070 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.012676 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000111 sec, exc=0.000111 sec, total=0.000111 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049662 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.008939 sec, exc=0.008939 sec, total=0.008939 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007746 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.000113 sec, exc=0.000113 sec, total=0.000113 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.261194 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.006081 sec, exc=0.006081 sec, total=0.006081 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000176 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.000121 sec, exc=0.000121 sec, total=0.000121 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007861 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000056 sec of elapsed time Sampled Session History of session 177 serial 37019 --------------------------------------------------- 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, 14:09:20 - 14:09:23] not in wait at each sample [1 sample, 14:09:19] idle wait at each sample [session created at: 14:09:19] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-27 14:09:24.189 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 1112, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-27 14:08:22.724953 :8F7D30F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.725373 :8F7D30F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.725613 :8F7D30F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.725650 :8F7D30F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.731169 :8F7D30FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.731225 :8F7D3101:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-04-27 14:08:22.731255 :8F7D3102:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-04-27 14:08:22.733180 :8F7D3108:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.733224 :8F7D3109:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.733851 :8F7D310A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.734119 :8F7D310B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.734153 :8F7D310E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.741603 :8F7D3114:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.741668 :8F7D3117:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-04-27 14:08:22.741699 :8F7D3118:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-04-27 14:08:22.743549 :8F7D311E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.743593 :8F7D311F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.743951 :8F7D3120:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.743988 :8F7D3121:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.744202 :8F7D3122:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.744233 :8F7D3123:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.744335 :8F7D3124:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.744608 :8F7D3125:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.744641 :8F7D3128:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.752740 :8F7D312E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.752803 :8F7D3131:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-04-27 14:08:22.752836 :8F7D3132:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-04-27 14:08:22.754734 :8F7D3138:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.754778 :8F7D3139:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.754801 :8F7D313A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.754828 :8F7D313B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.755140 :8F7D313C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.755175 :8F7D313D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.755665 :8F7D313E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.755698 :8F7D313F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.755823 :8F7D3140:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.755852 :8F7D3141:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.755946 :8F7D3142:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.755973 :8F7D3143:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.756067 :8F7D3144:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.756095 :8F7D3145:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.756197 :8F7D3146:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.756225 :8F7D3147:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.756262 :8F7D3148:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.756513 :8F7D3149:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.756553 :8F7D314C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.763013 :8F7D3152:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.763083 :8F7D3155:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-04-27 14:08:22.763120 :8F7D3156:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-04-27 14:08:22.763429 :8F7D315C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-27 14:08:22.763763 :8F7D315D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-27 14:08:22.763882 :8F7D315E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.764111 :8F7D315F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.764136 :8F7D3162:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.765794 :8F7D3168:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:08:22.766044 :8F7D3169:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.766075 :8F7D316C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:08:22.766982 :8F7D3172:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:22.767103 :8F7D317B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-04-27 14:08:52.862513 :8F7D3B6E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-27 14:08:56.803238 :8F7D3CB3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-27 14:08:56.789 2025-04-27 14:08:56.803240 :8F7D3CB4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10356 pso_num=22 pso_serial#=189 2025-04-27 14:08:56.803379 :8F7D3CB5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10356 (legacy spawn) 2025-04-27 14:08:56.822841 :8F7D3CC1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:56.823008 :8F7D3CC6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-04-27 14:08:56.823851 :8F7D3CCB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-27 14:08:56.832461 :8F7D3CCE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-27 14:08:56.820 2025-04-27 14:08:56.832464 :8F7D3CCF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11204 pso_num=22 pso_serial#=190 2025-04-27 14:08:56.832593 :8F7D3CD0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11204 (legacy spawn) 2025-04-27 14:08:56.839002 :8F7D3CD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:08:56.839177 :8F7D3CDA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-04-27 14:08:56.839954 :8F7D3CDB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-27 14:09:00.072570 :8F7D3DDB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-27 14:09:00.059 2025-04-27 14:09:00.072572 :8F7D3DDC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4728 pso_num=22 pso_serial#=191 2025-04-27 14:09:00.072713 :8F7D3DDD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4728 (legacy spawn) 2025-04-27 14:09:00.079308 :8F7D3DDE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:00.079489 :8F7D3DE3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-04-27 14:09:00.080369 :8F7D3DE8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-27 14:09:00.089100 :8F7D3DEB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-27 14:09:00.074 2025-04-27 14:09:00.089102 :8F7D3DEC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10952 pso_num=22 pso_serial#=192 2025-04-27 14:09:00.089236 :8F7D3DED:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10952 (legacy spawn) 2025-04-27 14:09:00.095756 :8F7D3DEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:00.095930 :8F7D3DF7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-04-27 14:09:00.096763 :8F7D3DF8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-27 14:09:02.544433 :8F7D3EA9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-27 14:09:02.532 2025-04-27 14:09:02.544435 :8F7D3EAA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1880 pso_num=22 pso_serial#=193 2025-04-27 14:09:02.544575 :8F7D3EAB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=1880 (legacy spawn) 2025-04-27 14:09:02.550993 :8F7D3EAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:02.551165 :8F7D3EB1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-04-27 14:09:02.551951 :8F7D3EB6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-27 14:09:16.055216 :8F7D4314:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-27 14:09:16.046 2025-04-27 14:09:16.055218 :8F7D4315:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10796 pso_num=22 pso_serial#=194 2025-04-27 14:09:16.055358 :8F7D4316:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10796 (legacy spawn) 2025-04-27 14:09:16.061757 :8F7D4317:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:16.061928 :8F7D4320:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-04-27 14:09:16.062710 :8F7D4321:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-27 14:09:19.545098 :8F7D4443:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-27 14:09:19.540 2025-04-27 14:09:19.545101 :8F7D4444:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7888 pso_num=22 pso_serial#=195 2025-04-27 14:09:19.545230 :8F7D4445:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7888 osp_idx=0 osp_ver=128970 osp_pg=0 (spawn #123892) 2025-04-27 14:09:19.545231 :8F7D4446:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 14:09:19.545232 :8F7D4447:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=168 time=3033761343 2025-04-27 14:09:19.545232 :8F7D4448:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 14:09:19.545232 :8F7D4449:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 14:09:19.545232 :8F7D444A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 14:09:19.545233 :8F7D444B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 14:09:19.545233 :8F7D444C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 14:09:19.545796 :8F7D444D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.545841 :8F7D4453:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-04-27 14:09:19.566560 :8F7D44E8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-04-27 14:09:19.647473 :8F7D45CE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 04-27 14:09:19.634 2025-04-27 14:09:19.647475 :8F7D45CF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1112 pso_num=22 pso_serial#=196 2025-04-27 14:09:19.647784 :8F7D45D0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1112 osp_idx=0 osp_ver=128971 osp_pg=0 (spawn #123895) 2025-04-27 14:09:19.647785 :8F7D45D1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 14:09:19.647785 :8F7D45D2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=169 time=3033761437 2025-04-27 14:09:19.647786 :8F7D45D3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 14:09:19.647786 :8F7D45D4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 14:09:19.647786 :8F7D45D5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 14:09:19.647787 :8F7D45D6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 14:09:19.647787 :8F7D45D7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 14:09:19.648509 :8F7D45D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.653622 :8F7D45F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.653662 :8F7D45FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-04-27 14:09:19.670696 :8F7D4626:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.670895 :8F7D462E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.671055 :8F7D4636:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.671207 :8F7D463E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.671361 :8F7D4646:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.671509 :8F7D464E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.671655 :8F7D4656:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.671804 :8F7D465E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.671945 :8F7D4666:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.672085 :8F7D466E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.672235 :8F7D4676:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.672375 :8F7D467E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.672514 :8F7D4686:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.672680 :8F7D468E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.672854 :8F7D4696:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.673203 :8F7D469E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.673474 :8F7D46A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.691889 :8F7D46AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:21.141407*:8F7D476D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000027373AD0 estart=1745734160 eid=17876449 ctx=0x0000000777968158 cwh=0x00000000299A0068 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-04-27 14:09:21.141407*:8F7D476E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000027373AD0 curCtx=0x0000000029A619A8 pubTabIdxCnt=0 2025-04-27 14:09:21.141407*:8F7D476F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000027373AD0 2025-04-27 14:09:21.141407*:8F7D4770:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000027373AD0 2025-04-27 14:09:21.141407*:8F7D4771:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000273EB820 estart=1745734159 eid=16777288 ctx=0x000000077B54EAC8 cwh=0x000000000FD00608 cpu=1265625 buffg=69821 interb=204800 r=25 rb=204800 w=0 wb=0 2025-04-27 14:09:21.141407*:8F7D4772:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000273EB820 curCtx=0x0000000029A69FF0 pubTabIdxCnt=0 2025-04-27 14:09:21.141407*:8F7D4773:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000273EB820 2025-04-27 14:09:21.141407*:8F7D4774:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000273EB820 2025-04-27 14:09:21.141407*:8F7D4775:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000027373AD0 curCtx=0x0000000029A619A8 xsc->flg4=65792 flags=1 2025-04-27 14:09:21.141407*:8F7D4776:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000027373AD0 2025-04-27 14:09:21.328913*:8F7D480C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000273EB820 curCtx=0x0000000029A69FF0 xsc->flg4=65792 flags=2 2025-04-27 14:09:21.328913*:8F7D480D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000273EB820 2025-04-27 14:09:21.340306 :8F7D480E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:21.359978 :8F7D481F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:21.361860 :8F7D482F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-04-27 14:09:21.362068 :8F7D4830:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-04-27 14:09:21.362958 :8F7D4831:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:21.363046 :8F7D4834:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:21.363958 :8F7D4846:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:21.365477 :8F7D484E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:21.365760 :8F7D4856:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:21.407179*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-04-27 14:09:21.407179*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-04-27 14:09:21.407179*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-04-27 14:09:21.407179*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-04-27 14:09:21.407179*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-04-27 14:09:21.407179*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-04-27 14:09:21.407179*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-04-27 14:09:21.479378 :8F7D48DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:23.343389 :8F7D49B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:23.343400 :8F7D49B9:db_trace:ktur.c@3079:ktuabt(): [10444:22:177] ABORT TRANSACTION - xid: 0x000a.015.001663cf ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 1112, J000)