Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250503220929\orcl_j000_12148_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250503220929\orcl_j000_12148_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:37567M/63366M, Ph+PgF:45090M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 12148, image: ORACLE.EXE (J000) *** 2025-05-03 22:09:29.267 *** SESSION ID:(193.50635) 2025-05-03 22:09:29.267 *** 2025-05-03 22:09:29.267 Process diagnostic dump for ORACLE.EXE (J000), OS id=12148, pid: 24, proc_ser: 220, sid: 193, sess_ser: 50635 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12148 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12374, hash value=3865644696 Current Wait Stack: Not in wait; last wait ended 0.706725 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.706750 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=168 seq_num=169 snap_id=1 wait times: snap=0.000236 sec, exc=0.000236 sec, total=0.000236 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#=0x276d, block#=0x0, blocks=0x0 wait_id=167 seq_num=168 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.023610 sec of elapsed time 2: waited for 'log file sequential read' log#=0x276d, block#=0x0, blocks=0x0 wait_id=166 seq_num=167 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.133624 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x3000, blocks=0x800 wait_id=165 seq_num=166 snap_id=1 wait times: snap=0.000275 sec, exc=0.000275 sec, total=0.000275 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x2800, blocks=0x800 wait_id=164 seq_num=165 snap_id=1 wait times: snap=0.000265 sec, exc=0.000265 sec, total=0.000265 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000022 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x2000, blocks=0x800 wait_id=163 seq_num=164 snap_id=1 wait times: snap=0.000295 sec, exc=0.000295 sec, total=0.000295 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.251743 sec of elapsed time 6: waited for 'log file sequential read' log#=0x276d, block#=0x800, blocks=0x800 wait_id=162 seq_num=163 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.137801 sec of elapsed time 7: waited for 'log file sequential read' log#=0x276d, block#=0x0, blocks=0x0 wait_id=161 seq_num=162 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000123 sec of elapsed time 8: waited for 'log file sequential read' log#=0x276d, block#=0x2, blocks=0x7fe wait_id=160 seq_num=161 snap_id=1 wait times: snap=0.000219 sec, exc=0.000219 sec, total=0.000219 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000153 sec of elapsed time 9: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=159 seq_num=160 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000003 sec of elapsed time Sampled Session History of session 193 serial 50635 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 22:09:23 - 22:09:28] not in wait at each sample [12 samples, 22:09:10 - 22:09:22] idle wait at each sample [session created at: 22:09:10] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-03 22:09:29.267 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 12148, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-03 22:07:03.882854 :924CD767: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-05-03 22:07:03.883030 :924CD770: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-05-03 22:07:03.883974 :924CD771:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:07:04.158279 :924CD780:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:07:04.150 2025-05-03 22:07:04.158282 :924CD781:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11336 pso_num=24 pso_serial#=207 2025-05-03 22:07:04.158417 :924CD782:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11336 (legacy spawn) 2025-05-03 22:07:04.164873 :924CD783: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-05-03 22:07:04.165090 :924CD78C: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-05-03 22:07:04.165959 :924CD78D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:07:10.603027 :924CD9B7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:07:10.597 2025-05-03 22:07:10.603029 :924CD9B8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11576 pso_num=24 pso_serial#=208 2025-05-03 22:07:10.603170 :924CD9B9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11576 osp_idx=22 osp_ver=220272 osp_pg=0 (spawn #145350) 2025-05-03 22:07:10.603170 :924CD9BA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 22:07:10.603171 :924CD9BB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=216 time=3580807765 2025-05-03 22:07:10.603171 :924CD9BC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 22:07:10.603172 :924CD9BD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 22:07:10.603172 :924CD9BE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 22:07:10.603172 :924CD9BF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 22:07:10.603173 :924CD9C0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 22:07:10.603662 :924CD9C1: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-05-03 22:07:10.604322 :924CD9D6: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-05-03 22:07:10.611847 :924CD9F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-03 22:07:10.612204 :924CD9F3: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-05-03 22:07:10.612238 :924CD9F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-03 22:07:10.613071 :924CD9FC: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-05-03 22:07:10.613194 :924CDA05: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-05-03 22:07:40.721345 :924CE403:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:07:56.405324 :924CE902:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:07:56.391 2025-05-03 22:07:56.405326 :924CE903:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1248 pso_num=24 pso_serial#=209 2025-05-03 22:07:56.405456 :924CE904:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=1248 (legacy spawn) 2025-05-03 22:07:56.411904 :924CE905: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-05-03 22:07:56.412085 :924CE90A: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-05-03 22:07:56.412921 :924CE90F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:07:56.421566 :924CE912:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:07:56.406 2025-05-03 22:07:56.421568 :924CE913:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=580 pso_num=24 pso_serial#=210 2025-05-03 22:07:56.421746 :924CE914:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=580 (legacy spawn) 2025-05-03 22:07:56.428276 :924CE915: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-05-03 22:07:56.428461 :924CE91E: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-05-03 22:07:56.429241 :924CE91F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:07:59.622108 :924CEA18:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:07:59.614 2025-05-03 22:07:59.622111 :924CEA19:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6556 pso_num=24 pso_serial#=211 2025-05-03 22:07:59.622246 :924CEA1A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6556 (legacy spawn) 2025-05-03 22:07:59.628673 :924CEA1B: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-05-03 22:07:59.628852 :924CEA24: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-05-03 22:07:59.629690 :924CEA25:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:07:59.638461 :924CEA28:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:07:59.630 2025-05-03 22:07:59.638463 :924CEA29:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9780 pso_num=24 pso_serial#=212 2025-05-03 22:07:59.638610 :924CEA2A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9780 (legacy spawn) 2025-05-03 22:07:59.645227 :924CEA2B: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-05-03 22:07:59.645383 :924CEA34: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-05-03 22:07:59.646194 :924CEA35:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:08:02.820145 :924CEB3B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:08:02.812 2025-05-03 22:08:02.820147 :924CEB3C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11464 pso_num=24 pso_serial#=213 2025-05-03 22:08:02.820284 :924CEB3D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11464 (legacy spawn) 2025-05-03 22:08:02.826701 :924CEB3E: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-05-03 22:08:02.826875 :924CEB43: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-05-03 22:08:02.827715 :924CEB48:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:08:06.063973 :924CEC5F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:08:06.051 2025-05-03 22:08:06.063975 :924CEC60:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11480 pso_num=24 pso_serial#=214 2025-05-03 22:08:06.064133 :924CEC61:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11480 (legacy spawn) 2025-05-03 22:08:06.070524 :924CEC62: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-05-03 22:08:06.070722 :924CEC67: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-05-03 22:08:06.071515 :924CEC6C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:08:10.711600 :924CEDFB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:08:10.706 2025-05-03 22:08:10.711602 :924CEDFC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7708 pso_num=24 pso_serial#=215 2025-05-03 22:08:10.711737 :924CEDFD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7708 osp_idx=22 osp_ver=220279 osp_pg=0 (spawn #145352) 2025-05-03 22:08:10.711738 :924CEDFE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 22:08:10.711738 :924CEDFF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=217 time=3580867875 2025-05-03 22:08:10.711739 :924CEE00:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 22:08:10.711739 :924CEE01:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 22:08:10.711740 :924CEE02:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 22:08:10.711740 :924CEE03:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 22:08:10.711741 :924CEE04:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 22:08:10.712229 :924CEE05: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-05-03 22:08:10.712861 :924CEE1A: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-05-03 22:08:10.723839 :924CEE36:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-03 22:08:10.724329 :924CEE37:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-03 22:08:10.726682 :924CEE38:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-03 22:08:10.727012 :924CEE39: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-05-03 22:08:10.727053 :924CEE3C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-03 22:08:10.727956 :924CEE42: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-05-03 22:08:10.728083 :924CEE4B: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-05-03 22:08:40.837996 :924CF874:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:08:46.659473 :924CFA3B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:08:46.651 2025-05-03 22:08:46.659475 :924CFA3C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11904 pso_num=24 pso_serial#=216 2025-05-03 22:08:46.659618 :924CFA3D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11904 (legacy spawn) 2025-05-03 22:08:46.666034 :924CFA3E: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-05-03 22:08:46.666203 :924CFA43: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-05-03 22:08:46.667003 :924CFA48:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:09:08.291395 :924D013D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:09:08.278 2025-05-03 22:09:08.291397 :924D013E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9600 pso_num=24 pso_serial#=217 2025-05-03 22:09:08.291535 :924D013F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9600 (legacy spawn) 2025-05-03 22:09:08.297988 :924D0140: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-05-03 22:09:08.298161 :924D0145: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-05-03 22:09:08.298963 :924D014A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:09:09.428257 :924D018A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:09:09.414 2025-05-03 22:09:09.428259 :924D018B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9440 pso_num=24 pso_serial#=218 2025-05-03 22:09:09.428396 :924D018C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9440 (legacy spawn) 2025-05-03 22:09:09.434865 :924D018D: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-05-03 22:09:09.435056 :924D0195: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-05-03 22:09:09.435870 :924D0197:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:09:09.444557 :924D019A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:09:09.429 2025-05-03 22:09:09.444560 :924D019B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11236 pso_num=24 pso_serial#=219 2025-05-03 22:09:09.444701 :924D019C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11236 (legacy spawn) 2025-05-03 22:09:09.451175 :924D019D: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-05-03 22:09:09.451359 :924D01A6: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-05-03 22:09:09.452192 :924D01A7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-03 22:09:10.804203 :924D0245:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-03 22:09:10.799 2025-05-03 22:09:10.804206 :924D0246:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12148 pso_num=24 pso_serial#=220 2025-05-03 22:09:10.804345 :924D0247:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12148 osp_idx=22 osp_ver=220284 osp_pg=0 (spawn #145354) 2025-05-03 22:09:10.804345 :924D0248:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 22:09:10.804346 :924D0249:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=218 time=3580927968 2025-05-03 22:09:10.804346 :924D024A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 22:09:10.804347 :924D024B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 22:09:10.804347 :924D024C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 22:09:10.804347 :924D024D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 22:09:10.804348 :924D024E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 22:09:10.804839 :924D024F: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-05-03 22:09:10.805547 :924D0264: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-05-03 22:09:10.812946 :924D0280:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-03 22:09:10.813307 :924D0281: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-05-03 22:09:10.813342 :924D0284:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-03 22:09:10.814269 :924D028A: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-05-03 22:09:10.814394 :924D0293: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-05-03 22:09:22.910005 :924D080B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 22:09:22.911273*:924D0840:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495C718 estart=1746281362 eid=16779759 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-03 22:09:22.911273*:924D0841:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495C718 curCtx=0x0000000024958938 pubTabIdxCnt=0 2025-05-03 22:09:22.911273*:924D0842:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495C718 2025-05-03 22:09:22.911273*:924D0843:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495C718 2025-05-03 22:09:22.911273*:924D0844:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495C718 curCtx=0x0000000024958938 xsc->flg4=65792 flags=2 2025-05-03 22:09:22.911273*:924D0845:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495C718 2025-05-03 22:09:22.916148 :924D0846: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-05-03 22:09:22.916188 :924D0849:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-03 22:09:22.933223 :924D084F: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-05-03 22:09:22.933431 :924D0857: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-05-03 22:09:22.933587 :924D085F: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-05-03 22:09:22.933737 :924D0867: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-05-03 22:09:22.933894 :924D086F: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-05-03 22:09:22.934037 :924D0877: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-05-03 22:09:22.934201 :924D087F: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-05-03 22:09:22.934370 :924D0887: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-05-03 22:09:22.934510 :924D088F: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-05-03 22:09:22.934829 :924D0897: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-05-03 22:09:22.935063 :924D089F: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-05-03 22:09:22.952918 :924D08A7: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-05-03 22:09:24.069624*:924D0968:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026B41FA8 estart=1746281364 eid=18053586 ctx=0x0000000777968158 cwh=0x00000000249809C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-03 22:09:24.069624*:924D0969:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026B41FA8 curCtx=0x000000002903E640 pubTabIdxCnt=0 2025-05-03 22:09:24.069624*:924D096A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026B41FA8 2025-05-03 22:09:24.069624*:924D096B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026B41FA8 2025-05-03 22:09:24.069624*:924D096C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDB6F0 estart=1746281363 eid=16777300 ctx=0x000000077B54EAC8 cwh=0x0000000024980518 cpu=937500 buffg=51912 interb=204800 r=25 rb=204800 w=0 wb=0 2025-05-03 22:09:24.069624*:924D096D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDB6F0 curCtx=0x0000000028ED7FD8 pubTabIdxCnt=0 2025-05-03 22:09:24.069624*:924D096E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDB6F0 2025-05-03 22:09:24.069624*:924D096F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDB6F0 2025-05-03 22:09:24.069624*:924D0970:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026B41FA8 curCtx=0x000000002903E640 xsc->flg4=65792 flags=1 2025-05-03 22:09:24.069624*:924D0971:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026B41FA8 2025-05-03 22:09:24.626131*:924D0A4E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDB6F0 curCtx=0x0000000028ED7FD8 xsc->flg4=65792 flags=2 2025-05-03 22:09:24.626131*:924D0A4F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDB6F0 2025-05-03 22:09:24.638417 :924D0A51: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-05-03 22:09:24.658525 :924D0A63: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-05-03 22:09:24.660436 :924D0A6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-03 22:09:24.660620 :924D0A6C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-03 22:09:24.661457 :924D0A6D: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-05-03 22:09:24.661547 :924D0A70: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-05-03 22:09:24.662367 :924D0A8A: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-05-03 22:09:24.663729 :924D0ABA: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-05-03 22:09:24.664010 :924D0AC3: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-05-03 22:09:24.704258*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-03 22:09:24.704258*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-03 22:09:24.704258*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-03 22:09:24.719879*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-03 22:09:24.719879*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-03 22:09:24.719879*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-03 22:09:24.719879*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-03 22:09:24.786579 :924D0AE7: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-05-03 22:09:26.671019 :924D0BAA: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-05-03 22:09:26.671031 :924D0BAB:db_trace:ktur.c@3079:ktuabt(): [10444:24:193] ABORT TRANSACTION - xid: 0x000a.012.00167471 2025-05-03 22:09:29.000388 :924D0D91: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-05-03 22:09:29.004082 :924D0D99: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-05-03 22:09:29.014796 :924D0DA1: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-05-03 22:09:29.037646 :924D0DA9: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 12148, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de