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


*** 2025-09-12 22:00:08.893
*** SESSION ID:(249.2001) 2025-09-12 22:00:08.893
 

*** 2025-09-12 22:00:08.893
Process diagnostic dump for ORACLE.EXE (J005), OS id=17500,
pid: 31, proc_ser: 210, sid: 249, sess_ser: 2001 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17500
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=15 seq_num=16 snap_id=1
    wait times: snap=0.266763 sec, exc=0.266763 sec, total=0.266763 sec
    wait times: max=0.500000 sec, heur=7.787966 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.000012 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=14 seq_num=15 snap_id=1
    wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=13 seq_num=14 snap_id=1
    wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=12 seq_num=13 snap_id=1
    wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=11 seq_num=12 snap_id=1
    wait times: snap=0.510103 sec, exc=0.510103 sec, total=0.510103 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=10 seq_num=11 snap_id=1
    wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=0.510110 sec, exc=0.510110 sec, total=0.510110 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000012 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 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
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000011 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.499982 sec, exc=0.499982 sec, total=0.499982 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
Sampled Session History of session 249 serial 2001
---------------------------------------------------
The sampled session history is constructed by sampling
the target session every 1 second. The sampling process
captures at each sample if the session is in a non-idle wait,
an idle wait, or not in a wait. If the session is in a
non-idle wait then one interval is shown for all the samples
the session was in the same non-idle wait. If the
session is in an idle wait or not in a wait for
consecutive samples then one interval is shown for all
the consecutive samples. Though we display these consecutive
samples  in a single interval the session may NOT be continuously
idle or not in a wait (the sampling process does not know).
 
The history is displayed in reverse chronological order.
 
sample interval: 1 sec, max history 120 sec
---------------------------------------------------
  [8 samples,                                              22:00:01 - 22:00:08]
    idle wait at each sample
  [session created at: 22:00:01]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-12 22:00:08.893
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 31 (osid: 17500, J005)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-07 00:04:53.315507 :C9F8EE43:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-07 06:00:00.878307 :CA14AC71:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 06:00:00.875
2025-09-07 06:00:00.878308 :CA14AC72:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22460 pso_num=31 pso_serial#=187
2025-09-07 06:00:00.878442 :CA14AC73:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=22460 osp_idx=29 osp_ver=951 osp_pg=0 (spawn #566950)
2025-09-07 06:00:00.878442 :CA14AC74: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-07 06:00:00.878443 :CA14AC75:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=21 time=1610452174
2025-09-07 06:00:00.878443 :CA14AC76:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 06:00:00.878444 :CA14AC77:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 06:00:00.878444 :CA14AC78:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 06:00:00.878444 :CA14AC79:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 06:00:00.878445 :CA14AC7A:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 06:00:00.878986 :CA14AC7B: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-07 06:00:30.975027 :CA14BF51:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-07 23:59:07.586717 :CA69C6A8:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 23:59:07.573
2025-09-07 23:59:07.586719 :CA69C6A9:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23280 pso_num=31 pso_serial#=188
2025-09-07 23:59:07.586855 :CA69C6AA:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23280 (legacy spawn)
2025-09-07 23:59:07.593698 :CA69C6AB: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-07 23:59:07.593878 :CA69C6B0: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-07 23:59:07.594659 :CA69C6B5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-07 23:59:07.603697 :CA69C6B8:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 23:59:07.589
2025-09-07 23:59:07.603699 :CA69C6B9:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22184 pso_num=31 pso_serial#=189
2025-09-07 23:59:07.603842 :CA69C6BA:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22184 (legacy spawn)
2025-09-07 23:59:07.610669 :CA69C6BB: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-07 23:59:07.610836 :CA69C6C4: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-07 23:59:07.611643 :CA69C6C5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-07 23:59:09.606384 :CA69C784:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 23:59:09.606
2025-09-07 23:59:09.606386 :CA69C785:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19908 pso_num=31 pso_serial#=190
2025-09-07 23:59:09.606540 :CA69C786:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19908 (legacy spawn)
2025-09-07 23:59:09.622032 :CA69C787: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-07 23:59:42.944492 :CA69D412:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-07 23:59:44.060072 :CA69D45A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 23:59:44.052
2025-09-07 23:59:44.060074 :CA69D45B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24228 pso_num=31 pso_serial#=191
2025-09-07 23:59:44.060234 :CA69D45C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24228 (legacy spawn)
2025-09-07 23:59:44.068030 :CA69D45D: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-07 23:59:44.068214 :CA69D462: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-07 23:59:44.069387 :CA69D467:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-07 23:59:44.079359 :CA69D46A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 23:59:44.067
2025-09-07 23:59:44.079362 :CA69D46B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23912 pso_num=31 pso_serial#=192
2025-09-07 23:59:44.079515 :CA69D46C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23912 (legacy spawn)
2025-09-07 23:59:44.087002 :CA69D47B: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-07 23:59:44.087206 :CA69D484: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-07 23:59:44.088198 :CA69D485:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-07 23:59:45.814843 :CA69D536:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 23:59:45.802
2025-09-07 23:59:45.814845 :CA69D537:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22988 pso_num=31 pso_serial#=193
2025-09-07 23:59:45.814985 :CA69D538:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22988 (legacy spawn)
2025-09-07 23:59:45.829042 :CA69D539: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-07 23:59:49.035145 :CA69D647: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-07 23:59:49.035329 :CA69D64C: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-07 23:59:49.036566 :CA69D651:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-07 23:59:50.515425 :CA69D6AB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 23:59:50.513
2025-09-07 23:59:50.515427 :CA69D6AC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22836 pso_num=31 pso_serial#=194
2025-09-07 23:59:50.515575 :CA69D6AD:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22836 (legacy spawn)
2025-09-07 23:59:50.531125 :CA69D6AE: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-08 00:00:23.995146 :CA69E25F:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-08 22:00:02.060855 :CAD27B6D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-08 22:00:02.054
2025-09-08 22:00:02.060856 :CAD27B6E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22852 pso_num=31 pso_serial#=195
2025-09-08 22:00:02.061009 :CAD27B6F:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=22852 osp_idx=29 osp_ver=959 osp_pg=0 (spawn #572497)
2025-09-08 22:00:02.061010 :CAD27B70: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-08 22:00:02.061010 :CAD27B71:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=22 time=1754453362
2025-09-08 22:00:02.061011 :CAD27B72:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-08 22:00:02.061012 :CAD27B73:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-08 22:00:02.061012 :CAD27B74:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-08 22:00:02.061013 :CAD27B75:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-08 22:00:02.061013 :CAD27B76:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-08 22:00:02.061574 :CAD27B77: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-08 22:00:32.163115 :CAD28F6C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-08 23:59:09.906005 :CADC6A71:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-08 23:59:09.893
2025-09-08 23:59:09.906007 :CADC6A72:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20612 pso_num=31 pso_serial#=196
2025-09-08 23:59:09.906147 :CADC6A73:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20612 (legacy spawn)
2025-09-08 23:59:09.920188 :CADC6A74: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-08 23:59:43.004607 :CADC7734:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-08 23:59:44.237247 :CADC777F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-08 23:59:44.224
2025-09-08 23:59:44.237249 :CADC7780:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19060 pso_num=31 pso_serial#=197
2025-09-08 23:59:44.237385 :CADC7781:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19060 (legacy spawn)
2025-09-08 23:59:44.252152 :CADC7782: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 00:00:17.749007 :CADC832B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-09 22:00:02.043522 :CB4313D8:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-09 22:00:02.036
2025-09-09 22:00:02.043523 :CB4313D9:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23672 pso_num=31 pso_serial#=198
2025-09-09 22:00:02.043696 :CB4313E2:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=23672 osp_idx=29 osp_ver=962 osp_pg=0 (spawn #575834)
2025-09-09 22:00:02.043697 :CB4313E3: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-09 22:00:02.043698 :CB4313E4:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=23 time=1840853346
2025-09-09 22:00:02.043699 :CB4313E5:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 22:00:02.043699 :CB4313E6:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
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
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 31 (osid: 17500, J005)