Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250721221952\orcl_m002_15600_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:36134M/63366M, Ph+PgF:42015M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 29
Windows thread id: 15600, image: ORACLE.EXE (M002)


*** 2025-07-21 22:19:52.297
*** SESSION ID:(233.29727) 2025-07-21 22:19:52.297
 

*** 2025-07-21 22:19:52.297
Process diagnostic dump for ORACLE.EXE (M002), OS id=15600,
pid: 29, proc_ser: 21, sid: 233, sess_ser: 29727 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=439 seq_num=440 snap_id=1
    wait times: snap=0.002540 sec, exc=0.002540 sec, total=0.002540 sec
    wait times: max=infinite, heur=0.502470 sec
    wait counts: calls=0 os=0
    in_wait=1 iflags=0x5a0
Wait State:
  fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1
Session Wait History:
    elapsed time of 0.000008 sec since current wait
 0: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=438 seq_num=439 snap_id=1
    wait times: snap=0.000345 sec, exc=0.000345 sec, total=0.000345 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000007 sec of elapsed time
 1: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=437 seq_num=438 snap_id=1
    wait times: snap=0.000120 sec, exc=0.000120 sec, total=0.000120 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000011 sec of elapsed time
 2: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=436 seq_num=437 snap_id=1
    wait times: snap=0.000122 sec, exc=0.000122 sec, total=0.000122 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000010 sec of elapsed time
 3: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=435 seq_num=436 snap_id=1
    wait times: snap=0.000076 sec, exc=0.000076 sec, total=0.000076 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000019 sec of elapsed time
 4: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=434 seq_num=435 snap_id=1
    wait times: snap=0.006446 sec, exc=0.006446 sec, total=0.006446 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000020 sec of elapsed time
 5: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=433 seq_num=434 snap_id=1
    wait times: snap=0.005666 sec, exc=0.005666 sec, total=0.005666 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000019 sec of elapsed time
 6: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=432 seq_num=433 snap_id=1
    wait times: snap=0.004584 sec, exc=0.004584 sec, total=0.004584 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000008 sec of elapsed time
 7: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=431 seq_num=432 snap_id=1
    wait times: snap=0.000063 sec, exc=0.000063 sec, total=0.000063 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000009 sec of elapsed time
 8: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=430 seq_num=431 snap_id=1
    wait times: snap=0.000056 sec, exc=0.000056 sec, total=0.000056 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000007 sec of elapsed time
 9: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=429 seq_num=430 snap_id=1
    wait times: snap=0.000071 sec, exc=0.000071 sec, total=0.000071 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000021 sec of elapsed time
Sampled Session History of session 233 serial 29727
---------------------------------------------------
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
---------------------------------------------------
  [1 sample,                                                          22:19:52]
    idle wait at each sample
  [session created at: 22:19:51]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-21 22:19:52.297
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 29 (osid: 15600, M002)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-21 00:03:10.690223 :B4C4C80D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15404 (legacy spawn)
2025-07-21 00:03:10.696763 :B4C4C80E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:03:10.696948 :B4C4C813:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:03:10.697822 :B4C4C818:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:03:10.706830 :B4C4C81B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:03:10.697
2025-07-21 00:03:10.706832 :B4C4C81C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19360 pso_num=29 pso_serial#=2
2025-07-21 00:03:10.706972 :B4C4C81D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19360 (legacy spawn)
2025-07-21 00:03:10.713627 :B4C4C81E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:03:10.713788 :B4C4C827:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:03:10.714684 :B4C4C828:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:04:17.350003 :B4C4DDBC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:17.340
2025-07-21 00:04:17.350005 :B4C4DDBD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16560 pso_num=29 pso_serial#=3
2025-07-21 00:04:17.350140 :B4C4DDBE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16560 (legacy spawn)
2025-07-21 00:04:17.356624 :B4C4DDBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:04:17.356800 :B4C4DDC8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:04:17.357588 :B4C4DDC9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:04:20.884989 :B4C4DEC7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:20.870
2025-07-21 00:04:20.884991 :B4C4DEC8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13400 pso_num=29 pso_serial#=4
2025-07-21 00:04:20.885134 :B4C4DEC9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13400 (legacy spawn)
2025-07-21 00:04:20.891590 :B4C4DECA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:04:20.891620 :B4C4DECD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:04:20.891764 :B4C4DED0:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:04:20.892585 :B4C4DEE1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:04:20.901243 :B4C4DEE4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:20.886
2025-07-21 00:04:20.901245 :B4C4DEE5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13944 pso_num=29 pso_serial#=5
2025-07-21 00:04:20.901414 :B4C4DEE6:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13944 (legacy spawn)
2025-07-21 00:04:20.907888 :B4C4DEE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:04:20.908059 :B4C4DEF0:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:04:20.908843 :B4C4DEF1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:04:24.009191 :B4C4E038:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:23.995
2025-07-21 00:04:24.009193 :B4C4E039:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19364 pso_num=29 pso_serial#=6
2025-07-21 00:04:24.009333 :B4C4E03A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19364 (legacy spawn)
2025-07-21 00:04:24.015824 :B4C4E03B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:04:24.015855 :B4C4E03E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:04:24.016009 :B4C4E041:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:04:24.016792 :B4C4E052:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:04:24.025398 :B4C4E055:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:24.011
2025-07-21 00:04:24.025400 :B4C4E056:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15532 pso_num=29 pso_serial#=7
2025-07-21 00:04:24.025536 :B4C4E057:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15532 (legacy spawn)
2025-07-21 00:04:24.032038 :B4C4E058:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:04:24.032202 :B4C4E061:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:04:24.033022 :B4C4E062:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:05:00.495637 :B4C4EC64:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:00.482
2025-07-21 00:05:00.495639 :B4C4EC65:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4428 pso_num=29 pso_serial#=8
2025-07-21 00:05:00.495777 :B4C4EC66:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4428 (legacy spawn)
2025-07-21 00:05:00.502376 :B4C4EC67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:05:00.502556 :B4C4EC6D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:05:00.503366 :B4C4EC71:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:05:00.520963 :B4C4EC74:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:00.513
2025-07-21 00:05:00.520965 :B4C4EC75:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19076 pso_num=29 pso_serial#=9
2025-07-21 00:05:00.521105 :B4C4EC76:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19076 (legacy spawn)
2025-07-21 00:05:00.527593 :B4C4EC77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:05:00.527758 :B4C4EC80:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:05:00.528559 :B4C4EC81:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:05:01.568972 :B4C4ECDD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:01.560
2025-07-21 00:05:01.568974 :B4C4ECDE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18456 pso_num=29 pso_serial#=10
2025-07-21 00:05:01.569119 :B4C4ECDF:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18456 (legacy spawn)
2025-07-21 00:05:01.575932 :B4C4ECE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:05:01.576116 :B4C4ECE5:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:05:01.577032 :B4C4ECEA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:05:25.541240 :B4C4F73F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:25.532
2025-07-21 00:05:25.541243 :B4C4F740:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9144 pso_num=29 pso_serial#=11
2025-07-21 00:05:25.541394 :B4C4F741:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9144 (legacy spawn)
2025-07-21 00:05:25.548317 :B4C4F76E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:05:25.548515 :B4C4F773:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:05:25.549453 :B4C4F778:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:05:59.546944 :B4C507CE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:59.538
2025-07-21 00:05:59.546946 :B4C507CF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16632 pso_num=29 pso_serial#=12
2025-07-21 00:05:59.547084 :B4C507D0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16632 (legacy spawn)
2025-07-21 00:05:59.553513 :B4C507D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:05:59.553707 :B4C507D9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:05:59.554490 :B4C507DB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:06:03.747635 :B4C50AC0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:03.739
2025-07-21 00:06:03.747637 :B4C50AC1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15788 pso_num=29 pso_serial#=13
2025-07-21 00:06:03.747774 :B4C50AC2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15788 (legacy spawn)
2025-07-21 00:06:03.754278 :B4C50AC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:06:03.754466 :B4C50AC8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:06:03.755288 :B4C50ACD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:06:10.014034 :B4C50C9D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:09.999
2025-07-21 00:06:10.014037 :B4C50C9E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12108 pso_num=29 pso_serial#=14
2025-07-21 00:06:10.014180 :B4C50C9F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12108 (legacy spawn)
2025-07-21 00:06:10.020752 :B4C50CA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:06:10.020929 :B4C50CA9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:06:10.021767 :B4C50CAA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:06:10.030451 :B4C50CAD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:10.030
2025-07-21 00:06:10.030453 :B4C50CAE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19020 pso_num=29 pso_serial#=15
2025-07-21 00:06:10.030591 :B4C50CAF:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19020 (legacy spawn)
2025-07-21 00:06:10.037151 :B4C50CB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:06:10.037316 :B4C50CB9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:06:10.038142 :B4C50CBA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:06:13.209779 :B4C50DB0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:13.196
2025-07-21 00:06:13.209781 :B4C50DB1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18924 pso_num=29 pso_serial#=16
2025-07-21 00:06:13.209915 :B4C50DB2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18924 (legacy spawn)
2025-07-21 00:06:13.216386 :B4C50DB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:06:13.216582 :B4C50DB8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:06:13.217352 :B4C50DBD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 00:06:13.226015 :B4C50DC0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:13.212
2025-07-21 00:06:13.226017 :B4C50DC1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17140 pso_num=29 pso_serial#=17
2025-07-21 00:06:13.226148 :B4C50DC2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17140 (legacy spawn)
2025-07-21 00:06:13.248245 :B4C50DC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 00:06:13.248412 :B4C50DCC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 00:06:13.249200 :B4C50DCD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 01:00:02.779115 :B4C937E9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 01:00:02.770
2025-07-21 01:00:02.779118 :B4C937EA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19440 pso_num=29 pso_serial#=18
2025-07-21 01:00:02.779240 :B4C937EB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19440 osp_idx=27 osp_ver=14807 osp_pg=0 (spawn #406249)
2025-07-21 01:00:02.779241 :B4C937EC:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-21 01:00:02.779241 :B4C937ED:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=255 time=1740378673
2025-07-21 01:00:02.779242 :B4C937EE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-21 01:00:02.779242 :B4C937EF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-21 01:00:02.779242 :B4C937F0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-21 01:00:02.779242 :B4C937F1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-21 01:00:02.779243 :B4C937F2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-21 01:00:02.779862 :B4C937F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-21 01:00:32.858661 :B4C9437C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 22:00:00.531573 :B52BDB71:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 22:00:00.524
2025-07-21 22:00:00.531575 :B52BDB72:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17876 pso_num=29 pso_serial#=19
2025-07-21 22:00:00.531723 :B52BDB7C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17876 osp_idx=27 osp_ver=14808 osp_pg=0 (spawn #409159)
2025-07-21 22:00:00.531724 :B52BDB7D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-21 22:00:00.531724 :B52BDB7E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=0 time=1815976423
2025-07-21 22:00:00.531725 :B52BDB7F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-21 22:00:00.531726 :B52BDB80:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-21 22:00:00.531726 :B52BDB81:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-21 22:00:00.531726 :B52BDB82:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-21 22:00:00.531727 :B52BDB83:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-21 22:00:00.532413 :B52BDB84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.537572 :B52BDBB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.537608 :B52BDBB4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203
2025-07-21 22:00:00.555053 :B52BDC74:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7
2025-07-21 22:00:00.555638 :B52BDC82:db_trace:kcc.c@13684:kccwbp(): [10021:29:233:1] 0115000000000000 0115000000000000 7a51120000000000
2025-07-21 22:00:00.566342 :B52BDCC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.566409 :B52BDCD5:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-21 22:00:00.566418 :B52BDCDB:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1)
2025-07-21 22:00:00.566423 :B52BDCDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.566450 :B52BDCE8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-21 22:00:00.567225 :B52BDCF4:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 232056647)
2025-07-21 22:00:00.567229 :B52BDCF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.567251 :B52BDD01:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-21 22:00:00.567259 :B52BDD0D:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65565 1)
2025-07-21 22:00:00.567261 :B52BDD0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.567289 :B52BDD1A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-21 22:00:00.567292 :B52BDD23:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65565)
2025-07-21 22:00:00.567294 :B52BDD27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.567343 :B52BDD4F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-21 22:00:00.569482 :B52BDFCE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-21 22:00:00.569506 :B52BDFD3:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7
2025-07-21 22:00:00.570327 :B52BE018:db_trace:kcc.c@13684:kccwbp(): [10021:29:233:1] 0115000000000000 0115000000000000 7b51120000000000
2025-07-21 22:00:00.570695 :B52BE050:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 232056647)
2025-07-21 22:00:00.570701 :B52BE051:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.570726 :B52BE05C:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-21 22:00:00.579709 :B52BE0BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203
2025-07-21 22:00:00.582633 :B52BE0BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.582689 :B52BE0C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name=   type=0
2025-07-21 22:00:00.582823 :B52BE0CF:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 22:00:30.674226 :B52BF131:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 22:19:50.480138 :B52DF4A2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 22:19:50.475
2025-07-21 22:19:50.480140 :B52DF4A3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=1740 pso_num=29 pso_serial#=20
2025-07-21 22:19:50.480302 :B52DF4A4:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=1740 osp_idx=27 osp_ver=14809 osp_pg=0 (spawn #409209)
2025-07-21 22:19:50.480303 :B52DF4A5:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-21 22:19:50.480303 :B52DF4A6:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=1 time=1817166361
2025-07-21 22:19:50.480304 :B52DF4A7:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-21 22:19:50.480304 :B52DF4A8:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-21 22:19:50.480304 :B52DF4A9:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-21 22:19:50.480305 :B52DF4AA:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-21 22:19:50.480305 :B52DF4AB:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-21 22:19:50.481138 :B52DF4AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-21 22:19:50.481184 :B52DF4B2:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-21 22:19:50.595868 :B52DF50C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-21 22:19:51.795251 :B52DF558:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 22:19:51.782
2025-07-21 22:19:51.795254 :B52DF559:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15600 pso_num=29 pso_serial#=21
2025-07-21 22:19:51.795386 :B52DF55A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15600 osp_idx=27 osp_ver=14810 osp_pg=0 (spawn #409211)
2025-07-21 22:19:51.795387 :B52DF55B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-21 22:19:51.795387 :B52DF55C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=2 time=1817167673
2025-07-21 22:19:51.795387 :B52DF55D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-21 22:19:51.795388 :B52DF55E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-21 22:19:51.795388 :B52DF55F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-21 22:19:51.795388 :B52DF560:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-21 22:19:51.795389 :B52DF561:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-21 22:19:51.795880 :B52DF562:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-21 22:19:51.795926 :B52DF568:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 29 (osid: 15600, M002)