Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250807220008\orcl_vkrm_19892_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:35638M/63366M, Ph+PgF:41122M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 19892, image: ORACLE.EXE (VKRM) *** 2025-08-07 22:00:08.631 *** SESSION ID:(177.55821) 2025-08-07 22:00:08.631 *** 2025-08-07 22:00:08.631 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=19892, pid: 22, proc_ser: 218, sid: 177, sess_ser: 55821 ------------------------------------------------------------------------------- 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=8.612868 sec, exc=8.612868 sec, total=8.612868 sec wait times: max=infinite, heur=8.612868 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 55821 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 22:00:00 - 22:00:08] 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-08-07 22:00:08.631 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 19892, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-07 21:56:36.386891 :BCB8E634:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16624 (legacy spawn) 2025-08-07 21:56:36.393432 :BCB8E635: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-08-07 21:56:36.393619 :BCB8E63E: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-08-07 21:56:36.394427 :BCB8E63F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:56:37.319365 :BCB8E68C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:56:37.311 2025-08-07 21:56:37.319367 :BCB8E68D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19392 pso_num=22 pso_serial#=197 2025-08-07 21:56:37.319512 :BCB8E68E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19392 (legacy spawn) 2025-08-07 21:56:37.325957 :BCB8E68F: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-08-07 21:56:37.326147 :BCB8E697: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-08-07 21:56:37.326952 :BCB8E699:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:57:01.432675 :BCB8EDFB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:57:01.425 2025-08-07 21:57:01.432677 :BCB8EDFC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19912 pso_num=22 pso_serial#=198 2025-08-07 21:57:01.432812 :BCB8EDFD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19912 (legacy spawn) 2025-08-07 21:57:01.439265 :BCB8EDFE: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-08-07 21:57:01.439433 :BCB8EE07: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-08-07 21:57:01.440208 :BCB8EE08:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:57:02.648733 :BCB8EE9A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:57:02.643 2025-08-07 21:57:02.648735 :BCB8EE9B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20964 pso_num=22 pso_serial#=199 2025-08-07 21:57:02.648872 :BCB8EE9C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20964 osp_idx=0 osp_ver=492421 osp_pg=0 (spawn #465803) 2025-08-07 21:57:02.648873 :BCB8EE9D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-07 21:57:02.648873 :BCB8EE9E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=80 time=3284491954 2025-08-07 21:57:02.648873 :BCB8EE9F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 21:57:02.648874 :BCB8EEA0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 21:57:02.648874 :BCB8EEA1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 21:57:02.648874 :BCB8EEA2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 21:57:02.648875 :BCB8EEA3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 21:57:02.649414 :BCB8EEA4: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-08-07 21:57:02.650091 :BCB8EEB9: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-08-07 21:57:02.657654 :BCB8EED5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-07 21:57:02.658016 :BCB8EED6: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-08-07 21:57:02.658051 :BCB8EED9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-07 21:57:02.658994 :BCB8EEDF: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-08-07 21:57:02.659122 :BCB8EEE7: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-08-07 21:57:32.717299 :BCB8F874:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:57:45.442554 :BCB8FC5A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:57:45.434 2025-08-07 21:57:45.442556 :BCB8FC5B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19536 pso_num=22 pso_serial#=200 2025-08-07 21:57:45.442752 :BCB8FC5C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19536 (legacy spawn) 2025-08-07 21:57:45.449214 :BCB8FC5D: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-08-07 21:57:45.449398 :BCB8FC62: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-08-07 21:57:45.450198 :BCB8FC67:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:57:45.791528 :BCB8FC6A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:57:45.778 2025-08-07 21:57:45.791530 :BCB8FC6B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20172 pso_num=22 pso_serial#=201 2025-08-07 21:57:45.791669 :BCB8FC6C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20172 (legacy spawn) 2025-08-07 21:57:45.798121 :BCB8FC6D: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-08-07 21:57:45.798290 :BCB8FC76: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-08-07 21:57:45.799110 :BCB8FC77:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:57:45.807775 :BCB8FC7A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:57:45.793 2025-08-07 21:57:45.807777 :BCB8FC7B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18208 pso_num=22 pso_serial#=202 2025-08-07 21:57:45.807967 :BCB8FC7C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18208 (legacy spawn) 2025-08-07 21:57:45.814403 :BCB8FC90: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-08-07 21:57:45.814563 :BCB8FC99: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-08-07 21:57:45.815401 :BCB8FC9A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:57:48.901079 :BCB8FD8E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:57:48.888 2025-08-07 21:57:48.901081 :BCB8FD8F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19500 pso_num=22 pso_serial#=203 2025-08-07 21:57:48.901233 :BCB8FD90:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19500 (legacy spawn) 2025-08-07 21:57:48.907853 :BCB8FD91: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-08-07 21:57:48.908028 :BCB8FD9A: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-08-07 21:57:48.908842 :BCB8FD9B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:57:48.917559 :BCB8FD9E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:57:48.904 2025-08-07 21:57:48.917560 :BCB8FD9F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4160 pso_num=22 pso_serial#=204 2025-08-07 21:57:48.917696 :BCB8FDA0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4160 (legacy spawn) 2025-08-07 21:57:48.936421 :BCB8FDA1: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-08-07 21:57:48.936590 :BCB8FDAA: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-08-07 21:57:48.937446 :BCB8FDAB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:58:02.720826 :BCB901F4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:58:02.714 2025-08-07 21:58:02.720828 :BCB901F5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20800 pso_num=22 pso_serial#=205 2025-08-07 21:58:02.720980 :BCB901F6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20800 osp_idx=0 osp_ver=492427 osp_pg=0 (spawn #465805) 2025-08-07 21:58:02.720981 :BCB901F7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-07 21:58:02.720981 :BCB901F8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=81 time=3284552033 2025-08-07 21:58:02.720982 :BCB901F9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 21:58:02.720982 :BCB901FA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 21:58:02.720983 :BCB901FB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 21:58:02.720983 :BCB901FC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 21:58:02.720983 :BCB901FD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 21:58:02.721554 :BCB901FE: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-08-07 21:58:02.722220 :BCB90213: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-08-07 21:58:02.733244 :BCB90230:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-07 21:58:02.733707 :BCB90231:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-07 21:58:02.735974 :BCB90232:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-07 21:58:02.736361 :BCB90233: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-08-07 21:58:02.736397 :BCB90236:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-07 21:58:02.737259 :BCB9023C: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-08-07 21:58:02.737402 :BCB90245: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-08-07 21:58:32.802543 :BCB90BC5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:58:34.674452 :BCB90C3E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:58:34.662 2025-08-07 21:58:34.674454 :BCB90C3F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19244 pso_num=22 pso_serial#=206 2025-08-07 21:58:34.674595 :BCB90C40:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19244 (legacy spawn) 2025-08-07 21:58:34.681105 :BCB90C41: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-08-07 21:58:34.681284 :BCB90C4A: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-08-07 21:58:34.682075 :BCB90C4B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:58:53.542752 :BCB91252:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:58:53.533 2025-08-07 21:58:53.542755 :BCB91253:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21220 pso_num=22 pso_serial#=207 2025-08-07 21:58:53.542895 :BCB91254:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21220 (legacy spawn) 2025-08-07 21:58:53.549401 :BCB91255: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-08-07 21:58:53.549567 :BCB9125A: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-08-07 21:58:53.550366 :BCB9125F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:58:58.707313 :BCB913C5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:58:58.693 2025-08-07 21:58:58.707315 :BCB913C6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18796 pso_num=22 pso_serial#=208 2025-08-07 21:58:58.707450 :BCB913C7:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18796 (legacy spawn) 2025-08-07 21:58:58.713861 :BCB913C8: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-08-07 21:58:58.714036 :BCB913D1: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-08-07 21:58:58.714817 :BCB913D2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:58:58.723515 :BCB913D5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:58:58.708 2025-08-07 21:58:58.723517 :BCB913D6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19884 pso_num=22 pso_serial#=209 2025-08-07 21:58:58.723652 :BCB913D7:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19884 (legacy spawn) 2025-08-07 21:58:58.730075 :BCB913D8: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-08-07 21:58:58.730243 :BCB913E1: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-08-07 21:58:58.731048 :BCB913E2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:59:01.795340 :BCB914BE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:59:01.787 2025-08-07 21:59:01.795343 :BCB914BF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10620 pso_num=22 pso_serial#=210 2025-08-07 21:59:01.795480 :BCB914C0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10620 (legacy spawn) 2025-08-07 21:59:01.801967 :BCB914C1: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-08-07 21:59:01.802137 :BCB914CA: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-08-07 21:59:01.802946 :BCB914CB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:59:01.811627 :BCB914CE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:59:01.802 2025-08-07 21:59:01.811629 :BCB914CF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20052 pso_num=22 pso_serial#=211 2025-08-07 21:59:01.811762 :BCB914D0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20052 (legacy spawn) 2025-08-07 21:59:01.818243 :BCB914D1: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-08-07 21:59:01.818407 :BCB914DA: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-08-07 21:59:01.819222 :BCB914DB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:59:02.792954 :BCB9155F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:59:02.787 2025-08-07 21:59:02.792957 :BCB91560:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20016 pso_num=22 pso_serial#=212 2025-08-07 21:59:02.793097 :BCB91561:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20016 osp_idx=0 osp_ver=492434 osp_pg=0 (spawn #465807) 2025-08-07 21:59:02.793098 :BCB91562:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-07 21:59:02.793099 :BCB91563:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=82 time=3284612095 2025-08-07 21:59:02.793099 :BCB91564:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 21:59:02.793099 :BCB91565:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 21:59:02.793100 :BCB91566:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 21:59:02.793100 :BCB91567:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 21:59:02.793100 :BCB91568:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 21:59:02.793590 :BCB91569: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-08-07 21:59:02.794288 :BCB9157E: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-08-07 21:59:02.801786 :BCB9159A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-07 21:59:02.802207 :BCB9159B: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-08-07 21:59:02.802244 :BCB9159E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-07 21:59:02.803258 :BCB915A4: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-08-07 21:59:02.803491 :BCB915AC: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-08-07 21:59:32.870121 :BCB91F62:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:59:34.982726 :BCB91FE8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:59:34.979 2025-08-07 21:59:34.982728 :BCB91FE9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17956 pso_num=22 pso_serial#=213 2025-08-07 21:59:34.982884 :BCB91FEA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17956 (legacy spawn) 2025-08-07 21:59:34.989426 :BCB91FEB: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-08-07 21:59:34.989598 :BCB91FF4: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-08-07 21:59:34.990405 :BCB91FF5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:59:34.999240 :BCB91FF8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:59:34.995 2025-08-07 21:59:34.999241 :BCB91FF9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19824 pso_num=22 pso_serial#=214 2025-08-07 21:59:34.999375 :BCB91FFA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19824 (legacy spawn) 2025-08-07 21:59:35.005883 :BCB91FFB: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-08-07 21:59:35.006046 :BCB92004: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-08-07 21:59:35.006905 :BCB92005:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:59:36.800961 :BCB92099:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:59:36.792 2025-08-07 21:59:36.800963 :BCB9209A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16012 pso_num=22 pso_serial#=215 2025-08-07 21:59:36.801101 :BCB9209B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16012 (legacy spawn) 2025-08-07 21:59:36.807538 :BCB9209C: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-08-07 21:59:36.807708 :BCB920A1: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-08-07 21:59:36.808522 :BCB920A6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:59:38.079871 :BCB9210B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:59:38.066 2025-08-07 21:59:38.079873 :BCB9210C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11644 pso_num=22 pso_serial#=216 2025-08-07 21:59:38.080012 :BCB9210D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11644 (legacy spawn) 2025-08-07 21:59:38.086543 :BCB9210E: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-08-07 21:59:38.086713 :BCB92113: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-08-07 21:59:38.087509 :BCB92118:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 21:59:38.096293 :BCB9211B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 21:59:38.082 2025-08-07 21:59:38.096296 :BCB9211C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17124 pso_num=22 pso_serial#=217 2025-08-07 21:59:38.096428 :BCB9211D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17124 (legacy spawn) 2025-08-07 21:59:38.102901 :BCB9211E: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-08-07 21:59:38.103074 :BCB92127: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-08-07 21:59:38.103875 :BCB92128:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-07 22:00:00.025112 :BCB928B7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-07 22:00:00.018 2025-08-07 22:00:00.025114 :BCB928B8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19892 pso_num=22 pso_serial#=218 2025-08-07 22:00:00.025216 :BCB928B9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19892 osp_idx=0 osp_ver=492440 osp_pg=0 (spawn #465809) 2025-08-07 22:00:00.025217 :BCB928BA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-07 22:00:00.025217 :BCB928BB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=83 time=3284669329 2025-08-07 22:00:00.025218 :BCB928BC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 22:00:00.025218 :BCB928BD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 22:00:00.025218 :BCB928BE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 22:00:00.025219 :BCB928BF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 22:00:00.025219 :BCB928C0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 22:00:00.025288 :BCB928C1: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-08-07 22:00:00.025294 :BCB928C2: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: 19892, VKRM)