Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250426060007\orcl_j002_8232_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250426060007\orcl_j002_8232_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:37579M/63366M, Ph+PgF:45251M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 8232, image: ORACLE.EXE (J002) *** 2025-04-26 06:00:07.343 *** SESSION ID:(219.42961) 2025-04-26 06:00:07.343 *** 2025-04-26 06:00:07.343 Process diagnostic dump for ORACLE.EXE (J002), OS id=8232, pid: 27, proc_ser: 35, sid: 219, sess_ser: 42961 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 8232 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12328, hash value=2064146508 Current Wait Stack: Not in wait; last wait ended 1.054579 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.054603 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=130 seq_num=131 snap_id=1 wait times: snap=0.000281 sec, exc=0.000281 sec, total=0.000281 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time 1: waited for 'log file sequential read' log#=0x2742, block#=0x0, blocks=0x0 wait_id=129 seq_num=130 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.041876 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2742, block#=0x0, blocks=0x0 wait_id=128 seq_num=129 snap_id=1 wait times: snap=0.000029 sec, exc=0.000029 sec, total=0.000029 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.537070 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x3000, blocks=0x800 wait_id=127 seq_num=128 snap_id=1 wait times: snap=0.000280 sec, exc=0.000280 sec, total=0.000280 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000041 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x2800, blocks=0x800 wait_id=126 seq_num=127 snap_id=1 wait times: snap=0.000314 sec, exc=0.000314 sec, total=0.000314 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.005027 sec of elapsed time 5: waited for 'log file sequential read' log#=0x2742, block#=0x0, blocks=0x0 wait_id=125 seq_num=126 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.000141 sec of elapsed time 6: waited for 'log file sequential read' log#=0x2742, block#=0x2, blocks=0x7fe wait_id=124 seq_num=125 snap_id=1 wait times: snap=0.000200 sec, exc=0.000200 sec, total=0.000200 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000152 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=123 seq_num=124 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 8: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x0, filetype=0x3 wait_id=122 seq_num=123 snap_id=1 wait times: snap=0.000207 sec, exc=0.000207 sec, total=0.000207 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000123 sec of elapsed time 9: waited for 'control file sequential read' file#=0x0, block#=0x20, blocks=0x1 wait_id=121 seq_num=122 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time Sampled Session History of session 219 serial 42961 --------------------------------------------------- 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, 06:00:01 - 06:00:07] not in wait at each sample [1 sample, 06:00:00] idle wait at each sample [session created at: 06:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-04-26 06:00:07.359 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 8232, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-26 01:58:35.730858 :8ECF60CD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 01:58:35.717 2025-04-26 01:58:35.730860 :8ECF60CE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11240 pso_num=27 pso_serial#=23 2025-04-26 01:58:35.730993 :8ECF60CF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11240 (legacy spawn) 2025-04-26 01:58:35.737412 :8ECF60D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 01:58:35.737605 :8ECF60D5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 01:58:35.738409 :8ECF60DA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 01:58:38.278853 :8ECF61CA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 01:58:38.271 2025-04-26 01:58:38.278855 :8ECF61CB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10432 pso_num=27 pso_serial#=24 2025-04-26 01:58:38.278989 :8ECF61CC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10432 (legacy spawn) 2025-04-26 01:58:38.285400 :8ECF61CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 01:58:38.285568 :8ECF61D2:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 01:58:38.286377 :8ECF61D7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 01:59:09.354328 :8ECF6C50:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 01:59:09.346 2025-04-26 01:59:09.354330 :8ECF6C51:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9424 pso_num=27 pso_serial#=25 2025-04-26 01:59:09.354471 :8ECF6C52:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9424 (legacy spawn) 2025-04-26 01:59:09.360806 :8ECF6C53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 01:59:09.360980 :8ECF6C5C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 01:59:09.361793 :8ECF6C5D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 01:59:09.834285 :8ECF6C60:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 01:59:09.825 2025-04-26 01:59:09.834286 :8ECF6C61:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10200 pso_num=27 pso_serial#=26 2025-04-26 01:59:09.834425 :8ECF6C62:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10200 (legacy spawn) 2025-04-26 01:59:09.840879 :8ECF6C63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 01:59:09.841038 :8ECF6C6C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 01:59:09.841814 :8ECF6C6D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 01:59:32.799532 :8ECF739E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 01:59:32.797 2025-04-26 01:59:32.799534 :8ECF739F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10612 pso_num=27 pso_serial#=27 2025-04-26 01:59:32.799666 :8ECF73A0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10612 (legacy spawn) 2025-04-26 01:59:32.806072 :8ECF73A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 01:59:32.806251 :8ECF73A6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 01:59:32.807070 :8ECF73AB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 01:59:32.815685 :8ECF73AE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 01:59:32.812 2025-04-26 01:59:32.815687 :8ECF73AF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7484 pso_num=27 pso_serial#=28 2025-04-26 01:59:32.815822 :8ECF73B0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7484 (legacy spawn) 2025-04-26 01:59:32.822287 :8ECF73B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 01:59:32.822445 :8ECF73BA:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 01:59:32.823228 :8ECF73BB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 01:59:36.007997 :8ECF74BC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 01:59:35.994 2025-04-26 01:59:36.007998 :8ECF74BD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9484 pso_num=27 pso_serial#=29 2025-04-26 01:59:36.008131 :8ECF74BE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9484 (legacy spawn) 2025-04-26 01:59:36.014556 :8ECF74BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 01:59:36.014726 :8ECF74C4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 01:59:36.015568 :8ECF74C9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 01:59:36.024100 :8ECF74CC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 01:59:36.010 2025-04-26 01:59:36.024102 :8ECF74CD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10960 pso_num=27 pso_serial#=30 2025-04-26 01:59:36.024232 :8ECF74CE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10960 (legacy spawn) 2025-04-26 01:59:36.030671 :8ECF74CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 01:59:36.030835 :8ECF74D8:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 01:59:36.031595 :8ECF74D9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 02:14:15.988693 :8ED0A668:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 02:14:15.983 2025-04-26 02:14:15.988695 :8ED0A669:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11012 pso_num=27 pso_serial#=31 2025-04-26 02:14:15.988830 :8ED0A66A:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=11012 osp_idx=25 osp_ver=21450 osp_pg=0 (spawn #118807) 2025-04-26 02:14:15.988830 :8ED0A66B:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 02:14:15.988831 :8ED0A66C:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=84 time=2904457781 2025-04-26 02:14:15.988831 :8ED0A66D:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 02:14:15.988832 :8ED0A66E:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 02:14:15.988832 :8ED0A66F:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 02:14:15.988832 :8ED0A670:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 02:14:15.988832 :8ED0A671:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 02:14:15.989331 :8ED0A672:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-26 02:14:15.989372 :8ED0A67A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 02:14:15.995580 :8ED0A686:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 02:52:15.403740 :8ED3AC60:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 02:52:15.403 2025-04-26 02:52:15.403742 :8ED3AC61:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8644 pso_num=27 pso_serial#=32 2025-04-26 02:52:15.403885 :8ED3AC62:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8644 (legacy spawn) 2025-04-26 02:52:15.410378 :8ED3AC70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 02:52:15.410534 :8ED3AC78:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 02:52:15.411265 :8ED3AC79:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 03:14:18.022649 :8ED57124:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 03:14:18.017 2025-04-26 03:14:18.022651 :8ED57125:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10712 pso_num=27 pso_serial#=33 2025-04-26 03:14:18.022787 :8ED57126:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=10712 osp_idx=25 osp_ver=21452 osp_pg=0 (spawn #118948) 2025-04-26 03:14:18.022787 :8ED57127:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 03:14:18.022788 :8ED57128:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=85 time=2908059812 2025-04-26 03:14:18.022789 :8ED57129:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 03:14:18.022789 :8ED5712A:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 03:14:18.022790 :8ED5712B:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 03:14:18.022790 :8ED5712C:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 03:14:18.022790 :8ED5712D:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 03:14:18.023296 :8ED5712E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-26 03:14:18.023341 :8ED57136:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 03:14:18.029502 :8ED57142:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 06:00:00.042820 :8EE27D9C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 06:00:00.033 2025-04-26 06:00:00.042823 :8EE27D9D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8776 pso_num=27 pso_serial#=34 2025-04-26 06:00:00.042963 :8EE27D9E:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=8776 osp_idx=25 osp_ver=21453 osp_pg=0 (spawn #119334) 2025-04-26 06:00:00.042963 :8EE27D9F:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 06:00:00.042964 :8EE27DA0:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=86 time=2918001828 2025-04-26 06:00:00.042964 :8EE27DA1:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 06:00:00.042964 :8EE27DA2:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 06:00:00.042965 :8EE27DA3:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 06:00:00.042965 :8EE27DA4:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 06:00:00.042965 :8EE27DA5:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 06:00:00.043465 :8EE27DA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.043513 :8EE27DAD:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 06:00:00.043601 :8EE27DC1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.047470 :8EE27DD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.048634 :8EE27DD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.048659 :8EE27DDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.048698 :8EE27DE6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 06:00:00.050623 :8EE27DF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.050652 :8EE27DF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.050690 :8EE27DFF:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 06:00:00.051160 :8EE27E0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.053557 :8EE27E12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.058974 :8EE27E1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.060145 :8EE27E22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.062743 :8EE27E2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.065470 :8EE27E32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.066988 :8EE27E3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.070032 :8EE27E42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.071348 :8EE27E4A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.074312 :8EE27E52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.078177 :8EE27E5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.078517 :8EE27E69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.078573 :8EE27E6C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-04-26 06:00:00.078589 :8EE27E6D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-04-26 06:00:00.081328 :8EE27E73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.081598 :8EE27E82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.081645 :8EE27E85:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-04-26 06:00:00.081658 :8EE27E86:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-04-26 06:00:00.084455 :8EE27E8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.084728 :8EE27E9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.084775 :8EE27E9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-04-26 06:00:00.084788 :8EE27E9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-04-26 06:00:00.084964 :8EE27EA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.084976 :8EE27EA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.084998 :8EE27EB4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 06:00:00.086480 :8EE27EBE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 06:00:00.226941 :8EE27F0D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 06:00:00.221 2025-04-26 06:00:00.226942 :8EE27F0E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8232 pso_num=27 pso_serial#=35 2025-04-26 06:00:00.227064 :8EE27F0F:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=8232 osp_idx=25 osp_ver=21454 osp_pg=0 (spawn #119335) 2025-04-26 06:00:00.227064 :8EE27F10:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 06:00:00.227065 :8EE27F11:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=87 time=2918002015 2025-04-26 06:00:00.227065 :8EE27F12:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 06:00:00.227066 :8EE27F13:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 06:00:00.227066 :8EE27F14:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 06:00:00.227067 :8EE27F15:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 06:00:00.227067 :8EE27F16:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 06:00:00.227582 :8EE27F1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.232915 :8EE27F40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.232956 :8EE27F43:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-04-26 06:00:00.250027 :8EE28105:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.250245 :8EE2810D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.250421 :8EE28115:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.250587 :8EE2811D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.250755 :8EE28125:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.250916 :8EE2813C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.251069 :8EE2814C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.251221 :8EE28154:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.251425 :8EE2815E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.251659 :8EE28166:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.251812 :8EE2817B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.252282 :8EE28184:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.252510 :8EE2818C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.252772 :8EE28194:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.252935 :8EE2819C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.253096 :8EE281A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.253249 :8EE281AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.253397 :8EE281B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.253527 :8EE281C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.253550 :8EE281CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.281017 :8EE281DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:02.057686 :8EE28575:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:02.079529 :8EE2857D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:02.082027 :8EE28585:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:psdpgi.c:2235 2025-04-26 06:00:02.082283 :8EE28586:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:psdpgi.c:2235 2025-04-26 06:00:02.083364 :8EE28587:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:02.083522 :8EE2858A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:02.084678 :8EE2859C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:02.086658 :8EE285A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:02.087010 :8EE285AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:02.137540*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-04-26 06:00:02.137540*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-04-26 06:00:02.137540*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-04-26 06:00:02.184420*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-04-26 06:00:02.184420*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-04-26 06:00:02.184420*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-04-26 06:00:02.184420*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-04-26 06:00:02.244946 :8EE285CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:04.209033 :8EE286BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:04.209047 :8EE286BE:db_trace:ktur.c@3079:ktuabt(): [10444:27:219] ABORT TRANSACTION - xid: 0x000a.005.0016607e 2025-04-26 06:00:06.749397 :8EE288D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:06.751034 :8EE288D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:06.756371 :8EE288E0:db_trace:ktu.c@4871:ktuaex1r(): [10442:27:219] Extension usn 9, ext 2, nex 3 2025-04-26 06:00:06.756382 :8EE288E1:db_trace:ktusm.c@2117:ktusmasp(): [10445:27:219] des1:tsn:1 rdba:0x00800357 where: kduwh01: kdusru 2025-04-26 06:00:06.762386 :8EE288E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 06:00:06.785062 :8EE288EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] 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 27 (osid: 8232, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de