Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250529220009\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:37240M/63366M, Ph+PgF:44123M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 24
Windows thread id: 15220, image: ORACLE.EXE (VKRM)


*** 2025-05-29 22:00:09.388
*** SESSION ID:(193.54689) 2025-05-29 22:00:09.388
 

*** 2025-05-29 22:00:09.388
Process diagnostic dump for ORACLE.EXE (VKRM), OS id=15220,
pid: 24, proc_ser: 23, sid: 193, sess_ser: 54689 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'VKRM Idle'
    =0x0, =0x0, =0x0
    wait_id=0 seq_num=3 snap_id=2
    wait times: snap=9.260679 sec, exc=9.260681 sec, total=9.361064 sec
    wait times: max=infinite, heur=9.361064 sec
    wait counts: calls=0 os=0
    in_wait=1 iflags=0x15a0
Wait State:
  fixed_waits=0 flags=0x23 boundary=0x0000000000000000/-1
Session Wait History:
    elapsed time of 0.000000 sec since current wait
 0: waited for 'resmgr:internal state change'
    location=0x3,  =0x0,  =0x0
    wait_id=1 seq_num=2 snap_id=1
    wait times: snap=0.100383 sec, exc=0.100383 sec, total=0.100383 sec
    wait times: max=0.100000 sec
    wait counts: calls=1 os=1
    occurred after 0.000000 sec of elapsed time
 1: waited for 'VKRM Idle'
    =0x0, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000000 sec of elapsed time
Sampled Session History of session 193 serial 54689
---------------------------------------------------
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:01 - 22:00:09]
    idle wait at each sample
  [1 sample,                                                          22:00:00]
    waited for 'resmgr:internal state change', seq_num: 2
      p1: 'location'=0x3
      p2: ' '=0x0
      p3: ' '=0x0
      time_waited: 0.100383 sec (sample interval: 0 sec)
  [1 sample,                                                          21:59:59]
    idle wait at each sample
  [session created at: 22:00:00]
---------------------------------------------------
Sampled Session History Summary:
  longest_non_idle_wait: 'resmgr:internal state change'
  [1 sample, 22:00:00 ]
      time_waited: 0.100383 sec (sample interval: 0 sec)
---------------------------------------------------
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-05-29 22:00:09.388
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 15220, VKRM)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-29 21:56:58.499894 :9DBA8AA9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14280 (legacy spawn)
2025-05-29 21:56:58.506414 :9DBA8AAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:56:58.506566 :9DBA8AB2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:56:58.507290 :9DBA8AB4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:56:58.514885 :9DBA8AB7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:56:58.500
2025-05-29 21:56:58.514886 :9DBA8AB8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15132 pso_num=24 pso_serial#=3
2025-05-29 21:56:58.515021 :9DBA8AB9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15132 (legacy spawn)
2025-05-29 21:56:58.521026 :9DBA8ABA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:56:58.521154 :9DBA8AC3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:56:58.521776 :9DBA8AC4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:57:01.349639 :9DBA8B9B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:57:01.344
2025-05-29 21:57:01.349640 :9DBA8B9C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13888 pso_num=24 pso_serial#=4
2025-05-29 21:57:01.349781 :9DBA8B9D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13888 osp_idx=22 osp_ver=339938 osp_pg=0 (spawn #232462)
2025-05-29 21:57:01.349782 :9DBA8B9E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-29 21:57:01.349782 :9DBA8B9F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=224 time=1531582125
2025-05-29 21:57:01.349783 :9DBA8BA0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-29 21:57:01.349783 :9DBA8BA1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-29 21:57:01.349784 :9DBA8BA2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-29 21:57:01.349784 :9DBA8BA3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-29 21:57:01.349784 :9DBA8BA4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-29 21:57:01.350277 :9DBA8BA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-29 21:57:01.350852 :9DBA8BBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:57:01.358921 :9DBA8BD7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-05-29 21:57:01.359288 :9DBA8BD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:57:01.359320 :9DBA8BDB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-05-29 21:57:01.360812 :9DBA8BE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:57:01.360915 :9DBA8BEA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:57:31.457980 :9DBA95CC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:57:34.599676 :9DBA96CF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:57:34.584
2025-05-29 21:57:34.599677 :9DBA96D0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11488 pso_num=24 pso_serial#=5
2025-05-29 21:57:34.599822 :9DBA96D1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11488 (legacy spawn)
2025-05-29 21:57:34.606269 :9DBA96D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:57:34.606416 :9DBA96D7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:57:34.607082 :9DBA96DC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:57:34.614764 :9DBA96DF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:57:34.600
2025-05-29 21:57:34.614765 :9DBA96E0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=5896 pso_num=24 pso_serial#=6
2025-05-29 21:57:34.614900 :9DBA96E1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=5896 (legacy spawn)
2025-05-29 21:57:34.620647 :9DBA96E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:57:34.620752 :9DBA96EB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:57:34.621368 :9DBA96EC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:57:37.651830 :9DBA97EA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:57:37.638
2025-05-29 21:57:37.651831 :9DBA97EB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14804 pso_num=24 pso_serial#=7
2025-05-29 21:57:37.651969 :9DBA97EC:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14804 (legacy spawn)
2025-05-29 21:57:37.658145 :9DBA97ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:57:37.658284 :9DBA97F2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:57:37.658934 :9DBA97F7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:57:37.666625 :9DBA97FA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:57:37.654
2025-05-29 21:57:37.666626 :9DBA97FB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14556 pso_num=24 pso_serial#=8
2025-05-29 21:57:37.666758 :9DBA97FC:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14556 (legacy spawn)
2025-05-29 21:57:37.672437 :9DBA97FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:57:37.672560 :9DBA9806:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:57:37.673178 :9DBA9807:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:58:01.324762 :9DBA9F8C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:58:01.312
2025-05-29 21:58:01.324763 :9DBA9F8D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11620 pso_num=24 pso_serial#=9
2025-05-29 21:58:01.324908 :9DBA9F8E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11620 (legacy spawn)
2025-05-29 21:58:01.331167 :9DBA9F8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:01.331303 :9DBA9F94:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:58:01.331949 :9DBA9F99:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:58:01.460328 :9DBA9FBF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:58:01.455
2025-05-29 21:58:01.460329 :9DBA9FC0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14388 pso_num=24 pso_serial#=10
2025-05-29 21:58:01.460471 :9DBA9FC1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14388 osp_idx=22 osp_ver=339944 osp_pg=0 (spawn #232464)
2025-05-29 21:58:01.460472 :9DBA9FC2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-29 21:58:01.460473 :9DBA9FC3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=225 time=1531642235
2025-05-29 21:58:01.460473 :9DBA9FC4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-29 21:58:01.460473 :9DBA9FC5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-29 21:58:01.460474 :9DBA9FC6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-29 21:58:01.460474 :9DBA9FC7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-29 21:58:01.460474 :9DBA9FC8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-29 21:58:01.460980 :9DBA9FC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-29 21:58:01.461573 :9DBA9FDE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:01.472918 :9DBA9FFA:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-05-29 21:58:01.473295 :9DBA9FFB:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-05-29 21:58:01.475729 :9DBA9FFC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-05-29 21:58:01.476051 :9DBA9FFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:01.476081 :9DBAA000:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-05-29 21:58:01.476927 :9DBAA006:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:01.477023 :9DBAA00F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:58:31.543144 :9DBAA9CF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:58:34.581476 :9DBAAAE3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:58:34.575
2025-05-29 21:58:34.581478 :9DBAAAE4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15356 pso_num=24 pso_serial#=11
2025-05-29 21:58:34.581623 :9DBAAAE5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15356 osp_idx=22 osp_ver=339945 osp_pg=0 (spawn #232466)
2025-05-29 21:58:34.581624 :9DBAAAE6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-29 21:58:34.581624 :9DBAAAE7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=226 time=1531675344
2025-05-29 21:58:34.581625 :9DBAAAE8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-29 21:58:34.581625 :9DBAAAE9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-29 21:58:34.581625 :9DBAAAEA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-29 21:58:34.581626 :9DBAAAEB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-29 21:58:34.581626 :9DBAAAEC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-29 21:58:34.582146 :9DBAAAED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-29 21:58:34.582180 :9DBAAAF5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-29 21:58:34.601131 :9DBAAB60:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:58:35.425413 :9DBAAB6F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:58:35.419
2025-05-29 21:58:35.425414 :9DBAAB70:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15292 pso_num=24 pso_serial#=12
2025-05-29 21:58:35.425552 :9DBAAB71:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15292 (legacy spawn)
2025-05-29 21:58:35.431971 :9DBAAB72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:35.432126 :9DBAAB7B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:58:35.432807 :9DBAAB7C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:58:44.816891 :9DBAAE5C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:58:44.808
2025-05-29 21:58:44.816892 :9DBAAE5D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11456 pso_num=24 pso_serial#=13
2025-05-29 21:58:44.817037 :9DBAAE5E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11456 (legacy spawn)
2025-05-29 21:58:44.823715 :9DBAAE5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:44.823866 :9DBAAE64:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:58:44.824584 :9DBAAE69:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:58:46.838706 :9DBAAF56:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:58:46.824
2025-05-29 21:58:46.838708 :9DBAAF57:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11212 pso_num=24 pso_serial#=14
2025-05-29 21:58:46.838846 :9DBAAF58:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11212 (legacy spawn)
2025-05-29 21:58:46.844987 :9DBAAF59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:46.845123 :9DBAAF5F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:58:46.845787 :9DBAAF63:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:58:46.853429 :9DBAAF66:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:58:46.840
2025-05-29 21:58:46.853430 :9DBAAF67:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14096 pso_num=24 pso_serial#=15
2025-05-29 21:58:46.853564 :9DBAAF68:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14096 (legacy spawn)
2025-05-29 21:58:46.859228 :9DBAAF69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:46.859352 :9DBAAF72:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:58:46.859967 :9DBAAF73:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:58:49.875701 :9DBAB062:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:58:49.862
2025-05-29 21:58:49.875703 :9DBAB063:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15112 pso_num=24 pso_serial#=16
2025-05-29 21:58:49.875840 :9DBAB064:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15112 (legacy spawn)
2025-05-29 21:58:49.895764 :9DBAB065:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:49.895899 :9DBAB06A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:58:49.896560 :9DBAB06F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:58:49.904371 :9DBAB072:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:58:49.894
2025-05-29 21:58:49.904372 :9DBAB073:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14264 pso_num=24 pso_serial#=17
2025-05-29 21:58:49.904504 :9DBAB074:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14264 (legacy spawn)
2025-05-29 21:58:49.910190 :9DBAB075:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:58:49.910297 :9DBAB07E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:58:49.910914 :9DBAB07F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:59:01.538138 :9DBAB41E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:59:01.533
2025-05-29 21:59:01.538139 :9DBAB41F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14948 pso_num=24 pso_serial#=18
2025-05-29 21:59:01.538285 :9DBAB420:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14948 osp_idx=22 osp_ver=339952 osp_pg=0 (spawn #232468)
2025-05-29 21:59:01.538286 :9DBAB421:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-29 21:59:01.538286 :9DBAB422:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=227 time=1531702313
2025-05-29 21:59:01.538286 :9DBAB423:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-29 21:59:01.538286 :9DBAB424:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-29 21:59:01.538287 :9DBAB425:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-29 21:59:01.538287 :9DBAB426:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-29 21:59:01.538287 :9DBAB427:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-29 21:59:01.538798 :9DBAB428:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-29 21:59:01.539398 :9DBAB43D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:59:01.547479 :9DBAB459:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-05-29 21:59:01.547836 :9DBAB45A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:59:01.547867 :9DBAB45D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-05-29 21:59:01.548715 :9DBAB463:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:59:01.548805 :9DBAB46C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:59:31.662207 :9DBABE92:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:59:43.743426 :9DBAC28F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:59:43.735
2025-05-29 21:59:43.743427 :9DBAC290:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14120 pso_num=24 pso_serial#=19
2025-05-29 21:59:43.743571 :9DBAC291:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14120 (legacy spawn)
2025-05-29 21:59:43.750052 :9DBAC292:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:59:43.750203 :9DBAC297:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:59:43.750928 :9DBAC29C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:59:47.067818 :9DBAC3A4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:59:47.059
2025-05-29 21:59:47.067819 :9DBAC3A5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7548 pso_num=24 pso_serial#=20
2025-05-29 21:59:47.067962 :9DBAC3A6:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7548 (legacy spawn)
2025-05-29 21:59:47.074546 :9DBAC3A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:59:47.088483 :9DBAC3AC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:59:47.089194 :9DBAC3B0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:59:59.087214 :9DBAC768:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:59:59.072
2025-05-29 21:59:59.087215 :9DBAC769:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13096 pso_num=24 pso_serial#=21
2025-05-29 21:59:59.087357 :9DBAC76A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13096 (legacy spawn)
2025-05-29 21:59:59.093670 :9DBAC76B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:59:59.093820 :9DBAC770:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:59:59.094499 :9DBAC774:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 21:59:59.107142 :9DBAC777:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 21:59:59.103
2025-05-29 21:59:59.107143 :9DBAC778:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13416 pso_num=24 pso_serial#=22
2025-05-29 21:59:59.107276 :9DBAC779:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13416 (legacy spawn)
2025-05-29 21:59:59.120729 :9DBAC77A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-29 21:59:59.120857 :9DBAC783:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-29 21:59:59.121500 :9DBAC784:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-29 22:00:00.027128 :9DBAC79F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 22:00:00.020
2025-05-29 22:00:00.027129 :9DBAC7A0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15220 pso_num=24 pso_serial#=23
2025-05-29 22:00:00.027231 :9DBAC7A1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15220 osp_idx=22 osp_ver=339957 osp_pg=0 (spawn #232470)
2025-05-29 22:00:00.027231 :9DBAC7A2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-29 22:00:00.027232 :9DBAC7A3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=228 time=1531760797
2025-05-29 22:00:00.027232 :9DBAC7A4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-29 22:00:00.027232 :9DBAC7A5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-29 22:00:00.027232 :9DBAC7A6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-29 22:00:00.027232 :9DBAC7A7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-29 22:00:00.027233 :9DBAC7A8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-29 22:00:00.027298 :9DBAC7A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-29 22:00:00.027302 :9DBAC7AA:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:24:0] kso: spawn diagnostics: init time=0 secs
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 24 (osid: 15220, VKRM)