Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250915220005\orcl_j005_23724_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:34716M/63366M, Ph+PgF:39306M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 31
Windows thread id: 23724, image: ORACLE.EXE (J005)


*** 2025-09-15 22:00:05.255
*** SESSION ID:(249.2023) 2025-09-15 22:00:05.255
 

*** 2025-09-15 22:00:05.255
Process diagnostic dump for ORACLE.EXE (J005), OS id=23724,
pid: 31, proc_ser: 221, sid: 249, sess_ser: 2023 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23724
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=0.221212 sec, exc=0.221212 sec, total=0.221212 sec
    wait times: max=0.500000 sec, heur=4.737338 sec
    wait counts: calls=1 os=1
    in_wait=1 iflags=0x5a8
Wait State:
  fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1
Session Wait History:
    elapsed time of 0.000014 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=0.510118 sec, exc=0.510118 sec, total=0.510118 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=0.510158 sec, exc=0.510158 sec, total=0.510158 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000012 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 snap_id=1
    wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=1 seq_num=2 snap_id=1
    wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.495770 sec, exc=0.495770 sec, total=0.495770 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000000 sec of elapsed time
Sampled Session History of session 249 serial 2023
---------------------------------------------------
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
---------------------------------------------------
  [5 samples,                                              22:00:00 - 22:00:05]
    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-09-15 22:00:05.255
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 31 (osid: 23724, J005)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-09 22:00:02.043700 :CB4313E7:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 22:00:02.043700 :CB4313E8:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 22:00:02.043701 :CB4313E9:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 22:00:02.044304 :CB4313EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-09 22:00:32.152502 :CB432648:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-09 23:59:10.021628 :CB4CBB48:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-09 23:59:10.010
2025-09-09 23:59:10.021630 :CB4CBB49:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23876 pso_num=31 pso_serial#=199
2025-09-09 23:59:10.021778 :CB4CBB4A:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23876 (legacy spawn)
2025-09-09 23:59:10.036746 :CB4CBB4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 23:59:13.283075 :CB4CBC5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 23:59:13.283259 :CB4CBC60:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-09-09 23:59:13.284507 :CB4CBC64:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-09 23:59:13.410657 :CB4CBC67:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-09 23:59:13.401
2025-09-09 23:59:13.410660 :CB4CBC68:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16664 pso_num=31 pso_serial#=200
2025-09-09 23:59:13.410796 :CB4CBC69:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16664 (legacy spawn)
2025-09-09 23:59:13.424620 :CB4CBC6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 23:59:46.472624 :CB4CC897:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-09 23:59:46.897417 :CB4CC8A7:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-09 23:59:46.891
2025-09-09 23:59:46.897420 :CB4CC8A8:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22852 pso_num=31 pso_serial#=201
2025-09-09 23:59:46.897571 :CB4CC8A9:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22852 (legacy spawn)
2025-09-09 23:59:46.912098 :CB4CC8AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 23:59:50.129360 :CB4CCA14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 23:59:50.129571 :CB4CCA19:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-09-09 23:59:50.130822 :CB4CCA1E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-09 23:59:51.826744 :CB4CCABF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-09 23:59:51.812
2025-09-09 23:59:51.826746 :CB4CCAC0:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12980 pso_num=31 pso_serial#=202
2025-09-09 23:59:51.826902 :CB4CCAC1:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12980 (legacy spawn)
2025-09-09 23:59:51.843197 :CB4CCAC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-10 00:00:25.358180 :CB4CD5FB:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-10 22:00:02.049708 :CBB3FC64:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-10 22:00:02.041
2025-09-10 22:00:02.049710 :CBB3FC67:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14644 pso_num=31 pso_serial#=203
2025-09-10 22:00:02.049917 :CBB3FC68:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=14644 osp_idx=29 osp_ver=967 osp_pg=0 (spawn #579169)
2025-09-10 22:00:02.049917 :CBB3FC69:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-10 22:00:02.049918 :CBB3FC6A:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=24 time=1927253346
2025-09-10 22:00:02.049918 :CBB3FC6B:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-10 22:00:02.049918 :CBB3FC6C:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-10 22:00:02.049919 :CBB3FC6D:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-10 22:00:02.049919 :CBB3FC6E:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-10 22:00:02.049920 :CBB3FC6F:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-10 22:00:02.050695 :CBB3FC78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-10 22:00:32.106672 :CBB40EB9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-10 23:59:09.741855 :CBBD6902:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-10 23:59:09.732
2025-09-10 23:59:09.741857 :CBBD6903:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23408 pso_num=31 pso_serial#=204
2025-09-10 23:59:09.742013 :CBBD6904:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23408 (legacy spawn)
2025-09-10 23:59:09.748859 :CBBD6905:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-10 23:59:09.749041 :CBBD690E:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-09-10 23:59:09.749924 :CBBD690F:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-10 23:59:10.299002 :CBBD6937:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-10 23:59:10.295
2025-09-10 23:59:10.299004 :CBBD6938:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14932 pso_num=31 pso_serial#=205
2025-09-10 23:59:10.299150 :CBBD6939:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14932 (legacy spawn)
2025-09-10 23:59:10.313579 :CBBD693A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-10 23:59:43.797255 :CBBD74ED:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-10 23:59:44.946020 :CBBD7560:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-10 23:59:44.939
2025-09-10 23:59:44.946022 :CBBD7561:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21708 pso_num=31 pso_serial#=206
2025-09-10 23:59:44.946178 :CBBD7562:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=21708 osp_idx=29 osp_ver=970 osp_pg=0 (spawn #579445)
2025-09-10 23:59:44.946179 :CBBD7563:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-10 23:59:44.946181 :CBBD7564:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=25 time=1934436252
2025-09-10 23:59:44.946181 :CBBD7565:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-10 23:59:44.946181 :CBBD7566:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-10 23:59:44.946181 :CBBD7567:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-10 23:59:44.946182 :CBBD7568:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-10 23:59:44.946182 :CBBD7569:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-10 23:59:44.946748 :CBBD756A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-10 23:59:44.947470 :CBBD757F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-10 23:59:44.955518 :CBBD759C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-09-10 23:59:44.955943 :CBBD759D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-10 23:59:44.955981 :CBBD75A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-09-10 23:59:44.956859 :CBBD75A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-10 23:59:44.956985 :CBBD75AF:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-09-11 00:00:15.026357 :CBBD7FEF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-11 22:00:00.436955 :CC24471B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-11 22:00:00.428
2025-09-11 22:00:00.436956 :CC24471C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=25496 pso_num=31 pso_serial#=207
2025-09-11 22:00:00.437094 :CC24471D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=25496 osp_idx=29 osp_ver=971 osp_pg=0 (spawn #582504)
2025-09-11 22:00:00.437095 :CC24471E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-11 22:00:00.437096 :CC24471F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=26 time=2013651737
2025-09-11 22:00:00.437097 :CC244720:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-11 22:00:00.437097 :CC244721:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-11 22:00:00.437098 :CC244722:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-11 22:00:00.437098 :CC244723:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-11 22:00:00.437099 :CC244724:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-11 22:00:00.437606 :CC24472D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-11 22:00:30.536786 :CC2457E9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-11 23:59:10.181356 :CC2D9F70:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-11 23:59:10.169
2025-09-11 23:59:10.181359 :CC2D9F71:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21740 pso_num=31 pso_serial#=208
2025-09-11 23:59:10.181526 :CC2D9F72:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21740 (legacy spawn)
2025-09-11 23:59:10.196766 :CC2D9F73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-11 23:59:43.366243 :CC2DAB56:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-11 23:59:44.439384 :CC2DAB79:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-11 23:59:44.426
2025-09-11 23:59:44.439386 :CC2DAB7A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23620 pso_num=31 pso_serial#=209
2025-09-11 23:59:44.439528 :CC2DAB7B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23620 (legacy spawn)
2025-09-11 23:59:44.454053 :CC2DAB7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-12 00:00:17.485927 :CC2DB887:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-12 22:00:01.110779 :CC94A16B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-12 22:00:01.103
2025-09-12 22:00:01.110780 :CC94A16C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17500 pso_num=31 pso_serial#=210
2025-09-12 22:00:01.110931 :CC94A16D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17500 osp_idx=29 osp_ver=974 osp_pg=0 (spawn #585835)
2025-09-12 22:00:01.110932 :CC94A16E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-12 22:00:01.110933 :CC94A16F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=27 time=2100052408
2025-09-12 22:00:01.110933 :CC94A170:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-12 22:00:01.110934 :CC94A171:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-12 22:00:01.110934 :CC94A172:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-12 22:00:01.110934 :CC94A173:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-12 22:00:01.110935 :CC94A174:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-12 22:00:01.111514 :CC94A17E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-12 22:00:31.178914 :CC94B27A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-12 23:59:10.244727 :CC9E18CA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-12 23:59:10.230
2025-09-12 23:59:10.244729 :CC9E18CB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24896 pso_num=31 pso_serial#=211
2025-09-12 23:59:10.244870 :CC9E18CC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24896 (legacy spawn)
2025-09-12 23:59:10.259065 :CC9E18CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-12 23:59:43.732609 :CC9E24B6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-12 23:59:43.915776 :CC9E24B9:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-12 23:59:43.901
2025-09-12 23:59:43.915778 :CC9E24BA:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19888 pso_num=31 pso_serial#=212
2025-09-12 23:59:43.915920 :CC9E24BB:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19888 (legacy spawn)
2025-09-12 23:59:43.929924 :CC9E24BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-13 00:00:16.962423 :CC9E3217:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-13 06:00:02.054117 :CCBAF6F7:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-13 06:00:02.051
2025-09-13 06:00:02.054118 :CCBAF6F8:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17248 pso_num=31 pso_serial#=213
2025-09-13 06:00:02.054249 :CCBAF6FD:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17248 osp_idx=29 osp_ver=977 osp_pg=0 (spawn #586943)
2025-09-13 06:00:02.054250 :CCBAF6FE:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-13 06:00:02.054250 :CCBAF6FF:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=28 time=2128853346
2025-09-13 06:00:02.054251 :CCBAF700:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-13 06:00:02.054252 :CCBAF701:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-13 06:00:02.054252 :CCBAF702:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-13 06:00:02.054253 :CCBAF703:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-13 06:00:02.054253 :CCBAF704:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-13 06:00:02.054753 :CCBAF705:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-13 06:00:32.147332 :CCBB09BC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-13 23:59:09.826317 :CD10BF60:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-13 23:59:09.817
2025-09-13 23:59:09.826319 :CD10BF61:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24556 pso_num=31 pso_serial#=214
2025-09-13 23:59:09.826460 :CD10BF62:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24556 (legacy spawn)
2025-09-13 23:59:09.840477 :CD10BF63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-13 23:59:43.109565 :CD10CB2C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-13 23:59:43.619673 :CD10CB74:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-13 23:59:43.606
2025-09-13 23:59:43.619677 :CD10CB75:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24848 pso_num=31 pso_serial#=215
2025-09-13 23:59:43.619912 :CD10CB76:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24848 (legacy spawn)
2025-09-13 23:59:43.634565 :CD10CB77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 00:00:16.684165 :CD10D87B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-14 06:00:02.080711 :CD2CC34E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-14 06:00:02.077
2025-09-14 06:00:02.080712 :CD2CC34F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14152 pso_num=31 pso_serial#=216
2025-09-14 06:00:02.080847 :CD2CC350:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=14152 osp_idx=29 osp_ver=980 osp_pg=0 (spawn #590287)
2025-09-14 06:00:02.080848 :CD2CC351:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 06:00:02.080848 :CD2CC352:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=29 time=2215253377
2025-09-14 06:00:02.080849 :CD2CC353:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 06:00:02.080849 :CD2CC354:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 06:00:02.080849 :CD2CC355:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 06:00:02.080850 :CD2CC356:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 06:00:02.080850 :CD2CC357:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 06:00:02.081424 :CD2CC359:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 06:00:32.178311 :CD2CD6E5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-14 23:59:09.668594 :CD8289A0:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-14 23:59:09.667
2025-09-14 23:59:09.668597 :CD8289A1:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21264 pso_num=31 pso_serial#=217
2025-09-14 23:59:09.668750 :CD8289A2:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21264 (legacy spawn)
2025-09-14 23:59:09.683710 :CD8289A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 23:59:42.993479 :CD8295C8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-14 23:59:47.214221 :CD82974C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-14 23:59:47.203
2025-09-14 23:59:47.214223 :CD82974D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=25076 pso_num=31 pso_serial#=218
2025-09-14 23:59:47.214375 :CD82974E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=25076 (legacy spawn)
2025-09-14 23:59:47.221486 :CD82974F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 23:59:47.221676 :CD829758:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-09-14 23:59:47.222591 :CD829759:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-14 23:59:50.509084 :CD829862:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-14 23:59:50.506
2025-09-14 23:59:50.509086 :CD829863:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11336 pso_num=31 pso_serial#=219
2025-09-14 23:59:50.509228 :CD829864:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11336 (legacy spawn)
2025-09-14 23:59:50.523899 :CD829865:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 00:00:23.916881 :CD82A749:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-15 16:00:16.993734 :CDCE9E01:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 16:00:16.983
2025-09-15 16:00:16.993735 :CDCE9E02:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8216 pso_num=31 pso_serial#=220
2025-09-15 16:00:16.993867 :CDCE9E03:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=8216 osp_idx=29 osp_ver=984 osp_pg=0 (spawn #595015)
2025-09-15 16:00:16.993868 :CDCE9E04:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-15 16:00:16.993869 :CDCE9E05:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=30 time=2337668299
2025-09-15 16:00:16.993870 :CDCE9E06:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 16:00:16.993870 :CDCE9E07:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 16:00:16.993871 :CDCE9E08:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 16:00:16.993871 :CDCE9E09:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 16:00:16.993871 :CDCE9E0A:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 16:00:16.995208 :CDCE9E0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-15 16:00:16.995272 :CDCE9E11:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-09-15 16:00:17.003966 :CDCE9E23:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-15 22:00:00.519793 :CDEB2659:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 22:00:00.516
2025-09-15 22:00:00.519794 :CDEB265A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23724 pso_num=31 pso_serial#=221
2025-09-15 22:00:00.519977 :CDEB268D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=23724 osp_idx=29 osp_ver=985 osp_pg=0 (spawn #595849)
2025-09-15 22:00:00.519978 :CDEB268F:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-15 22:00:00.519979 :CDEB2690:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=31 time=2359251815
2025-09-15 22:00:00.519979 :CDEB2691:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 22:00:00.519980 :CDEB2692:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 22:00:00.519981 :CDEB2693:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 22:00:00.519981 :CDEB2694:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 22:00:00.519982 :CDEB2696:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 22:00:00.520546 :CDEB26BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 31 (osid: 23724, J005)