Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250601140716\orcl_j000_3752_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250601140716\orcl_j000_3752_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:37166M/63366M, Ph+PgF:44019M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 3752, image: ORACLE.EXE (J000) *** 2025-06-01 14:07:17.016 *** SESSION ID:(201.39671) 2025-06-01 14:07:17.016 *** 2025-06-01 14:07:17.016 Process diagnostic dump for ORACLE.EXE (J000), OS id=3752, pid: 25, proc_ser: 98, sid: 201, sess_ser: 39671 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 3752 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12529, hash value=3316513881 Current Wait Stack: Not in wait; last wait ended 0.970890 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.970915 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=144 seq_num=145 snap_id=1 wait times: snap=0.000287 sec, exc=0.000287 sec, total=0.000287 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#=0x2816, block#=0x0, blocks=0x0 wait_id=143 seq_num=144 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.062346 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2816, block#=0x0, blocks=0x0 wait_id=142 seq_num=143 snap_id=1 wait times: snap=0.000019 sec, exc=0.000019 sec, total=0.000019 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.137335 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0xb800, blocks=0x800 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000267 sec, exc=0.000267 sec, total=0.000267 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#=0xb000, blocks=0x800 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000271 sec, exc=0.000271 sec, total=0.000271 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0xa800, blocks=0x800 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000309 sec, exc=0.000309 sec, total=0.000309 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000118 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0xa000, blocks=0x800 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.006211 sec, exc=0.006211 sec, total=0.006211 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.366941 sec of elapsed time 7: waited for 'log file sequential read' log#=0x2816, block#=0x800, blocks=0x800 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000195 sec, exc=0.000195 sec, total=0.000195 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002825 sec of elapsed time 8: waited for 'log file sequential read' log#=0x2816, block#=0x0, blocks=0x0 wait_id=136 seq_num=137 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000119 sec of elapsed time 9: waited for 'log file sequential read' log#=0x2816, block#=0x2, blocks=0x7fe wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000216 sec, exc=0.000216 sec, total=0.000216 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000139 sec of elapsed time Sampled Session History of session 201 serial 39671 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 14:07:10 - 14:07:16] not in wait at each sample [1 sample, 14:07:09] idle wait at each sample [session created at: 14:07:10] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-01 14:07:17.016 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 3752, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-01 14:05:48.700740 :9EE6B09A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13812 pso_num=25 pso_serial#=84 2025-06-01 14:05:48.700930 :9EE6B09B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13812 (legacy spawn) 2025-06-01 14:05:48.707345 :9EE6B09C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:05:48.707516 :9EE6B0A5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:05:48.708355 :9EE6B0A6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:05:49.461520 :9EE6B117:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:05:49.452 2025-06-01 14:05:49.461522 :9EE6B118:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3236 pso_num=25 pso_serial#=85 2025-06-01 14:05:49.461672 :9EE6B119:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=3236 (legacy spawn) 2025-06-01 14:05:49.468123 :9EE6B11A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:05:49.468288 :9EE6B123:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:05:49.469121 :9EE6B124:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:05:51.744595 :9EE6B1AC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:05:51.731 2025-06-01 14:05:51.744597 :9EE6B1AD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10448 pso_num=25 pso_serial#=86 2025-06-01 14:05:51.744736 :9EE6B1AE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10448 (legacy spawn) 2025-06-01 14:05:51.751169 :9EE6B1AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:05:51.751340 :9EE6B1B4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:05:51.752128 :9EE6B1B9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:05:51.760685 :9EE6B1BC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:05:51.747 2025-06-01 14:05:51.760687 :9EE6B1BD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14408 pso_num=25 pso_serial#=87 2025-06-01 14:05:51.760832 :9EE6B1BE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14408 (legacy spawn) 2025-06-01 14:05:51.767744 :9EE6B1E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:05:51.767931 :9EE6B1EA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:05:51.768755 :9EE6B1EC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:06:07.852388 :9EE6B73F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:06:07.839 2025-06-01 14:06:07.852390 :9EE6B740:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14464 pso_num=25 pso_serial#=88 2025-06-01 14:06:07.852526 :9EE6B741:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14464 (legacy spawn) 2025-06-01 14:06:07.859087 :9EE6B742:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:07.859258 :9EE6B74B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:06:07.860058 :9EE6B74C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:06:07.868661 :9EE6B74F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:06:07.854 2025-06-01 14:06:07.868664 :9EE6B750:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14120 pso_num=25 pso_serial#=89 2025-06-01 14:06:07.868798 :9EE6B751:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14120 (legacy spawn) 2025-06-01 14:06:07.875229 :9EE6B752:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:07.875386 :9EE6B75B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:06:07.876192 :9EE6B75C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:06:15.140238 :9EE6B983:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:06:15.135 2025-06-01 14:06:15.140240 :9EE6B984:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14084 pso_num=25 pso_serial#=90 2025-06-01 14:06:15.140376 :9EE6B985:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14084 osp_idx=23 osp_ver=135607 osp_pg=0 (spawn #241391) 2025-06-01 14:06:15.140377 :9EE6B986:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 14:06:15.140378 :9EE6B987:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=241 time=1762533782 2025-06-01 14:06:15.140378 :9EE6B988:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 14:06:15.140379 :9EE6B989:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 14:06:15.140379 :9EE6B98A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 14:06:15.140380 :9EE6B98B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 14:06:15.140380 :9EE6B98C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 14:06:15.140869 :9EE6B98D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 14:06:15.141486 :9EE6B9A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:15.155897 :9EE6B9BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 14:06:15.155984 :9EE6B9C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 14:06:15.156790 :9EE6B9C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 14:06:15.157138 :9EE6B9C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:15.157180 :9EE6B9C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 14:06:15.165298 :9EE6B9CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:15.165365 :9EE6B9CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-01 14:06:15.165401 :9EE6B9CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-01 14:06:15.165766 :9EE6B9D5:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-01 14:06:15.166098 :9EE6B9D6:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-01 14:06:15.166291 :9EE6B9D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 14:06:15.166558 :9EE6B9D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:15.166585 :9EE6B9DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 14:06:15.168202 :9EE6B9E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 14:06:15.168428 :9EE6B9E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:15.168458 :9EE6B9E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 14:06:15.169404 :9EE6B9EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:15.169523 :9EE6B9F4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:06:45.275062 :9EE6C381:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:06:47.558705 :9EE6C429:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:06:47.551 2025-06-01 14:06:47.558707 :9EE6C42A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12424 pso_num=25 pso_serial#=91 2025-06-01 14:06:47.558841 :9EE6C42B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12424 (legacy spawn) 2025-06-01 14:06:47.565353 :9EE6C42C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:47.565537 :9EE6C431:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:06:47.566421 :9EE6C442:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:06:57.784824 :9EE6C77A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:06:57.775 2025-06-01 14:06:57.784827 :9EE6C77B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10660 pso_num=25 pso_serial#=92 2025-06-01 14:06:57.784969 :9EE6C77C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10660 (legacy spawn) 2025-06-01 14:06:57.791899 :9EE6C77D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:06:57.792092 :9EE6C782:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:06:57.792972 :9EE6C787:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:07:01.241677 :9EE6C89C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:07:01.227 2025-06-01 14:07:01.241680 :9EE6C89D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12732 pso_num=25 pso_serial#=93 2025-06-01 14:07:01.241815 :9EE6C89E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12732 (legacy spawn) 2025-06-01 14:07:01.248297 :9EE6C89F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:01.248488 :9EE6C8A4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:07:01.249296 :9EE6C8A9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:07:01.257876 :9EE6C8AC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:07:01.242 2025-06-01 14:07:01.257878 :9EE6C8AD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14484 pso_num=25 pso_serial#=94 2025-06-01 14:07:01.258011 :9EE6C8AE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14484 (legacy spawn) 2025-06-01 14:07:01.264472 :9EE6C8AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:01.264639 :9EE6C8B8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:07:01.265426 :9EE6C8B9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:07:04.334001 :9EE6C9B1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:07:04.321 2025-06-01 14:07:04.334004 :9EE6C9B2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10456 pso_num=25 pso_serial#=95 2025-06-01 14:07:04.334192 :9EE6C9B3:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10456 (legacy spawn) 2025-06-01 14:07:04.340723 :9EE6C9B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:04.340895 :9EE6C9BD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:07:04.341702 :9EE6C9BE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:07:04.350257 :9EE6C9C1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:07:04.336 2025-06-01 14:07:04.350259 :9EE6C9C2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12668 pso_num=25 pso_serial#=96 2025-06-01 14:07:04.350389 :9EE6C9C3:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12668 (legacy spawn) 2025-06-01 14:07:04.357071 :9EE6C9C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:04.357243 :9EE6C9CD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:07:04.358149 :9EE6C9CE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:07:09.938155 :9EE6CB89:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:07:09.933 2025-06-01 14:07:09.938157 :9EE6CB8A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9092 pso_num=25 pso_serial#=97 2025-06-01 14:07:09.938290 :9EE6CB8B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9092 osp_idx=23 osp_ver=135614 osp_pg=0 (spawn #241393) 2025-06-01 14:07:09.938291 :9EE6CB8C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 14:07:09.938292 :9EE6CB8D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=242 time=1762588579 2025-06-01 14:07:09.938292 :9EE6CB8E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 14:07:09.938292 :9EE6CB8F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 14:07:09.938293 :9EE6CB90:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 14:07:09.938293 :9EE6CB91:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 14:07:09.938293 :9EE6CB92:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 14:07:09.938783 :9EE6CB93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 14:07:09.938827 :9EE6CB99:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-01 14:07:09.959977 :9EE6CC2D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-01 14:07:10.043005 :9EE6CD1E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-01 14:07:10.043 2025-06-01 14:07:10.043006 :9EE6CD1F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3752 pso_num=25 pso_serial#=98 2025-06-01 14:07:10.043134 :9EE6CD20:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3752 osp_idx=23 osp_ver=135615 osp_pg=0 (spawn #241396) 2025-06-01 14:07:10.043134 :9EE6CD21:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 14:07:10.043135 :9EE6CD22:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=243 time=1762588672 2025-06-01 14:07:10.043135 :9EE6CD23:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 14:07:10.043136 :9EE6CD24:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 14:07:10.043136 :9EE6CD25:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 14:07:10.043137 :9EE6CD26:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 14:07:10.043137 :9EE6CD27:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 14:07:10.044662 :9EE6CD28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.050112 :9EE6CD47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.050157 :9EE6CD4A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-01 14:07:10.066929 :9EE6CD60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.067117 :9EE6CD68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.067277 :9EE6CD70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.067425 :9EE6CD78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.067577 :9EE6CD80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.067722 :9EE6CD88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.067869 :9EE6CD90:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.068017 :9EE6CD98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.068161 :9EE6CDA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.068300 :9EE6CDA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.068452 :9EE6CDB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.068645 :9EE6CDB8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.068790 :9EE6CDC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.068947 :9EE6CDC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.069093 :9EE6CDD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.069415 :9EE6CDD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.069672 :9EE6CDE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:10.086722 :9EE6CDE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:11.453694*:9EE6CE9F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DA3AD0 estart=1748758030 eid=19007764 ctx=0x0000000777968158 cwh=0x0000000026EB0068 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-01 14:07:11.453694*:9EE6CEA0:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DA3AD0 curCtx=0x0000000026F719A8 pubTabIdxCnt=0 2025-06-01 14:07:11.453694*:9EE6CEA1:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DA3AD0 2025-06-01 14:07:11.453694*:9EE6CEA2:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DA3AD0 2025-06-01 14:07:11.453694*:9EE6CEA3:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024E2B820 estart=1748758029 eid=16777363 ctx=0x000000077B54EAC8 cwh=0x0000000024A80608 cpu=1156250 buffg=64164 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-01 14:07:11.453694*:9EE6CEA4:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024E2B820 curCtx=0x0000000026F79FF0 pubTabIdxCnt=0 2025-06-01 14:07:11.453694*:9EE6CEA5:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024E2B820 2025-06-01 14:07:11.453694*:9EE6CEA6:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024E2B820 2025-06-01 14:07:11.453694*:9EE6CEA7:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DA3AD0 curCtx=0x0000000026F719A8 xsc->flg4=65792 flags=1 2025-06-01 14:07:11.453694*:9EE6CEA8:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DA3AD0 2025-06-01 14:07:11.766202*:9EE6CF39:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024E2B820 curCtx=0x0000000026F79FF0 xsc->flg4=65792 flags=2 2025-06-01 14:07:11.766202*:9EE6CF3A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024E2B820 2025-06-01 14:07:11.777530 :9EE6CF3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:11.799748 :9EE6CF5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:11.801945 :9EE6CF65:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-01 14:07:11.802181 :9EE6CF66:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-01 14:07:11.803145 :9EE6CF67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:11.803242 :9EE6CF6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:11.804166 :9EE6CF7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:11.805862 :9EE6CF84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:11.806178 :9EE6CF8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:11.859948*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-01 14:07:11.859948*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-01 14:07:11.859948*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-01 14:07:11.859948*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-01 14:07:11.859948*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-01 14:07:11.859948*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-01 14:07:11.859948*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-01 14:07:11.937037 :9EE6D024:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:13.847813 :9EE6D117:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:13.847829 :9EE6D118:db_trace:ktur.c@3079:ktuabt(): [10444:25:201] ABORT TRANSACTION - xid: 0x000a.017.0016be53 2025-06-01 14:07:16.511802 :9EE6D2F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:16.516279 :9EE6D301:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:16.527803 :9EE6D309:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 14:07:16.553111 :9EE6D311:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] 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 25 (osid: 3752, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de