Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250814220005\orcl_j004_18948_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:35494M/63366M, Ph+PgF:40844M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 18948, image: ORACLE.EXE (J004) *** 2025-08-14 22:00:06.060 *** SESSION ID:(241.4027) 2025-08-14 22:00:06.060 *** 2025-08-14 22:00:06.060 Process diagnostic dump for ORACLE.EXE (J004), OS id=18948, pid: 30, proc_ser: 99, sid: 241, sess_ser: 4027 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18948 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.130811 sec, exc=0.130811 sec, total=0.130811 sec wait times: max=0.500000 sec, heur=2.124727 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.493886 sec, exc=0.493886 sec, total=0.493886 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 241 serial 4027 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 22:00:03 - 22:00:06] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-14 22:00:06.060 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 18948, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-14 00:05:04.998741 :BF62723F:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=16768 (legacy spawn) 2025-08-14 00:05:05.005181 :BF627240:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:05:05.005344 :BF627249:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:05:05.006139 :BF62724A:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:05:05.014881 :BF62724D:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:05:05.000 2025-08-14 00:05:05.014883 :BF62724E:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17716 pso_num=30 pso_serial#=86 2025-08-14 00:05:05.015056 :BF62724F:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=17716 (legacy spawn) 2025-08-14 00:05:05.021589 :BF627250:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:05:05.021765 :BF627259:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:05:05.022571 :BF62725A:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:05:08.085314 :BF627670:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:05:08.072 2025-08-14 00:05:08.085315 :BF627671:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=21124 pso_num=30 pso_serial#=87 2025-08-14 00:05:08.085450 :BF627672:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=21124 (legacy spawn) 2025-08-14 00:05:08.092011 :BF627673:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:05:08.092177 :BF62767C:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:05:08.092955 :BF62767D:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:05:08.101703 :BF627680:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:05:08.098 2025-08-14 00:05:08.101704 :BF627681:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9092 pso_num=30 pso_serial#=88 2025-08-14 00:05:08.101847 :BF627682:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=9092 (legacy spawn) 2025-08-14 00:05:08.108302 :BF627683:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:05:08.108463 :BF62768C:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:05:08.109248 :BF62768D:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:05:11.253701 :BF62797D:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:05:11.246 2025-08-14 00:05:11.253703 :BF62797E:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=19324 pso_num=30 pso_serial#=89 2025-08-14 00:05:11.253846 :BF62797F:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=19324 (legacy spawn) 2025-08-14 00:05:11.260321 :BF627980:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:05:11.260504 :BF627985:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:05:11.261301 :BF62798A:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:05:14.284937 :BF627A7C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:05:14.277 2025-08-14 00:05:14.284939 :BF627A7D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=20712 pso_num=30 pso_serial#=90 2025-08-14 00:05:14.285076 :BF627A7E:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=20712 (legacy spawn) 2025-08-14 00:05:14.291534 :BF627A7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:05:14.291732 :BF627A88:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:05:14.292520 :BF627A89:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:05:14.301309 :BF627A8C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:05:14.293 2025-08-14 00:05:14.301312 :BF627A8D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=21112 pso_num=30 pso_serial#=91 2025-08-14 00:05:14.301468 :BF627A8E:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=21112 (legacy spawn) 2025-08-14 00:05:14.307894 :BF627A8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:05:14.308054 :BF627A98:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:05:14.309018 :BF627AA5:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:06:04.645364 :BF628C04:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:06:04.637 2025-08-14 00:06:04.645366 :BF628C05:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=19936 pso_num=30 pso_serial#=92 2025-08-14 00:06:04.645502 :BF628C06:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=19936 (legacy spawn) 2025-08-14 00:06:04.651962 :BF628C07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:06:04.652143 :BF628C10:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:06:04.652973 :BF628C11:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:06:07.030363 :BF628CF2:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:06:07.022 2025-08-14 00:06:07.030365 :BF628CF3:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16664 pso_num=30 pso_serial#=93 2025-08-14 00:06:07.030510 :BF628CF4:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=16664 (legacy spawn) 2025-08-14 00:06:07.037042 :BF628CF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:06:07.037216 :BF628CFA:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:06:07.038045 :BF628CFF:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:06:17.686235 :BF629027:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:06:17.672 2025-08-14 00:06:17.686237 :BF629028:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18800 pso_num=30 pso_serial#=94 2025-08-14 00:06:17.686375 :BF629029:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=18800 (legacy spawn) 2025-08-14 00:06:17.692823 :BF62902A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:06:17.692996 :BF629033:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:06:17.693788 :BF629034:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 00:06:17.711506 :BF629037:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 00:06:17.703 2025-08-14 00:06:17.711508 :BF629038:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16928 pso_num=30 pso_serial#=95 2025-08-14 00:06:17.711644 :BF629039:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=16928 (legacy spawn) 2025-08-14 00:06:17.718090 :BF62903A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:06:17.718253 :BF629043:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 00:06:17.719095 :BF629044:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 01:00:13.392035 :BF66B6E4:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 01:00:13.379 2025-08-14 01:00:13.392037 :BF66B6E5:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=20048 pso_num=30 pso_serial#=96 2025-08-14 01:00:13.392168 :BF66B6E6:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=20048 (legacy spawn) 2025-08-14 01:00:13.398616 :BF66B6E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.398798 :BF66B6F0:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 01:00:13.399595 :BF66B6F1:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 01:00:13.408227 :BF66B6F4:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 01:00:13.395 2025-08-14 01:00:13.408229 :BF66B6F5:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=14192 pso_num=30 pso_serial#=97 2025-08-14 01:00:13.408359 :BF66B6F6:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=14192 (legacy spawn) 2025-08-14 01:00:13.414918 :BF66B6F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.415083 :BF66B700:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 01:00:13.415887 :BF66B701:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 01:00:13.524135 :BF66B73C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 01:00:13.520 2025-08-14 01:00:13.524137 :BF66B73D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17248 pso_num=30 pso_serial#=98 2025-08-14 01:00:13.524273 :BF66B73E:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=17248 osp_idx=28 osp_ver=1882 osp_pg=0 (spawn #486237) 2025-08-14 01:00:13.524273 :BF66B73F:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 01:00:13.524274 :BF66B740:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=226 time=3813882814 2025-08-14 01:00:13.524274 :BF66B741:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 01:00:13.524275 :BF66B742:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 01:00:13.524275 :BF66B743:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 01:00:13.524275 :BF66B744:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 01:00:13.524276 :BF66B745:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 01:00:13.524779 :BF66B746:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.524833 :BF66B74C:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-08-14 01:00:13.532265 :BF66B75A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.533374 :BF66B762:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.533895 :BF66B76A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.534348 :BF66B772:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.534773 :BF66B77A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.537568 :BF66B782:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.538655 :BF66B78A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.589508 :BF66B792:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.590364 :BF66B79A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.590685 :BF66B7A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.590904 :BF66B7AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 01:00:13.592712 :BF66B7B2:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-14 22:00:02.042872 :BFC81C03:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-14 22:00:02.041 2025-08-14 22:00:02.042873 :BFC81C04:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18948 pso_num=30 pso_serial#=99 2025-08-14 22:00:02.043009 :BFC81C08:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=18948 osp_idx=28 osp_ver=1883 osp_pg=0 (spawn #489146) 2025-08-14 22:00:02.043010 :BFC81C09:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 22:00:02.043011 :BFC81C0A:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=227 time=3889471329 2025-08-14 22:00:02.043011 :BFC81C0B:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 22:00:02.043012 :BFC81C0C:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 22:00:02.043013 :BFC81C0D:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 22:00:02.043013 :BFC81C0E:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 22:00:02.043014 :BFC81C0F:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 22:00:02.043570 :BFC81C16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.048517 :BFC81D57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.048555 :BFC81D5A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-08-14 22:00:02.053913 :BFC81D85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.259772 :BFC81ECC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.460609 :BFC81EDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.596106 :BFC81F09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.718320 :BFC820E7:db_trace:ksl2.c@2477:kslwtbctx(): [10005:30:241] KSL WAIT BEG [latch: In memory undo latch] 33110751640/0x7b58df998 243/0xf3 0/0x0 wait_id=5 seq_num=6 snap_id=1 2025-08-14 22:00:03.718379 :BFC820E9:db_trace:ksl2.c@2611:kslwtectx(): [10005:30:241] KSL WAIT END [latch: In memory undo latch] 33110751640/0x7b58df998 243/0xf3 0/0x0 wait_id=5 seq_num=6 snap_id=1 2025-08-14 22:00:03.718380 :BFC820EA:db_trace:ksl2.c@2619:kslwtectx(): [10005:30:241] KSL WAIT END wait times (usecs) - snap=58, exc=58, tot=58 2025-08-14 22:00:03.719795 :BFC820EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.720769 :BFC820F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.724702 :BFC820FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.726883 :BFC82103:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.729186 :BFC8210B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.731404 :BFC82113:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.732822 :BFC8211B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.752429 :BFC82123:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.753053 :BFC8212B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.753567 :BFC82133:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.754071 :BFC8213B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.756660 :BFC82143:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.757563 :BFC8214B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.758317 :BFC8214C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.759089 :BFC8214D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.759519 :BFC8214E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.759526 :BFC8214F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.760056 :BFC82150:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.760683 :BFC8215B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.761017 :BFC8215C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.762190 :BFC8215D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.804823 :BFC82195:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.804872 :BFC82198:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.805803 :BFC8219E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.806743 :BFC821A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.807221 :BFC821A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.828412 :BFC821BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.828461 :BFC821BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.829369 :BFC821C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.830183 :BFC821CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.830595 :BFC821CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.848820 :BFC821D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.848869 :BFC821D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.849689 :BFC821DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.850601 :BFC821E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.851150 :BFC821E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.874451 :BFC821EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.874501 :BFC821F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.875348 :BFC821F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.876227 :BFC82200:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.876801 :BFC82201:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.897789 :BFC82209:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.897841 :BFC8220C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.898677 :BFC82212:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.910342 :BFC8221C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-14 22:00:03.910856 :BFC8221D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.930260 :BFC8222D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.930312 :BFC82230:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-14 22:00:03.931139 :BFC82236:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.931591 :BFC8223E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.931947 :BFC82246:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.932114 :BFC8224E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.932244 :BFC82256:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.932370 :BFC8225E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-08-14 22:00:03.936296 :BFC8225F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.936371 :BFC82262:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.936414 :BFC82269:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-14 22:00:03.936417 :BFC8226A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:03.936469 :BFC82272:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 30 (osid: 18948, J004)