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


*** 2025-07-08 08:08:53.031
*** SESSION ID:(219.25019) 2025-07-08 08:08:53.031
 

*** 2025-07-08 08:08:53.031
Process diagnostic dump for ORACLE.EXE (M002), OS id=5092,
pid: 27, proc_ser: 169, sid: 219, sess_ser: 25019 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=447 seq_num=448 snap_id=1
    wait times: snap=0.005448 sec, exc=0.005448 sec, total=0.005448 sec
    wait times: max=infinite, heur=0.513806 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.000018 sec since current wait
 0: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=446 seq_num=447 snap_id=1
    wait times: snap=0.007030 sec, exc=0.007030 sec, total=0.007030 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000016 sec of elapsed time
 1: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=445 seq_num=446 snap_id=1
    wait times: snap=0.005492 sec, exc=0.005492 sec, total=0.005492 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000016 sec of elapsed time
 2: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=444 seq_num=445 snap_id=1
    wait times: snap=0.002899 sec, exc=0.002899 sec, total=0.002899 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000016 sec of elapsed time
 3: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=443 seq_num=444 snap_id=1
    wait times: snap=0.003626 sec, exc=0.003626 sec, total=0.003626 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000015 sec of elapsed time
 4: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=442 seq_num=443 snap_id=1
    wait times: snap=0.003240 sec, exc=0.003240 sec, total=0.003240 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000007 sec of elapsed time
 5: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=441 seq_num=442 snap_id=1
    wait times: snap=0.000115 sec, exc=0.000115 sec, total=0.000115 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000007 sec of elapsed time
 6: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=440 seq_num=441 snap_id=1
    wait times: snap=0.000119 sec, exc=0.000119 sec, total=0.000119 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000009 sec of elapsed time
 7: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=439 seq_num=440 snap_id=1
    wait times: snap=0.000083 sec, exc=0.000083 sec, total=0.000083 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000020 sec of elapsed time
 8: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=438 seq_num=439 snap_id=1
    wait times: snap=0.006450 sec, exc=0.006450 sec, total=0.006450 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000015 sec of elapsed time
 9: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=437 seq_num=438 snap_id=1
    wait times: snap=0.005675 sec, exc=0.005675 sec, total=0.005675 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000013 sec of elapsed time
Sampled Session History of session 219 serial 25019
---------------------------------------------------
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,                                                          08:08:52]
    not in wait at each sample
  [1 sample,                                                          08:08:51]
    idle wait at each sample
  [session created at: 08:08:52]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-08 08:08:53.031
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 27 (osid: 5092, M002)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-08 01:56:43.623649 :AF023B6D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1020 pso_num=27 pso_serial#=146
2025-07-08 01:56:43.623791 :AF023B6E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1020 (legacy spawn)
2025-07-08 01:56:43.630543 :AF023B6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:56:43.630706 :AF023B78:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:56:43.631521 :AF023B79:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:56:46.708602 :AF023C6B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:56:46.695
2025-07-08 01:56:46.708605 :AF023C6C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=608 pso_num=27 pso_serial#=147
2025-07-08 01:56:46.708798 :AF023C6D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=608 (legacy spawn)
2025-07-08 01:56:46.715538 :AF023C6E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:56:46.715719 :AF023C74:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:56:46.716530 :AF023C78:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:56:46.725356 :AF023C7B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:56:46.711
2025-07-08 01:56:46.725358 :AF023C7C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15780 pso_num=27 pso_serial#=148
2025-07-08 01:56:46.725497 :AF023C7D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15780 (legacy spawn)
2025-07-08 01:56:46.732218 :AF023C7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:56:46.732384 :AF023C86:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:56:46.733183 :AF023C88:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:56:57.832176 :AF02403D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:56:57.823
2025-07-08 01:56:57.832178 :AF02403E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7960 pso_num=27 pso_serial#=149
2025-07-08 01:56:57.832315 :AF02403F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7960 (legacy spawn)
2025-07-08 01:56:57.839443 :AF024040:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:56:57.839633 :AF024049:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:56:57.840537 :AF02404A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:57:45.339613 :AF025064:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:57:45.331
2025-07-08 01:57:45.339614 :AF025065:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17648 pso_num=27 pso_serial#=150
2025-07-08 01:57:45.339755 :AF025066:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17648 (legacy spawn)
2025-07-08 01:57:45.346544 :AF025067:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:57:45.346709 :AF025070:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:57:45.347501 :AF025071:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:57:56.396404 :AF0253B8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:57:56.382
2025-07-08 01:57:56.396406 :AF0253B9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17876 pso_num=27 pso_serial#=151
2025-07-08 01:57:56.396559 :AF0253BA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17876 (legacy spawn)
2025-07-08 01:57:56.403594 :AF0253BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:57:56.403781 :AF0253C0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:57:56.404748 :AF0253C5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:57:56.413504 :AF0253C8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:57:56.413
2025-07-08 01:57:56.413506 :AF0253C9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17340 pso_num=27 pso_serial#=152
2025-07-08 01:57:56.413649 :AF0253CA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17340 (legacy spawn)
2025-07-08 01:57:56.420395 :AF0253CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:57:56.420568 :AF0253D3:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:57:56.421374 :AF0253D4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:57:59.603166 :AF0254D8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:57:59.595
2025-07-08 01:57:59.603169 :AF0254D9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12464 pso_num=27 pso_serial#=153
2025-07-08 01:57:59.603311 :AF0254DA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12464 (legacy spawn)
2025-07-08 01:57:59.610021 :AF0254DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:57:59.620604 :AF0254E0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:57:59.621566 :AF0254E5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:57:59.630730 :AF0254E8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:57:59.630
2025-07-08 01:57:59.630731 :AF0254E9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18004 pso_num=27 pso_serial#=154
2025-07-08 01:57:59.630883 :AF0254EA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18004 (legacy spawn)
2025-07-08 01:57:59.637553 :AF0254EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:57:59.637713 :AF0254F4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:57:59.638512 :AF0254F5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:58:06.044095 :AF025745:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:58:06.030
2025-07-08 01:58:06.044097 :AF025746:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14300 pso_num=27 pso_serial#=155
2025-07-08 01:58:06.044242 :AF025747:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14300 (legacy spawn)
2025-07-08 01:58:06.050894 :AF025748:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:58:06.051068 :AF02574D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:58:06.051873 :AF025752:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:58:16.442810 :AF025A67:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:58:16.434
2025-07-08 01:58:16.442812 :AF025A68:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11032 pso_num=27 pso_serial#=156
2025-07-08 01:58:16.442949 :AF025A69:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11032 (legacy spawn)
2025-07-08 01:58:16.449551 :AF025A6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:58:16.449741 :AF025A6F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:58:16.450555 :AF025A74:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:58:40.240660 :AF0262A2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:58:40.231
2025-07-08 01:58:40.240662 :AF0262A3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14268 pso_num=27 pso_serial#=157
2025-07-08 01:58:40.240799 :AF0262A4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14268 (legacy spawn)
2025-07-08 01:58:40.247665 :AF0262A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:58:40.247834 :AF0262AA:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:58:40.248673 :AF0262AF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:58:47.548117 :AF0264FF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:58:47.540
2025-07-08 01:58:47.548119 :AF026500:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16476 pso_num=27 pso_serial#=158
2025-07-08 01:58:47.548260 :AF026501:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16476 (legacy spawn)
2025-07-08 01:58:47.555020 :AF026502:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:58:47.555198 :AF026507:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:58:47.556042 :AF02650C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:59:45.508380 :AF027857:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:59:45.493
2025-07-08 01:59:45.508382 :AF027858:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10656 pso_num=27 pso_serial#=159
2025-07-08 01:59:45.508519 :AF027859:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10656 (legacy spawn)
2025-07-08 01:59:45.515226 :AF02785A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:59:45.515400 :AF02785F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:45.516209 :AF027864:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:59:45.525124 :AF027867:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:59:45.525
2025-07-08 01:59:45.525126 :AF027868:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15912 pso_num=27 pso_serial#=160
2025-07-08 01:59:45.525261 :AF027869:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15912 (legacy spawn)
2025-07-08 01:59:45.531929 :AF02786A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:59:45.532098 :AF027872:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:45.532887 :AF027874:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:59:48.439104 :AF027968:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:59:48.429
2025-07-08 01:59:48.439106 :AF027969:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17100 pso_num=27 pso_serial#=161
2025-07-08 01:59:48.439242 :AF02796A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17100 (legacy spawn)
2025-07-08 01:59:48.445957 :AF02796B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:59:48.446117 :AF027972:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:48.446920 :AF027975:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:59:48.693253 :AF027983:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:59:48.679
2025-07-08 01:59:48.693254 :AF027984:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14500 pso_num=27 pso_serial#=162
2025-07-08 01:59:48.693388 :AF027985:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14500 (legacy spawn)
2025-07-08 01:59:48.699990 :AF027986:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:59:48.700160 :AF02798F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:48.700954 :AF027990:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:59:48.709912 :AF027993:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:59:48.695
2025-07-08 01:59:48.709914 :AF027994:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6008 pso_num=27 pso_serial#=163
2025-07-08 01:59:48.710058 :AF027995:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6008 (legacy spawn)
2025-07-08 01:59:48.716667 :AF027996:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:59:48.716841 :AF02799F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:48.717683 :AF0279A0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:59:49.031603 :AF0279B1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:59:49.023
2025-07-08 01:59:49.031606 :AF0279B2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13012 pso_num=27 pso_serial#=164
2025-07-08 01:59:49.031748 :AF0279B3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13012 (legacy spawn)
2025-07-08 01:59:49.038334 :AF0279B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:59:49.038365 :AF0279B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:59:49.038510 :AF0279BE:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:49.039365 :AF0279CB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 01:59:49.750893 :AF0279D9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 01:59:49.742
2025-07-08 01:59:49.750895 :AF0279DA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17844 pso_num=27 pso_serial#=165
2025-07-08 01:59:49.751051 :AF0279DB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17844 (legacy spawn)
2025-07-08 01:59:49.757581 :AF0279DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 01:59:49.757767 :AF0279E1:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:49.758563 :AF0279E6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 02:00:00.030875 :AF027D58:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 02:00:00.021
2025-07-08 02:00:00.030878 :AF027D59:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7776 pso_num=27 pso_serial#=166
2025-07-08 02:00:00.031010 :AF027D5A:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=7776 osp_idx=25 osp_ver=143484 osp_pg=0 (spawn #363066)
2025-07-08 02:00:00.031011 :AF027D5B:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-08 02:00:00.031011 :AF027D5C:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=210 time=620775923
2025-07-08 02:00:00.031012 :AF027D5D:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-07-08 02:00:00.031012 :AF027D5E:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-07-08 02:00:00.031012 :AF027D5F:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-07-08 02:00:00.031013 :AF027D60:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-08 02:00:00.031013 :AF027D61:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-08 02:00:00.031555 :AF027D62:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.031603 :AF027D69:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 02:00:00.031688 :AF027D7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.035718 :AF027D8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.088329 :AF027D96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.090992 :AF027D9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.092665 :AF027DA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.093804 :AF027DAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.093828 :AF027DB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.093869 :AF027DBC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-08 02:00:00.094502 :AF027DC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.094516 :AF027DCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.094541 :AF027DD6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-08 02:00:00.095389 :AF027DE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.095403 :AF027DE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-07-08 02:00:00.095432 :AF027DEF:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-08 02:00:00.095482 :AF027DF9:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 02:00:00.096618 :AF027DFB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 08:08:50.026187 :AF203707:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 08:08:50.023
2025-07-08 08:08:50.026189 :AF203708:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14972 pso_num=27 pso_serial#=167
2025-07-08 08:08:50.026349 :AF203709:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=14972 osp_idx=25 osp_ver=143485 osp_pg=0 (spawn #363921)
2025-07-08 08:08:50.026350 :AF20370A:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-08 08:08:50.026350 :AF20370B:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=211 time=642905908
2025-07-08 08:08:50.026351 :AF20370C:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-07-08 08:08:50.026351 :AF20370D:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-07-08 08:08:50.026351 :AF20370E:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-07-08 08:08:50.026352 :AF20370F:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-08 08:08:50.026352 :AF203710:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-08 08:08:50.027602 :AF203711:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-08 08:08:50.027650 :AF203717:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 08:08:50.159175 :AF203749:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 08:08:50.554254 :AF203758:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 08:08:50.539
2025-07-08 08:08:50.554256 :AF203759:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10992 pso_num=27 pso_serial#=168
2025-07-08 08:08:50.554432 :AF20375A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10992 (legacy spawn)
2025-07-08 08:08:50.561321 :AF20375B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-08 08:08:50.561485 :AF203764:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 08:08:50.562350 :AF203765:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-08 08:08:52.432073 :AF203862:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-08 08:08:52.422
2025-07-08 08:08:52.432075 :AF203863:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5092 pso_num=27 pso_serial#=169
2025-07-08 08:08:52.432215 :AF203864:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=5092 osp_idx=25 osp_ver=143487 osp_pg=0 (spawn #363923)
2025-07-08 08:08:52.432216 :AF203865:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-08 08:08:52.432216 :AF203866:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=212 time=642908314
2025-07-08 08:08:52.432217 :AF203867:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-07-08 08:08:52.432217 :AF203868:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-07-08 08:08:52.432217 :AF203869:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-07-08 08:08:52.432218 :AF20386A:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-08 08:08:52.432218 :AF20386B:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-08 08:08:52.432871 :AF20386C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-08 08:08:52.432916 :AF203872:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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 27 (osid: 5092, M002)