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


*** 2025-09-17 22:00:10.896
*** SESSION ID:(249.2051) 2025-09-17 22:00:10.896
 

*** 2025-09-17 22:00:10.896
Process diagnostic dump for ORACLE.EXE (J005), OS id=20048,
pid: 31, proc_ser: 234, sid: 249, sess_ser: 2051 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20048
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=17 seq_num=18 snap_id=1
    wait times: snap=0.235890 sec, exc=0.235890 sec, total=0.235890 sec
    wait times: max=0.500000 sec, heur=8.773450 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.000009 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=16 seq_num=17 snap_id=1
    wait times: snap=0.514642 sec, exc=0.514642 sec, total=0.514642 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=15 seq_num=16 snap_id=1
    wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000006 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=14 seq_num=15 snap_id=1
    wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=13 seq_num=14 snap_id=1
    wait times: snap=0.510097 sec, exc=0.510097 sec, total=0.510097 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000011 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=12 seq_num=13 snap_id=1
    wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=11 seq_num=12 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.000012 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=10 seq_num=11 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.000010 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 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=8 seq_num=9 snap_id=1
    wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 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=7 seq_num=8 snap_id=1
    wait times: snap=0.510093 sec, exc=0.510093 sec, total=0.510093 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000015 sec of elapsed time
Sampled Session History of session 249 serial 2051
---------------------------------------------------
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:02 - 22:00:10]
    idle wait at each sample
  [session created at: 22:00:02]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-17 22:00:10.896
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 31 (osid: 20048, J005)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
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
2025-09-15 22:00:30.612648 :CDEB3A31:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-15 23:59:16.309713 :CDF4B4FB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 23:59:16.304
2025-09-15 23:59:16.309715 :CDF4B4FC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18956 pso_num=31 pso_serial#=222
2025-09-15 23:59:16.309859 :CDF4B4FD:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=18956 osp_idx=29 osp_ver=986 osp_pg=0 (spawn #596123)
2025-09-15 23:59:16.309860 :CDF4B4FE: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 23:59:16.309861 :CDF4B4FF:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=32 time=2366407612
2025-09-15 23:59:16.309861 :CDF4B500:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 23:59:16.309862 :CDF4B501:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 23:59:16.309862 :CDF4B502:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 23:59:16.309862 :CDF4B503:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 23:59:16.309863 :CDF4B504:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 23:59:16.310388 :CDF4B505: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-15 23:59:16.311049 :CDF4B51A: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 23:59:16.318973 :CDF4B537:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-09-15 23:59:16.319367 :CDF4B538: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 23:59:16.319401 :CDF4B53B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-09-15 23:59:16.320317 :CDF4B541: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 23:59:16.320448 :CDF4B54A: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-15 23:59:46.379308 :CDF4C076:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-15 23:59:54.888084 :CDF4C35B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 23:59:54.888
2025-09-15 23:59:54.888087 :CDF4C35C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24944 pso_num=31 pso_serial#=223
2025-09-15 23:59:54.888259 :CDF4C35D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24944 (legacy spawn)
2025-09-15 23:59:54.903489 :CDF4C35E: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 23:59:58.227079 :CDF4C499: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 23:59:58.227246 :CDF4C49E: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-15 23:59:58.228438 :CDF4C4A3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-15 23:59:58.485222 :CDF4C4A6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-15 23:59:58.476
2025-09-15 23:59:58.485224 :CDF4C4A7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17404 pso_num=31 pso_serial#=224
2025-09-15 23:59:58.485407 :CDF4C4A8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17404 (legacy spawn)
2025-09-15 23:59:58.501429 :CDF4C4A9: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-16 00:06:18.460848 :CDF54F88: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-16 00:06:18.460879 :CDF54F8B: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-16 00:06:18.461020 :CDF54F92: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-16 00:06:18.462056 :CDF54F9F:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-16 22:00:02.103880 :CE5D7F75:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-16 22:00:02.096
2025-09-16 22:00:02.103881 :CE5D7F76:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24156 pso_num=31 pso_serial#=225
2025-09-16 22:00:02.104033 :CE5D7F78:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=24156 osp_idx=29 osp_ver=989 osp_pg=0 (spawn #599188)
2025-09-16 22:00:02.104033 :CE5D7F79: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-16 22:00:02.104034 :CE5D7F7A:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=33 time=2445653408
2025-09-16 22:00:02.104035 :CE5D7F7B:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-16 22:00:02.104035 :CE5D7F7C:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-16 22:00:02.104036 :CE5D7F7D:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-16 22:00:02.104037 :CE5D7F7E:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-16 22:00:02.104037 :CE5D7F7F:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-16 22:00:02.104714 :CE5D7F80: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-16 22:00:02.206177 :CE5D8237:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-16 22:00:32.271280 :CE5D958B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-16 23:59:12.907315 :CE671817:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-16 23:59:12.893
2025-09-16 23:59:12.907316 :CE671818:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23416 pso_num=31 pso_serial#=226
2025-09-16 23:59:12.907445 :CE67181E:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=23416 osp_idx=29 osp_ver=990 osp_pg=0 (spawn #599463)
2025-09-16 23:59:12.907446 :CE67181F: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-16 23:59:12.907446 :CE671820:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=34 time=2452804205
2025-09-16 23:59:12.907447 :CE671821:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-16 23:59:12.907447 :CE671822:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-16 23:59:12.907448 :CE671823:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-16 23:59:12.907448 :CE671824:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-16 23:59:12.907450 :CE671825:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-16 23:59:12.908176 :CE671826: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-16 23:59:27.911097 :CE671D81:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-16 23:59:57.996800 :CE6728FF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-17 00:00:07.098430 :CE672C19:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-17 00:00:07.095
2025-09-17 00:00:07.098431 :CE672C1A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=25112 pso_num=31 pso_serial#=227
2025-09-17 00:00:07.098562 :CE672C1B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=25112 (legacy spawn)
2025-09-17 00:00:07.104988 :CE672C1C: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-17 00:00:07.105166 :CE672C21: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-17 00:00:07.105956 :CE672C26:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-17 00:00:07.114745 :CE672C29:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-17 00:00:07.110
2025-09-17 00:00:07.114747 :CE672C2A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24468 pso_num=31 pso_serial#=228
2025-09-17 00:00:07.114927 :CE672C2B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24468 (legacy spawn)
2025-09-17 00:00:07.121331 :CE672C2C: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-17 00:00:07.121507 :CE672C35: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-17 00:00:07.122325 :CE672C36:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-17 00:00:10.201619 :CE672D6E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-17 00:00:10.188
2025-09-17 00:00:10.201621 :CE672D6F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22084 pso_num=31 pso_serial#=229
2025-09-17 00:00:10.201765 :CE672D70:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22084 (legacy spawn)
2025-09-17 00:00:10.208528 :CE672D71: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-17 00:00:10.208565 :CE672D74: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-17 00:00:10.208733 :CE672D7A: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-17 00:00:10.209555 :CE672D88:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-17 00:00:10.218307 :CE672D8B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-17 00:00:10.204
2025-09-17 00:00:10.218310 :CE672D8C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24688 pso_num=31 pso_serial#=230
2025-09-17 00:00:10.218484 :CE672D8D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24688 (legacy spawn)
2025-09-17 00:00:10.225058 :CE672D8E: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-17 00:00:10.225235 :CE672D97: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-17 00:00:10.226115 :CE672D98:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-17 00:00:29.557725 :CE673667:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-17 00:00:29.546
2025-09-17 00:00:29.557728 :CE673668:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20984 pso_num=31 pso_serial#=231
2025-09-17 00:00:29.557900 :CE673669:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20984 (legacy spawn)
2025-09-17 00:00:29.565496 :CE67366D: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-17 00:00:29.565680 :CE673675: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-17 00:00:29.566751 :CE673677:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-17 00:00:29.623673 :CE6736AA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-17 00:00:29.608
2025-09-17 00:00:29.623675 :CE6736AB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22408 pso_num=31 pso_serial#=232
2025-09-17 00:00:29.623820 :CE6736AC:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=22408 osp_idx=29 osp_ver=996 osp_pg=0 (spawn #599467)
2025-09-17 00:00:29.623821 :CE6736AD: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-17 00:00:29.623821 :CE6736AE:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=35 time=2452880924
2025-09-17 00:00:29.623821 :CE6736AF:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-17 00:00:29.623822 :CE6736B0:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-17 00:00:29.623822 :CE6736B1:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-17 00:00:29.623823 :CE6736B2:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-17 00:00:29.623823 :CE6736B3:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-17 00:00:29.624406 :CE6736B4: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-17 00:00:29.624449 :CE6736BA: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-17 00:00:29.632439 :CE6736C8: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-17 00:00:29.633537 :CE6736D0: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-17 00:00:29.634082 :CE6736D8: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-17 00:00:29.634565 :CE6736E0: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-17 00:00:29.634994 :CE6736E8: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-17 00:00:29.637696 :CE6736F0: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-17 00:00:29.638796 :CE6736F8: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-17 00:00:29.690185 :CE673700: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-17 00:00:29.691092 :CE673708: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-17 00:00:29.691332 :CE673710: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-17 00:00:29.691548 :CE673718: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-17 00:00:29.693551 :CE673720:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-17 00:04:28.665431 :CE678750:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-17 00:04:28.653
2025-09-17 00:04:28.665433 :CE678751:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11176 pso_num=31 pso_serial#=233
2025-09-17 00:04:28.665566 :CE678752:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11176 osp_idx=29 osp_ver=997 osp_pg=0 (spawn #599477)
2025-09-17 00:04:28.665567 :CE678753: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-17 00:04:28.665568 :CE678754:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=36 time=2453119955
2025-09-17 00:04:28.665568 :CE678755:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-17 00:04:28.665568 :CE678756:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-17 00:04:28.665568 :CE678757:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-17 00:04:28.665569 :CE678758:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-17 00:04:28.665569 :CE678759:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-17 00:04:28.666064 :CE67875A: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-17 00:04:28.666106 :CE678760: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-17 00:04:28.670141 :CE678778:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517
2025-09-17 00:04:28.670191 :CE678779:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517
2025-09-17 00:04:28.670438 :CE67877A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517
2025-09-17 00:04:28.670455 :CE67877B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517
2025-09-17 00:04:28.670677 :CE67877C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517
2025-09-17 00:04:28.670691 :CE67877D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517
2025-09-17 00:04:28.670895 :CE67877E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517
2025-09-17 00:04:28.670908 :CE67877F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517
2025-09-17 00:04:28.671112 :CE678780:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517
2025-09-17 00:04:28.671125 :CE678781:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517
2025-09-17 00:04:28.671329 :CE678782:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517
2025-09-17 00:04:28.671342 :CE678783:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517
2025-09-17 00:04:28.673839 :CE6787A6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-09-17 22:00:02.122335 :CECDD7E5:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-17 22:00:02.116
2025-09-17 22:00:02.122336 :CECDD7E6:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20048 pso_num=31 pso_serial#=234
2025-09-17 22:00:02.122488 :CECDD7E7:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=20048 osp_idx=29 osp_ver=998 osp_pg=0 (spawn #602514)
2025-09-17 22:00:02.122488 :CECDD7E8: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-17 22:00:02.122488 :CECDD7E9:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=37 time=2532053424
2025-09-17 22:00:02.122489 :CECDD7EA:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-09-17 22:00:02.122490 :CECDD7EB:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-09-17 22:00:02.122490 :CECDD7EC:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-09-17 22:00:02.122490 :CECDD7ED:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-17 22:00:02.122491 :CECDD7EE:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-17 22:00:02.123307 :CECDD7F8: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: 20048, J005)