Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250713220437\orcl_j000_13924_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250713220437\orcl_j000_13924_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:36381M/63366M, Ph+PgF:42362M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 13924, image: ORACLE.EXE (J000) *** 2025-07-13 22:04:37.351 *** SESSION ID:(177.31773) 2025-07-13 22:04:37.351 *** 2025-07-13 22:04:37.351 Process diagnostic dump for ORACLE.EXE (J000), OS id=13924, pid: 22, proc_ser: 169, sid: 177, sess_ser: 31773 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13924 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12755, hash value=2512763354 Current Wait Stack: Not in wait; last wait ended 1.210751 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.210776 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=146 seq_num=147 snap_id=1 wait times: snap=0.000322 sec, exc=0.000322 sec, total=0.000322 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#=0x290b, block#=0x0, blocks=0x0 wait_id=145 seq_num=146 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.029337 sec of elapsed time 2: waited for 'log file sequential read' log#=0x290b, block#=0x0, blocks=0x0 wait_id=144 seq_num=145 snap_id=1 wait times: snap=0.000010 sec, exc=0.000010 sec, total=0.000010 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x9000, blocks=0x800 wait_id=143 seq_num=144 snap_id=1 wait times: snap=0.000296 sec, exc=0.000296 sec, total=0.000296 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#=0x8800, blocks=0x800 wait_id=142 seq_num=143 snap_id=1 wait times: snap=0.000311 sec, exc=0.000311 sec, total=0.000311 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x8000, blocks=0x800 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000307 sec, exc=0.000307 sec, total=0.000307 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0x7800, blocks=0x800 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000294 sec, exc=0.000294 sec, total=0.000294 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x7000, 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.000036 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0x6800, blocks=0x800 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000308 sec, exc=0.000308 sec, total=0.000308 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.541236 sec of elapsed time 9: waited for 'log file sequential read' log#=0x290b, block#=0x800, blocks=0x800 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000211 sec, exc=0.000211 sec, total=0.000211 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002643 sec of elapsed time Sampled Session History of session 177 serial 31773 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 22:04:31 - 22:04:37] not in wait at each sample [1 sample, 22:04:30] waited for 'null event', seq_num: 0 p1: ''=0x0 p2: ''=0x0 p3: ''=0x0 time_waited: 0.000157 sec (sample interval: 0 sec) [1 sample, 22:04:29] idle wait at each sample [session created at: 22:04:30] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'null event' [1 sample, 22:04:30 ] time_waited: 0.000157 sec (sample interval: 0 sec) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-13 22:04:37.351 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 13924, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-13 22:02:39.199669 :B19AEA46:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15428 pso_num=22 pso_serial#=156 2025-07-13 22:02:39.199807 :B19AEA47:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15428 (legacy spawn) 2025-07-13 22:02:39.206321 :B19AEA48: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-07-13 22:02:39.206494 :B19AEA4E: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-07-13 22:02:39.207310 :B19AEA52:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:02:39.215988 :B19AEA55:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:02:39.213 2025-07-13 22:02:39.215990 :B19AEA56:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2680 pso_num=22 pso_serial#=157 2025-07-13 22:02:39.216127 :B19AEA57:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=2680 (legacy spawn) 2025-07-13 22:02:39.222591 :B19AEA58: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-07-13 22:02:39.222753 :B19AEA61: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-07-13 22:02:39.223526 :B19AEA62:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:02:48.473298 :B19AED26:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:02:48.467 2025-07-13 22:02:48.473301 :B19AED27:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17964 pso_num=22 pso_serial#=158 2025-07-13 22:02:48.473439 :B19AED28:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17964 osp_idx=0 osp_ver=399050 osp_pg=0 (spawn #382503) 2025-07-13 22:02:48.473440 :B19AED29:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 22:02:48.473440 :B19AED2A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=66 time=1124944361 2025-07-13 22:02:48.473441 :B19AED2B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 22:02:48.473441 :B19AED2C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 22:02:48.473442 :B19AED2D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 22:02:48.473442 :B19AED2E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 22:02:48.473442 :B19AED2F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 22:02:48.473922 :B19AED30: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-07-13 22:02:48.474629 :B19AED45: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-07-13 22:02:48.485649 :B19AED61:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-13 22:02:48.486106 :B19AED62:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-13 22:02:48.488500 :B19AED63:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 22:02:48.488846 :B19AED64: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-07-13 22:02:48.488885 :B19AED67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 22:02:48.489755 :B19AED6D: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-07-13 22:02:48.489874 :B19AED76: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-07-13 22:03:18.593410 :B19AF70E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:03:32.909929 :B19AFB5A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:03:32.901 2025-07-13 22:03:32.909931 :B19AFB5B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17040 pso_num=22 pso_serial#=159 2025-07-13 22:03:32.910072 :B19AFB5C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17040 (legacy spawn) 2025-07-13 22:03:32.937252 :B19AFB5D: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-07-13 22:03:32.937447 :B19AFB62: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-07-13 22:03:32.938322 :B19AFB67:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:03:34.883032 :B19AFC02:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:03:34.870 2025-07-13 22:03:34.883035 :B19AFC03:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13216 pso_num=22 pso_serial#=160 2025-07-13 22:03:34.883181 :B19AFC04:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13216 (legacy spawn) 2025-07-13 22:03:34.889670 :B19AFC05: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-07-13 22:03:34.889848 :B19AFC0A: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-07-13 22:03:34.890648 :B19AFC0F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:03:34.899386 :B19AFC12:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:03:34.885 2025-07-13 22:03:34.899388 :B19AFC13:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5276 pso_num=22 pso_serial#=161 2025-07-13 22:03:34.899522 :B19AFC14:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=5276 (legacy spawn) 2025-07-13 22:03:34.905973 :B19AFC15: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-07-13 22:03:34.906136 :B19AFC1E: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-07-13 22:03:34.906947 :B19AFC1F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:03:38.638842 :B19AFD52:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:03:38.626 2025-07-13 22:03:38.638844 :B19AFD53:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7608 pso_num=22 pso_serial#=162 2025-07-13 22:03:38.638976 :B19AFD54:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=7608 (legacy spawn) 2025-07-13 22:03:38.645380 :B19AFD55: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-07-13 22:03:38.645549 :B19AFD5E: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-07-13 22:03:38.646322 :B19AFD5F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:03:48.566178 :B19B034B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:03:48.560 2025-07-13 22:03:48.566180 :B19B034C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15532 pso_num=22 pso_serial#=163 2025-07-13 22:03:48.566320 :B19B034D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15532 osp_idx=0 osp_ver=399055 osp_pg=0 (spawn #382505) 2025-07-13 22:03:48.566321 :B19B034E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 22:03:48.566321 :B19B034F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=67 time=1125004454 2025-07-13 22:03:48.566322 :B19B0350:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 22:03:48.566322 :B19B0351:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 22:03:48.566322 :B19B0352:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 22:03:48.566323 :B19B0353:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 22:03:48.566323 :B19B0354:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 22:03:48.566809 :B19B0355: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-07-13 22:03:48.567449 :B19B036A: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-07-13 22:03:48.575046 :B19B0386:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 22:03:48.575416 :B19B0387: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-07-13 22:03:48.575452 :B19B038A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 22:03:48.576309 :B19B0390: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-07-13 22:03:48.576426 :B19B0399: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-07-13 22:04:18.683901 :B19B0D95:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:04:24.210800 :B19B0F6C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:04:24.199 2025-07-13 22:04:24.210802 :B19B0F6D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14344 pso_num=22 pso_serial#=164 2025-07-13 22:04:24.210946 :B19B0F6E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14344 (legacy spawn) 2025-07-13 22:04:24.217484 :B19B0F6F: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-07-13 22:04:24.217660 :B19B0F74: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-07-13 22:04:24.218457 :B19B0F79:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:04:24.227271 :B19B0F7C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:04:24.215 2025-07-13 22:04:24.227273 :B19B0F7D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12424 pso_num=22 pso_serial#=165 2025-07-13 22:04:24.227417 :B19B0F7E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12424 (legacy spawn) 2025-07-13 22:04:24.233901 :B19B0F7F: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-07-13 22:04:24.234060 :B19B0F88: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-07-13 22:04:24.234888 :B19B0F89:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:04:27.309078 :B19B1071:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:04:27.296 2025-07-13 22:04:27.309080 :B19B1072:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10660 pso_num=22 pso_serial#=166 2025-07-13 22:04:27.309227 :B19B1073:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10660 (legacy spawn) 2025-07-13 22:04:27.315836 :B19B1080: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-07-13 22:04:27.316008 :B19B1089: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-07-13 22:04:27.316814 :B19B108A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:04:27.335722 :B19B108D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:04:27.327 2025-07-13 22:04:27.335724 :B19B108E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12796 pso_num=22 pso_serial#=167 2025-07-13 22:04:27.335868 :B19B108F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12796 (legacy spawn) 2025-07-13 22:04:27.342370 :B19B1090: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-07-13 22:04:27.342399 :B19B1093: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-07-13 22:04:27.342563 :B19B1099: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-07-13 22:04:27.343367 :B19B10A7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:04:30.222352 :B19B119B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:04:30.217 2025-07-13 22:04:30.222354 :B19B119C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6364 pso_num=22 pso_serial#=168 2025-07-13 22:04:30.222494 :B19B119D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6364 osp_idx=0 osp_ver=399060 osp_pg=0 (spawn #382507) 2025-07-13 22:04:30.222495 :B19B119E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 22:04:30.222495 :B19B119F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=68 time=1125046111 2025-07-13 22:04:30.222496 :B19B11A0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 22:04:30.222496 :B19B11A1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 22:04:30.222496 :B19B11A2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 22:04:30.222497 :B19B11A3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 22:04:30.222497 :B19B11A4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 22:04:30.223006 :B19B11A5: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-07-13 22:04:30.223054 :B19B11AB: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-07-13 22:04:30.227124 :B19B11BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-13 22:04:30.227172 :B19B11BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-13 22:04:30.227431 :B19B11BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-13 22:04:30.227447 :B19B11C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-13 22:04:30.227670 :B19B11C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-13 22:04:30.227683 :B19B11C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-13 22:04:30.227891 :B19B11C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-13 22:04:30.227904 :B19B11C4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-13 22:04:30.228114 :B19B11C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-13 22:04:30.228128 :B19B11C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-13 22:04:30.228332 :B19B11C7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-13 22:04:30.228345 :B19B11C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-13 22:04:30.230724 :B19B11F6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 22:04:30.320330 :B19B12EF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 22:04:30.311 2025-07-13 22:04:30.320330 :B19B12F0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13924 pso_num=22 pso_serial#=169 2025-07-13 22:04:30.320453 :B19B12F1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13924 osp_idx=0 osp_ver=399061 osp_pg=0 (spawn #382509) 2025-07-13 22:04:30.320454 :B19B12F2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 22:04:30.320454 :B19B12F3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=69 time=1125046204 2025-07-13 22:04:30.320455 :B19B12F4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 22:04:30.320455 :B19B12F5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 22:04:30.320455 :B19B12F6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 22:04:30.320456 :B19B12F7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 22:04:30.320456 :B19B12F8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 22:04:30.320920 :B19B12F9: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-07-13 22:04:30.327128 :B19B1324: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-07-13 22:04:30.327179 :B19B1327:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-07-13 22:04:30.344248 :B19B133D: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-07-13 22:04:30.344450 :B19B1345: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-07-13 22:04:30.344790 :B19B134D: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-07-13 22:04:30.345054 :B19B1355: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-07-13 22:04:30.345352 :B19B135D: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-07-13 22:04:30.345517 :B19B1365: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-07-13 22:04:30.345667 :B19B136D: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-07-13 22:04:30.345818 :B19B1375: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-07-13 22:04:30.345998 :B19B137D: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-07-13 22:04:30.364352 :B19B1385: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-07-13 22:04:31.993110 :B19B14B6:db_trace:ktu.c@4871:ktuaex1r(): [10442:22:177] Extension usn 9, ext 1, nex 2 2025-07-13 22:04:31.993116 :B19B14B7:db_trace:ktusm.c@2117:ktusmasp(): [10445:22:177] des1:tsn:1 rdba:0x0080035f where: kdiwh22: kdifind 2025-07-13 22:04:32.007624 :B19B14C1:db_trace:ktu.c@4871:ktuaex1r(): [10442:22:177] Extension usn 9, ext 2, nex 3 2025-07-13 22:04:32.007632 :B19B14C2:db_trace:ktusm.c@2117:ktusmasp(): [10445:22:177] des1:tsn:1 rdba:0x008191c5 where: kdiwh22: kdifind 2025-07-13 22:04:32.011848 :B19B14C3: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-07-13 22:04:32.029461*:B19B14E8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000239208E0 estart=1752415471 eid=16777455 ctx=0x000000077B2E3E40 cwh=0x00000000238006F8 cpu=31250 buffg=786 interb=0 r=0 rb=0 w=0 wb=0 2025-07-13 22:04:32.029461*:B19B14EB:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000239208E0 curCtx=0x0000000024B5EE70 pubTabIdxCnt=0 2025-07-13 22:04:32.029461*:B19B14EE:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000239208E0 2025-07-13 22:04:32.029461*:B19B14F0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000239208E0 2025-07-13 22:04:32.029461*:B19B14F7:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000239208E0 curCtx=0x0000000024B5EE70 xsc->flg4=65792 flags=1 2025-07-13 22:04:32.029461*:B19B14F8:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000239208E0 2025-07-13 22:04:32.031992 :B19B14FB: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-07-13 22:04:32.033810 :B19B1511:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 22:04:32.033985 :B19B1512:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 22:04:32.034938 :B19B1513: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-07-13 22:04:32.035041 :B19B1516: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-07-13 22:04:32.035887 :B19B1528: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-07-13 22:04:32.037294 :B19B1530: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-07-13 22:04:32.037561 :B19B153E: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-07-13 22:04:32.076338*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-13 22:04:32.076338*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-13 22:04:32.076338*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-13 22:04:32.107582*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-13 22:04:32.107582*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-13 22:04:32.107582*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-13 22:04:32.107582*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-13 22:04:32.176673 :B19B15DC: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-07-13 22:04:34.099989 :B19B1667: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-07-13 22:04:34.100002 :B19B1668:db_trace:ktur.c@3079:ktuabt(): [10444:22:177] ABORT TRANSACTION - xid: 0x000a.013.00172b62 2025-07-13 22:04:36.595806 :B19B1827: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-07-13 22:04:36.597429 :B19B182F: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-07-13 22:04:36.608702 :B19B1837: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-07-13 22:04:36.635992 :B19B183F: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 13924, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de