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


*** 2025-05-30 08:16:30.760
*** SESSION ID:(225.52531) 2025-05-30 08:16:30.760
 

*** 2025-05-30 08:16:30.760
Process diagnostic dump for ORACLE.EXE (M002), OS id=10280,
pid: 28, proc_ser: 87, sid: 225, sess_ser: 52531 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=816 seq_num=817 snap_id=1
    wait times: snap=0.001166 sec, exc=0.001166 sec, total=0.001166 sec
    wait times: max=infinite, heur=0.107449 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.000015 sec since current wait
 0: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=815 seq_num=816 snap_id=1
    wait times: snap=0.002244 sec, exc=0.002244 sec, total=0.002244 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000006 sec of elapsed time
 1: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=814 seq_num=815 snap_id=1
    wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000006 sec of elapsed time
 2: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=813 seq_num=814 snap_id=1
    wait times: snap=0.000047 sec, exc=0.000047 sec, total=0.000047 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000006 sec of elapsed time
 3: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=812 seq_num=813 snap_id=1
    wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000005 sec of elapsed time
 4: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=811 seq_num=812 snap_id=1
    wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 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=810 seq_num=811 snap_id=1
    wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 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=809 seq_num=810 snap_id=1
    wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000006 sec of elapsed time
 7: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=808 seq_num=809 snap_id=1
    wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000007 sec of elapsed time
 8: waited for 'ADR block file read'
     =0x0,  =0x0,  =0x0
    wait_id=807 seq_num=808 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=806 seq_num=807 snap_id=1
    wait times: snap=0.000053 sec, exc=0.000053 sec, total=0.000053 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000009 sec of elapsed time
Sampled Session History of session 225 serial 52531
---------------------------------------------------
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:16:30]
    waited for 'ADR block file read', seq_num: 569
      p1: ' '=0x0
      p2: ' '=0x0
      p3: ' '=0x0
      time_waited: >= 0 sec (still in wait)
  [1 sample,                                                          08:16:29]
    idle wait at each sample
  [session created at: 08:16:29]
---------------------------------------------------
Sampled Session History Summary:
  longest_non_idle_wait: 'ADR block file read'
  [1 sample, 08:16:30 ]
      time_waited: >= 0 sec (still in wait)
---------------------------------------------------
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-05-30 08:16:30.760
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 28 (osid: 10280, M002)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-30 01:55:34.450902 :9DCD5A28:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:55:34.451722 :9DCD5A2A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:55:37.680917 :9DCD5B16:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:55:37.668
2025-05-30 01:55:37.680918 :9DCD5B17:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14224 pso_num=28 pso_serial#=61
2025-05-30 01:55:37.681058 :9DCD5B18:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14224 (legacy spawn)
2025-05-30 01:55:37.687639 :9DCD5B19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:55:37.687807 :9DCD5B1E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:55:37.688597 :9DCD5B23:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:55:37.697321 :9DCD5B26:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:55:37.683
2025-05-30 01:55:37.697323 :9DCD5B27:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7736 pso_num=28 pso_serial#=62
2025-05-30 01:55:37.697466 :9DCD5B28:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7736 (legacy spawn)
2025-05-30 01:55:37.703970 :9DCD5B29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:55:37.704134 :9DCD5B32:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:55:37.704918 :9DCD5B33:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:55:37.800969 :9DCD5B4C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:55:37.793
2025-05-30 01:55:37.800971 :9DCD5B4D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14804 pso_num=28 pso_serial#=63
2025-05-30 01:55:37.801106 :9DCD5B4E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14804 (legacy spawn)
2025-05-30 01:55:37.807556 :9DCD5B4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:55:37.807744 :9DCD5B58:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:55:37.808592 :9DCD5B59:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:55:42.185502 :9DCD5CE9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:55:42.172
2025-05-30 01:55:42.185504 :9DCD5CEA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13808 pso_num=28 pso_serial#=64
2025-05-30 01:55:42.185637 :9DCD5CEB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13808 (legacy spawn)
2025-05-30 01:55:42.192152 :9DCD5CEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:55:42.192331 :9DCD5CF1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:55:42.193129 :9DCD5CF6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:56:40.024712 :9DCD7024:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:56:40.016
2025-05-30 01:56:40.024714 :9DCD7025:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15288 pso_num=28 pso_serial#=65
2025-05-30 01:56:40.024861 :9DCD7026:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15288 (legacy spawn)
2025-05-30 01:56:40.031415 :9DCD7027:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:56:40.031590 :9DCD702C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:56:40.032431 :9DCD7031:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:56:47.023625 :9DCD7249:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:56:47.021
2025-05-30 01:56:47.023627 :9DCD724A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13456 pso_num=28 pso_serial#=66
2025-05-30 01:56:47.023769 :9DCD724B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13456 (legacy spawn)
2025-05-30 01:56:47.030129 :9DCD724C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:56:47.030306 :9DCD7251:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:56:47.031162 :9DCD7256:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:56:47.039959 :9DCD7259:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:56:47.037
2025-05-30 01:56:47.039960 :9DCD725A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14896 pso_num=28 pso_serial#=67
2025-05-30 01:56:47.040098 :9DCD725B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14896 (legacy spawn)
2025-05-30 01:56:47.046526 :9DCD725C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:56:47.046691 :9DCD7262:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:56:47.047509 :9DCD7266:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:56:50.117000 :9DCD735D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:56:50.104
2025-05-30 01:56:50.117002 :9DCD735E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9628 pso_num=28 pso_serial#=68
2025-05-30 01:56:50.117137 :9DCD735F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9628 (legacy spawn)
2025-05-30 01:56:50.123666 :9DCD7360:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:56:50.123835 :9DCD7365:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:56:50.124642 :9DCD736A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:56:50.487277 :9DCD738A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:56:50.473
2025-05-30 01:56:50.487278 :9DCD738B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11976 pso_num=28 pso_serial#=69
2025-05-30 01:56:50.487414 :9DCD738C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11976 (legacy spawn)
2025-05-30 01:56:50.493973 :9DCD738D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:56:50.494137 :9DCD7396:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:56:50.494911 :9DCD7397:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:56:50.654363 :9DCD73D5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:56:50.645
2025-05-30 01:56:50.654365 :9DCD73D6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13168 pso_num=28 pso_serial#=70
2025-05-30 01:56:50.654499 :9DCD73D7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13168 (legacy spawn)
2025-05-30 01:56:50.660956 :9DCD73D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:56:50.661120 :9DCD73E1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:56:50.661911 :9DCD73E2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:57:24.752692 :9DCD7F0C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:57:24.743
2025-05-30 01:57:24.752694 :9DCD7F0D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11632 pso_num=28 pso_serial#=71
2025-05-30 01:57:24.752829 :9DCD7F0E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11632 (legacy spawn)
2025-05-30 01:57:24.759272 :9DCD7F0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:57:24.759445 :9DCD7F18:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:57:24.760229 :9DCD7F19:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:57:26.912949 :9DCD7FCC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:57:26.900
2025-05-30 01:57:26.912951 :9DCD7FCD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12380 pso_num=28 pso_serial#=72
2025-05-30 01:57:26.913082 :9DCD7FCE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12380 (legacy spawn)
2025-05-30 01:57:26.919440 :9DCD7FDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:57:26.919610 :9DCD7FE3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:57:26.920395 :9DCD7FE4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:57:26.929009 :9DCD7FE7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:57:26.915
2025-05-30 01:57:26.929011 :9DCD7FE8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14208 pso_num=28 pso_serial#=73
2025-05-30 01:57:26.929150 :9DCD7FE9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14208 (legacy spawn)
2025-05-30 01:57:26.935852 :9DCD7FEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:57:26.936019 :9DCD7FF3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:57:26.936961 :9DCD7FF4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:58:32.653370 :9DCD946C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:58:32.644
2025-05-30 01:58:32.653372 :9DCD946D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12844 pso_num=28 pso_serial#=74
2025-05-30 01:58:32.653509 :9DCD946E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12844 (legacy spawn)
2025-05-30 01:58:32.659975 :9DCD946F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:58:32.660141 :9DCD9474:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:58:32.660923 :9DCD9479:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:58:36.425673 :9DCD95E2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:58:36.424
2025-05-30 01:58:36.425675 :9DCD95E3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14648 pso_num=28 pso_serial#=75
2025-05-30 01:58:36.425765 :9DCD95E4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14648 (legacy spawn)
2025-05-30 01:58:36.431669 :9DCD95E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:58:36.431844 :9DCD95EE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:58:36.432685 :9DCD95EF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:58:36.448146 :9DCD95F2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:58:36.440
2025-05-30 01:58:36.448149 :9DCD95F3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12396 pso_num=28 pso_serial#=76
2025-05-30 01:58:36.448325 :9DCD95F4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12396 (legacy spawn)
2025-05-30 01:58:36.454717 :9DCD95F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:58:36.454888 :9DCD95FE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:58:36.455784 :9DCD95FF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:58:39.625954 :9DCD96F0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:58:39.613
2025-05-30 01:58:39.625956 :9DCD96F1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9748 pso_num=28 pso_serial#=77
2025-05-30 01:58:39.626090 :9DCD96F2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9748 (legacy spawn)
2025-05-30 01:58:39.632603 :9DCD96F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:58:39.632774 :9DCD96FA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:58:39.633581 :9DCD96FD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:58:39.642265 :9DCD9700:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:58:39.629
2025-05-30 01:58:39.642267 :9DCD9701:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12644 pso_num=28 pso_serial#=78
2025-05-30 01:58:39.642415 :9DCD9702:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12644 (legacy spawn)
2025-05-30 01:58:39.648877 :9DCD9703:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:58:39.649047 :9DCD970B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:58:39.649871 :9DCD970D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:58:44.410734 :9DCD9849:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:58:44.402
2025-05-30 01:58:44.410736 :9DCD984A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9872 pso_num=28 pso_serial#=79
2025-05-30 01:58:44.410885 :9DCD984B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9872 (legacy spawn)
2025-05-30 01:58:44.417557 :9DCD984C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:58:44.417807 :9DCD9851:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:58:44.418713 :9DCD9856:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:59:40.961526 :9DCDAB5B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:59:40.952
2025-05-30 01:59:40.961529 :9DCDAB5C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14524 pso_num=28 pso_serial#=80
2025-05-30 01:59:40.961666 :9DCDAB5D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14524 (legacy spawn)
2025-05-30 01:59:40.968157 :9DCDAB5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:59:40.968321 :9DCDAB63:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:59:40.969117 :9DCDAB68:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:59:46.605882 :9DCDAD38:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:59:46.592
2025-05-30 01:59:46.605884 :9DCDAD39:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12524 pso_num=28 pso_serial#=81
2025-05-30 01:59:46.606017 :9DCDAD3A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12524 (legacy spawn)
2025-05-30 01:59:46.612580 :9DCDAD3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:59:46.612753 :9DCDAD40:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:59:46.613555 :9DCDAD45:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:59:49.013543 :9DCDAE20:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:59:48.999
2025-05-30 01:59:49.013545 :9DCDAE21:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11456 pso_num=28 pso_serial#=82
2025-05-30 01:59:49.013702 :9DCDAE22:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11456 (legacy spawn)
2025-05-30 01:59:49.020552 :9DCDAE23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:59:49.020731 :9DCDAE28:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:59:49.021618 :9DCDAE2D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:59:49.039447 :9DCDAE30:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:59:49.030
2025-05-30 01:59:49.039449 :9DCDAE31:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7792 pso_num=28 pso_serial#=83
2025-05-30 01:59:49.039589 :9DCDAE32:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7792 (legacy spawn)
2025-05-30 01:59:49.046230 :9DCDAE33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:59:49.046426 :9DCDAE3C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:59:49.047486 :9DCDAE3D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:59:52.216667 :9DCDAF2F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:59:52.203
2025-05-30 01:59:52.216670 :9DCDAF30:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11580 pso_num=28 pso_serial#=84
2025-05-30 01:59:52.216811 :9DCDAF31:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11580 (legacy spawn)
2025-05-30 01:59:52.223340 :9DCDAF32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:59:52.223516 :9DCDAF3B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:59:52.224337 :9DCDAF3C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 01:59:52.233141 :9DCDAF3F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 01:59:52.219
2025-05-30 01:59:52.233143 :9DCDAF40:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12244 pso_num=28 pso_serial#=85
2025-05-30 01:59:52.233332 :9DCDAF41:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12244 (legacy spawn)
2025-05-30 01:59:52.239808 :9DCDAF42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-30 01:59:52.239982 :9DCDAF4B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 01:59:52.240849 :9DCDAF4C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 08:16:27.532470 :9DEB7E77:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 08:16:27.531
2025-05-30 08:16:27.532472 :9DEB7E78:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15312 pso_num=28 pso_serial#=86
2025-05-30 08:16:27.532637 :9DEB7E79:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=15312 osp_idx=26 osp_ver=157214 osp_pg=0 (spawn #233904)
2025-05-30 08:16:27.532638 :9DEB7E7A:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-30 08:16:27.532638 :9DEB7E7B:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=240 time=1568748297
2025-05-30 08:16:27.532638 :9DEB7E7C:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs
2025-05-30 08:16:27.532639 :9DEB7E7D:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs
2025-05-30 08:16:27.532639 :9DEB7E7E:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs
2025-05-30 08:16:27.532639 :9DEB7E7F:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-30 08:16:27.532640 :9DEB7E80:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-30 08:16:27.533630 :9DEB7E81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-30 08:16:27.533681 :9DEB7E87:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-30 08:16:27.644833 :9DEB7EED:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-30 08:16:29.854191 :9DEB7FD6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-30 08:16:29.853
2025-05-30 08:16:29.854193 :9DEB7FD7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10280 pso_num=28 pso_serial#=87
2025-05-30 08:16:29.854318 :9DEB7FD8:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10280 osp_idx=26 osp_ver=157215 osp_pg=0 (spawn #233906)
2025-05-30 08:16:29.854319 :9DEB7FD9:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-30 08:16:29.854319 :9DEB7FDA:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=241 time=1568750610
2025-05-30 08:16:29.854320 :9DEB7FDB:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs
2025-05-30 08:16:29.854320 :9DEB7FDC:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs
2025-05-30 08:16:29.854321 :9DEB7FDD:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs
2025-05-30 08:16:29.854321 :9DEB7FDE:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-30 08:16:29.854321 :9DEB7FDF:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-30 08:16:29.854860 :9DEB7FE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-30 08:16:29.854903 :9DEB7FE6:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 28 (osid: 10280, M002)