Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250707220007\orcl_vkrm_8164_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:36640M/63366M, Ph+PgF:42679M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 8164, image: ORACLE.EXE (VKRM) *** 2025-07-07 22:00:07.228 *** SESSION ID:(177.56147) 2025-07-07 22:00:07.228 *** 2025-07-07 22:00:07.228 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=8164, pid: 22, proc_ser: 98, sid: 177, sess_ser: 56147 ------------------------------------------------------------------------------- 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=7.207412 sec, exc=7.207412 sec, total=7.207412 sec wait times: max=infinite, heur=7.207412 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 56147 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:00:00 - 22:00:07] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-07 22:00:07.228 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 8164, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-07 21:56:01.511737 :AEEF0F9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:56:01.511905 :AEEF0FA7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:56:01.521329 :AEEF0FA8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:56:04.690920 :AEEF10A6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:56:04.677 2025-07-07 21:56:04.690922 :AEEF10A7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8632 pso_num=22 pso_serial#=80 2025-07-07 21:56:04.691061 :AEEF10A8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8632 (legacy spawn) 2025-07-07 21:56:04.697756 :AEEF10A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:56:04.756755 :AEEF10AE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:56:04.757716 :AEEF10B3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:56:04.766786 :AEEF10B6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:56:04.755 2025-07-07 21:56:04.766789 :AEEF10B7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4500 pso_num=22 pso_serial#=81 2025-07-07 21:56:04.766927 :AEEF10B8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4500 (legacy spawn) 2025-07-07 21:56:04.773444 :AEEF10C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:56:04.773607 :AEEF10CD:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:56:04.774432 :AEEF10CE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:56:15.392754 :AEEF13F0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:56:15.387 2025-07-07 21:56:15.392756 :AEEF13F1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7880 pso_num=22 pso_serial#=82 2025-07-07 21:56:15.392890 :AEEF13F2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7880 osp_idx=0 osp_ver=381634 osp_pg=0 (spawn #362499) 2025-07-07 21:56:15.392891 :AEEF13F3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-07 21:56:15.392892 :AEEF13F4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=161 time=606151283 2025-07-07 21:56:15.392892 :AEEF13F5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-07 21:56:15.392892 :AEEF13F6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-07 21:56:15.392893 :AEEF13F7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-07 21:56:15.392893 :AEEF13F8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-07 21:56:15.392893 :AEEF13F9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-07 21:56:15.393381 :AEEF13FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-07 21:56:15.394102 :AEEF140F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:56:15.405235 :AEEF142B:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-07 21:56:15.405734 :AEEF142C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-07 21:56:15.408118 :AEEF142D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-07 21:56:15.408455 :AEEF142E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:56:15.408490 :AEEF1431:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-07 21:56:15.409367 :AEEF1437:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:56:15.409494 :AEEF1440:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:56:45.473795 :AEEF1DF7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:57:01.234885 :AEEF22FE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:57:01.226 2025-07-07 21:57:01.234887 :AEEF22FF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15216 pso_num=22 pso_serial#=83 2025-07-07 21:57:01.235028 :AEEF2300:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15216 (legacy spawn) 2025-07-07 21:57:01.241531 :AEEF2301:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:57:01.241699 :AEEF230A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:57:01.242498 :AEEF230B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:57:12.621936 :AEEF26A0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:57:12.613 2025-07-07 21:57:12.621938 :AEEF26A1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13328 pso_num=22 pso_serial#=84 2025-07-07 21:57:12.622084 :AEEF26A2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13328 (legacy spawn) 2025-07-07 21:57:12.628588 :AEEF26A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:57:12.628757 :AEEF26AB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:57:12.629574 :AEEF26AD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:57:14.315342 :AEEF273A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:57:14.301 2025-07-07 21:57:14.315345 :AEEF273B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13932 pso_num=22 pso_serial#=85 2025-07-07 21:57:14.315517 :AEEF273C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13932 (legacy spawn) 2025-07-07 21:57:14.321960 :AEEF273D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:57:14.322134 :AEEF2742:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:57:14.322935 :AEEF2747:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:57:14.331617 :AEEF274A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:57:14.316 2025-07-07 21:57:14.331619 :AEEF274B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7436 pso_num=22 pso_serial#=86 2025-07-07 21:57:14.331753 :AEEF274C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=7436 (legacy spawn) 2025-07-07 21:57:14.338343 :AEEF274D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:57:14.338508 :AEEF2756:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:57:14.339314 :AEEF2757:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:57:15.468024 :AEEF27A8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:57:15.462 2025-07-07 21:57:15.468026 :AEEF27A9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14260 pso_num=22 pso_serial#=87 2025-07-07 21:57:15.468170 :AEEF27AA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14260 osp_idx=0 osp_ver=381639 osp_pg=0 (spawn #362501) 2025-07-07 21:57:15.468171 :AEEF27AB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-07 21:57:15.468172 :AEEF27AC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=162 time=606211361 2025-07-07 21:57:15.468172 :AEEF27AD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-07 21:57:15.468172 :AEEF27AE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-07 21:57:15.468173 :AEEF27AF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-07 21:57:15.468173 :AEEF27B0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-07 21:57:15.468173 :AEEF27B1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-07 21:57:15.468669 :AEEF27B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-07 21:57:15.469338 :AEEF27C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:57:15.476972 :AEEF27E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-07 21:57:15.477343 :AEEF27E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:57:15.477380 :AEEF27E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-07 21:57:15.478259 :AEEF27ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:57:15.478385 :AEEF27F6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:57:40.506954 :AEEF307D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-07 21:58:10.593498 :AEEF3A51:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:58:14.813042 :AEEF3B7F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:58:14.804 2025-07-07 21:58:14.813044 :AEEF3B80:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16840 pso_num=22 pso_serial#=88 2025-07-07 21:58:14.813183 :AEEF3B81:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16840 (legacy spawn) 2025-07-07 21:58:14.819799 :AEEF3B82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:58:14.819975 :AEEF3B87:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:58:14.820840 :AEEF3B8C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:58:15.559792 :AEEF3BC5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:58:15.554 2025-07-07 21:58:15.559794 :AEEF3BC6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10408 pso_num=22 pso_serial#=89 2025-07-07 21:58:15.559932 :AEEF3BC7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10408 osp_idx=0 osp_ver=381641 osp_pg=0 (spawn #362503) 2025-07-07 21:58:15.559932 :AEEF3BC8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-07 21:58:15.559933 :AEEF3BC9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=163 time=606271454 2025-07-07 21:58:15.559933 :AEEF3BCA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-07 21:58:15.559934 :AEEF3BCB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-07 21:58:15.559934 :AEEF3BCC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-07 21:58:15.559934 :AEEF3BCD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-07 21:58:15.559935 :AEEF3BCE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-07 21:58:15.560425 :AEEF3BCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-07 21:58:15.561047 :AEEF3BE4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:58:15.572219 :AEEF3C0C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-07 21:58:15.572701 :AEEF3C0D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-07 21:58:15.575171 :AEEF3C0E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-07 21:58:15.575496 :AEEF3C0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:58:15.575532 :AEEF3C12:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-07 21:58:15.576394 :AEEF3C18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:58:15.576525 :AEEF3C21:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:58:45.671759 :AEEF45C6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:58:45.914026 :AEEF45F1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:58:45.906 2025-07-07 21:58:45.914028 :AEEF45F2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18300 pso_num=22 pso_serial#=90 2025-07-07 21:58:45.914166 :AEEF45F3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18300 (legacy spawn) 2025-07-07 21:58:45.920664 :AEEF45F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:58:45.920694 :AEEF45F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:58:45.920840 :AEEF45FC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:58:45.921647 :AEEF460B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:59:03.208992 :AEEF4B6B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:59:03.194 2025-07-07 21:59:03.208994 :AEEF4B6C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13880 pso_num=22 pso_serial#=91 2025-07-07 21:59:03.209132 :AEEF4B6D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13880 (legacy spawn) 2025-07-07 21:59:03.215568 :AEEF4B6E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:03.215749 :AEEF4B73:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:59:03.216532 :AEEF4B78:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:59:03.225280 :AEEF4B7B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:59:03.210 2025-07-07 21:59:03.225282 :AEEF4B7C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15096 pso_num=22 pso_serial#=92 2025-07-07 21:59:03.225416 :AEEF4B7D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15096 (legacy spawn) 2025-07-07 21:59:03.231865 :AEEF4B7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:03.232025 :AEEF4B87:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:59:03.232856 :AEEF4B88:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:59:06.483326 :AEEF4C7F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:59:06.470 2025-07-07 21:59:06.483327 :AEEF4C80:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=580 pso_num=22 pso_serial#=93 2025-07-07 21:59:06.483464 :AEEF4C81:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=580 (legacy spawn) 2025-07-07 21:59:06.489952 :AEEF4C82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:06.490128 :AEEF4C87:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:59:06.490938 :AEEF4C8C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:59:06.499535 :AEEF4C8F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:59:06.486 2025-07-07 21:59:06.499537 :AEEF4C90:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4588 pso_num=22 pso_serial#=94 2025-07-07 21:59:06.499717 :AEEF4C91:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4588 (legacy spawn) 2025-07-07 21:59:06.521589 :AEEF4C92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:06.521753 :AEEF4C9A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:59:06.522736 :AEEF4C9C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:59:15.677281 :AEEF4F98:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:59:15.667 2025-07-07 21:59:15.677283 :AEEF4F99:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9416 pso_num=22 pso_serial#=95 2025-07-07 21:59:15.677411 :AEEF4F9A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9416 osp_idx=0 osp_ver=381647 osp_pg=0 (spawn #362505) 2025-07-07 21:59:15.677412 :AEEF4F9B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-07 21:59:15.677412 :AEEF4F9C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=164 time=606331564 2025-07-07 21:59:15.677412 :AEEF4F9D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-07 21:59:15.677413 :AEEF4F9E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-07 21:59:15.677413 :AEEF4F9F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-07 21:59:15.677413 :AEEF4FA0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-07 21:59:15.677413 :AEEF4FA1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-07 21:59:15.677898 :AEEF4FA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-07 21:59:15.678673 :AEEF4FB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:15.686320 :AEEF4FD3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-07 21:59:15.686693 :AEEF4FD4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:15.686730 :AEEF4FD7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-07 21:59:15.687642 :AEEF4FDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:15.687749 :AEEF4FE6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:59:45.799577 :AEEF5987:db_trace:ksl2.c@2324:kslwtbctx(): [10005:22:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-07-07 21:59:45.799591 :AEEF5988:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-07-07 21:59:45.799593 :AEEF5989:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:0] KSL WAIT END wait times (usecs) - snap=14, exc=14, tot=14 2025-07-07 21:59:45.799746 :AEEF598D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:59:48.145958 :AEEF5A6F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:59:48.138 2025-07-07 21:59:48.145960 :AEEF5A70:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13600 pso_num=22 pso_serial#=96 2025-07-07 21:59:48.146097 :AEEF5A71:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13600 (legacy spawn) 2025-07-07 21:59:48.152484 :AEEF5A72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:48.152518 :AEEF5A75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:48.152675 :AEEF5A78:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:59:48.153454 :AEEF5A89:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 21:59:51.724900 :AEEF5B7D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 21:59:51.716 2025-07-07 21:59:51.724903 :AEEF5B7E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15000 pso_num=22 pso_serial#=97 2025-07-07 21:59:51.725036 :AEEF5B7F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15000 (legacy spawn) 2025-07-07 21:59:51.731592 :AEEF5B93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 21:59:51.731752 :AEEF5B9C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 21:59:51.732565 :AEEF5B9D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-07 22:00:00.028582 :AEEF5E55:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-07 22:00:00.021 2025-07-07 22:00:00.028584 :AEEF5E56:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8164 pso_num=22 pso_serial#=98 2025-07-07 22:00:00.028693 :AEEF5E57:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8164 osp_idx=0 osp_ver=381650 osp_pg=0 (spawn #362507) 2025-07-07 22:00:00.028694 :AEEF5E58:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-07 22:00:00.028694 :AEEF5E59:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=165 time=606375923 2025-07-07 22:00:00.028695 :AEEF5E5A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-07 22:00:00.028695 :AEEF5E5B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-07 22:00:00.028695 :AEEF5E5C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-07 22:00:00.028695 :AEEF5E5D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-07 22:00:00.028696 :AEEF5E5E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-07 22:00:00.028770 :AEEF5E5F: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-07-07 22:00:00.028776 :AEEF5E60: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: 8164, VKRM)