Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525140427\orcl_vkrm_15220_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:37303M/63366M, Ph+PgF:44321M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15220, image: ORACLE.EXE (VKRM) *** 2025-05-25 14:04:27.565 *** SESSION ID:(177.17709) 2025-05-25 14:04:27.565 *** 2025-05-25 14:04:27.565 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=15220, pid: 22, proc_ser: 60, sid: 177, sess_ser: 17709 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=484 min 27 sec, exc=484 min 27 sec, total=484 min 27 sec wait times: max=infinite, heur=484 min 27 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000000 sec since current wait Sampled Session History of session 177 serial 17709 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 14:02:27 - 14:04:27] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 14:04:27.565 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15220, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 05:56:50.259958 :9BAE03C6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9584 (legacy spawn) 2025-05-25 05:56:50.266552 :9BAE03C7: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-05-25 05:56:50.266721 :9BAE03D0: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-05-25 05:56:50.267535 :9BAE03D1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:56:53.425239 :9BAE04C9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:56:53.412 2025-05-25 05:56:53.425242 :9BAE04CA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10388 pso_num=22 pso_serial#=39 2025-05-25 05:56:53.425381 :9BAE04CB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10388 (legacy spawn) 2025-05-25 05:56:53.431847 :9BAE04CC: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-05-25 05:56:53.432020 :9BAE04D5: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-05-25 05:56:53.432800 :9BAE04D6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:56:53.441401 :9BAE04D9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:56:53.427 2025-05-25 05:56:53.441404 :9BAE04DA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15176 pso_num=22 pso_serial#=40 2025-05-25 05:56:53.441535 :9BAE04DB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15176 (legacy spawn) 2025-05-25 05:56:53.447971 :9BAE04DC: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-05-25 05:56:53.448134 :9BAE04E5: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-05-25 05:56:53.448960 :9BAE04E6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:57:01.387929 :9BAE072A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:57:01.380 2025-05-25 05:57:01.387931 :9BAE072B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11704 pso_num=22 pso_serial#=41 2025-05-25 05:57:01.388075 :9BAE072C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11704 (legacy spawn) 2025-05-25 05:57:01.394546 :9BAE072D: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-05-25 05:57:01.394725 :9BAE0732: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-05-25 05:57:01.395615 :9BAE0743:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:57:02.565223 :9BAE07BB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:57:02.556 2025-05-25 05:57:02.565225 :9BAE07BC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13676 pso_num=22 pso_serial#=42 2025-05-25 05:57:02.565359 :9BAE07BD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13676 (legacy spawn) 2025-05-25 05:57:02.571838 :9BAE07BE: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-05-25 05:57:02.572005 :9BAE07C7: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-05-25 05:57:02.572775 :9BAE07C8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:57:09.319629 :9BAE09CB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:57:09.313 2025-05-25 05:57:09.319631 :9BAE09CC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13380 pso_num=22 pso_serial#=43 2025-05-25 05:57:09.319768 :9BAE09CD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13380 osp_idx=0 osp_ver=232603 osp_pg=0 (spawn #216929) 2025-05-25 05:57:09.319769 :9BAE09CE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 05:57:09.319769 :9BAE09CF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=94 time=1128401579 2025-05-25 05:57:09.319770 :9BAE09D0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 05:57:09.319770 :9BAE09D1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 05:57:09.319771 :9BAE09D2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 05:57:09.319771 :9BAE09D3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 05:57:09.319771 :9BAE09D4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 05:57:09.320271 :9BAE09D5: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-05-25 05:57:09.320953 :9BAE09EA: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-05-25 05:57:09.328401 :9BAE0A06:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-25 05:57:09.328778 :9BAE0A07: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-05-25 05:57:09.328814 :9BAE0A0A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-25 05:57:09.329651 :9BAE0A10: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-05-25 05:57:09.329776 :9BAE0A19: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-05-25 05:57:39.416975 :9BAE1406:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:58:02.950408 :9BAE1B1F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:58:02.940 2025-05-25 05:58:02.950410 :9BAE1B20:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14288 pso_num=22 pso_serial#=44 2025-05-25 05:58:02.950547 :9BAE1B21:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14288 (legacy spawn) 2025-05-25 05:58:02.957005 :9BAE1B22: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-05-25 05:58:02.957171 :9BAE1B2B: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-05-25 05:58:02.957978 :9BAE1B2C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:58:02.966710 :9BAE1B2F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:58:02.955 2025-05-25 05:58:02.966712 :9BAE1B30:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13396 pso_num=22 pso_serial#=45 2025-05-25 05:58:02.966856 :9BAE1B31:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13396 (legacy spawn) 2025-05-25 05:58:02.973312 :9BAE1B32: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-05-25 05:58:02.973481 :9BAE1B3B: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-05-25 05:58:02.974270 :9BAE1B3C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:58:03.561944 :9BAE1B68:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:58:03.549 2025-05-25 05:58:03.561946 :9BAE1B69:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14596 pso_num=22 pso_serial#=46 2025-05-25 05:58:03.562084 :9BAE1B6A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14596 (legacy spawn) 2025-05-25 05:58:03.568512 :9BAE1B6B: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-05-25 05:58:03.568680 :9BAE1B70: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-05-25 05:58:03.569483 :9BAE1B75:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:58:06.025619 :9BAE1C39:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:58:06.012 2025-05-25 05:58:06.025620 :9BAE1C3A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14436 pso_num=22 pso_serial#=47 2025-05-25 05:58:06.025758 :9BAE1C3B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14436 (legacy spawn) 2025-05-25 05:58:06.032220 :9BAE1C3C: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-05-25 05:58:06.032387 :9BAE1C45: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-05-25 05:58:06.033192 :9BAE1C46:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:58:06.052451 :9BAE1C49:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:58:06.044 2025-05-25 05:58:06.052453 :9BAE1C4A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13028 pso_num=22 pso_serial#=48 2025-05-25 05:58:06.052591 :9BAE1C4B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13028 (legacy spawn) 2025-05-25 05:58:06.058979 :9BAE1C4C: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-05-25 05:58:06.059183 :9BAE1C55: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-05-25 05:58:06.059974 :9BAE1C56:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:58:09.409759 :9BAE1D78:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:58:09.403 2025-05-25 05:58:09.409763 :9BAE1D79:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12012 pso_num=22 pso_serial#=49 2025-05-25 05:58:09.409901 :9BAE1D7A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12012 osp_idx=0 osp_ver=232609 osp_pg=0 (spawn #216931) 2025-05-25 05:58:09.409901 :9BAE1D7B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 05:58:09.409902 :9BAE1D7C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=95 time=1128461657 2025-05-25 05:58:09.409902 :9BAE1D7D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 05:58:09.409902 :9BAE1D7E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 05:58:09.409903 :9BAE1D7F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 05:58:09.409903 :9BAE1D80:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 05:58:09.409903 :9BAE1D81:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 05:58:09.410385 :9BAE1D82: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-05-25 05:58:09.411026 :9BAE1D97: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-05-25 05:58:09.422034 :9BAE1DB3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-25 05:58:09.422502 :9BAE1DB4:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-25 05:58:09.424875 :9BAE1DB5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-25 05:58:09.425212 :9BAE1DB6: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-05-25 05:58:09.425247 :9BAE1DB9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-25 05:58:09.426107 :9BAE1DBF: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-05-25 05:58:09.426233 :9BAE1DC7: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-05-25 05:58:39.520596 :9BAE2765:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:58:42.344545 :9BAE285A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:58:42.332 2025-05-25 05:58:42.344547 :9BAE285B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11896 pso_num=22 pso_serial#=50 2025-05-25 05:58:42.344686 :9BAE285C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11896 (legacy spawn) 2025-05-25 05:58:42.351191 :9BAE285D: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-05-25 05:58:42.351370 :9BAE2862: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-05-25 05:58:42.352140 :9BAE2867:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:58:42.360737 :9BAE286A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:58:42.347 2025-05-25 05:58:42.360740 :9BAE286B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12744 pso_num=22 pso_serial#=51 2025-05-25 05:58:42.360877 :9BAE286C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12744 (legacy spawn) 2025-05-25 05:58:42.367342 :9BAE286D: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-05-25 05:58:42.367514 :9BAE2876: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-05-25 05:58:42.368319 :9BAE2877:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:58:44.778047 :9BAE2942:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:58:44.770 2025-05-25 05:58:44.778049 :9BAE2943:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14896 pso_num=22 pso_serial#=52 2025-05-25 05:58:44.778185 :9BAE2944:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14896 (legacy spawn) 2025-05-25 05:58:44.784577 :9BAE2945: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-05-25 05:58:44.784764 :9BAE294E: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-05-25 05:58:44.785584 :9BAE294F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:59:05.746479 :9BAE2FC9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:59:05.734 2025-05-25 05:59:05.746481 :9BAE2FCA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13592 pso_num=22 pso_serial#=53 2025-05-25 05:59:05.746623 :9BAE2FCB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13592 (legacy spawn) 2025-05-25 05:59:05.753107 :9BAE2FD8: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-05-25 05:59:05.753289 :9BAE2FE1: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-05-25 05:59:05.754140 :9BAE2FE2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:59:09.515195 :9BAE3109:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:59:09.509 2025-05-25 05:59:09.515197 :9BAE310A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12800 pso_num=22 pso_serial#=54 2025-05-25 05:59:09.515331 :9BAE310B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12800 osp_idx=0 osp_ver=232614 osp_pg=0 (spawn #216934) 2025-05-25 05:59:09.515331 :9BAE310C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 05:59:09.515332 :9BAE310D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=96 time=1128521766 2025-05-25 05:59:09.515332 :9BAE310E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 05:59:09.515332 :9BAE310F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 05:59:09.515333 :9BAE3110:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 05:59:09.515333 :9BAE3111:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 05:59:09.515334 :9BAE3112:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 05:59:09.515828 :9BAE3113: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-05-25 05:59:09.516483 :9BAE3128: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-05-25 05:59:09.524042 :9BAE3144:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-25 05:59:09.524391 :9BAE3145: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-05-25 05:59:09.524426 :9BAE3148:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-25 05:59:09.525283 :9BAE314E: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-05-25 05:59:09.525401 :9BAE3157: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-05-25 05:59:39.609133 :9BAE3B61:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:59:51.933566 :9BAE3F10:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:59:51.919 2025-05-25 05:59:51.933568 :9BAE3F11:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13928 pso_num=22 pso_serial#=55 2025-05-25 05:59:51.933703 :9BAE3F12:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13928 (legacy spawn) 2025-05-25 05:59:51.952401 :9BAE3F13: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-05-25 05:59:51.952579 :9BAE3F18: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-05-25 05:59:51.953467 :9BAE3F1D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:59:51.962117 :9BAE3F20:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:59:51.961 2025-05-25 05:59:51.962120 :9BAE3F21:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13296 pso_num=22 pso_serial#=56 2025-05-25 05:59:51.962255 :9BAE3F22:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13296 (legacy spawn) 2025-05-25 05:59:51.968779 :9BAE3F23: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-05-25 05:59:51.968954 :9BAE3F2C: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-05-25 05:59:51.969742 :9BAE3F2D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:59:52.970929 :9BAE3F73:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:59:52.962 2025-05-25 05:59:52.970931 :9BAE3F74:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14612 pso_num=22 pso_serial#=57 2025-05-25 05:59:52.971070 :9BAE3F75:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14612 (legacy spawn) 2025-05-25 05:59:52.977543 :9BAE3F76: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-05-25 05:59:52.977696 :9BAE3F7F: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-05-25 05:59:52.978507 :9BAE3F80:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:59:55.225340 :9BAE402F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:59:55.212 2025-05-25 05:59:55.225343 :9BAE4030:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14580 pso_num=22 pso_serial#=58 2025-05-25 05:59:55.225476 :9BAE4031:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14580 (legacy spawn) 2025-05-25 05:59:55.231877 :9BAE4032: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-05-25 05:59:55.232051 :9BAE4037: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-05-25 05:59:55.232843 :9BAE403C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 05:59:55.241431 :9BAE403F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 05:59:55.228 2025-05-25 05:59:55.241433 :9BAE4040:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15276 pso_num=22 pso_serial#=59 2025-05-25 05:59:55.241576 :9BAE4041:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15276 (legacy spawn) 2025-05-25 05:59:55.248012 :9BAE4042: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-05-25 05:59:55.248179 :9BAE404B: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-05-25 05:59:55.248987 :9BAE404C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-25 06:00:00.013673 :9BAE41FA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-25 06:00:00.006 2025-05-25 06:00:00.013675 :9BAE41FB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15220 pso_num=22 pso_serial#=60 2025-05-25 06:00:00.013783 :9BAE41FC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15220 osp_idx=0 osp_ver=232620 osp_pg=0 (spawn #216936) 2025-05-25 06:00:00.013784 :9BAE41FD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 06:00:00.013784 :9BAE41FE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=97 time=1128572266 2025-05-25 06:00:00.013784 :9BAE41FF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 06:00:00.013785 :9BAE4200:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 06:00:00.013785 :9BAE4201:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 06:00:00.013786 :9BAE4202:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 06:00:00.013786 :9BAE4203:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 06:00:00.013854 :9BAE4204:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 06:00:00.013862 :9BAE4205:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:22:0] kso: spawn diagnostics: init time=0 secs ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 15220, VKRM)