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


*** 2025-07-29 08:23:46.632
*** SESSION ID:(219.23059) 2025-07-29 08:23:46.632
 

*** 2025-07-29 08:23:46.632
Process diagnostic dump for ORACLE.EXE (M002), OS id=18288,
pid: 27, proc_ser: 171, sid: 219, sess_ser: 23059 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'db file sequential read'
    file#=0x2, block#=0x776a, blocks=0x1
    wait_id=1657 seq_num=1658 snap_id=1
    wait times: snap=0.001826 sec, exc=0.001826 sec, total=0.001826 sec
    wait times: max=infinite, heur=0.001826 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.000354 sec since current wait
 0: waited for 'db file sequential read'
    file#=0x2, block#=0x772a, blocks=0x1
    wait_id=1656 seq_num=1657 snap_id=1
    wait times: snap=0.002198 sec, exc=0.002198 sec, total=0.002198 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000149 sec of elapsed time
 1: waited for 'db file sequential read'
    file#=0x2, block#=0x770a, blocks=0x1
    wait_id=1655 seq_num=1656 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.000139 sec of elapsed time
 2: waited for 'db file sequential read'
    file#=0x2, block#=0x76f2, blocks=0x1
    wait_id=1654 seq_num=1655 snap_id=1
    wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000145 sec of elapsed time
 3: waited for 'db file sequential read'
    file#=0x2, block#=0x76e2, blocks=0x1
    wait_id=1653 seq_num=1654 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.000176 sec of elapsed time
 4: waited for 'db file sequential read'
    file#=0x2, block#=0x76d2, blocks=0x1
    wait_id=1652 seq_num=1653 snap_id=1
    wait times: snap=0.000075 sec, exc=0.000075 sec, total=0.000075 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000351 sec of elapsed time
 5: waited for 'db file sequential read'
    file#=0x2, block#=0x76ba, blocks=0x1
    wait_id=1651 seq_num=1652 snap_id=1
    wait times: snap=0.003400 sec, exc=0.003400 sec, total=0.003400 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000353 sec of elapsed time
 6: waited for 'db file sequential read'
    file#=0x2, block#=0x76a2, blocks=0x1
    wait_id=1650 seq_num=1651 snap_id=1
    wait times: snap=0.004878 sec, exc=0.004878 sec, total=0.004878 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000202 sec of elapsed time
 7: waited for 'db file sequential read'
    file#=0x2, block#=0x768a, blocks=0x1
    wait_id=1649 seq_num=1650 snap_id=1
    wait times: snap=0.000357 sec, exc=0.000357 sec, total=0.000357 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000177 sec of elapsed time
 8: waited for 'db file sequential read'
    file#=0x2, block#=0x5512, blocks=0x1
    wait_id=1648 seq_num=1649 snap_id=1
    wait times: snap=0.000150 sec, exc=0.000150 sec, total=0.000150 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000376 sec of elapsed time
 9: waited for 'db file sequential read'
    file#=0x2, block#=0x54f2, blocks=0x1
    wait_id=1647 seq_num=1648 snap_id=1
    wait times: snap=0.005147 sec, exc=0.005147 sec, total=0.005147 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000143 sec of elapsed time
Sampled Session History of session 219 serial 23059
---------------------------------------------------
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:23:45]
    waited for 'ADR block file read', seq_num: 654
      p1: ' '=0x0
      p2: ' '=0x0
      p3: ' '=0x0
      time_waited: >= 0 sec (still in wait)
  [1 sample,                                                          08:23:44]
    idle wait at each sample
  [session created at: 08:23:45]
---------------------------------------------------
Sampled Session History Summary:
  longest_non_idle_wait: 'ADR block file read'
  [1 sample, 08:23:45 ]
      time_waited: >= 0 sec (still in wait)
---------------------------------------------------
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-29 08:23:46.632
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 27 (osid: 18288, M002)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-29 01:55:23.024999 :B858E19D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15304 pso_num=27 pso_serial#=148
2025-07-29 01:55:23.025143 :B858E19E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15304 (legacy spawn)
2025-07-29 01:55:23.032171 :B858E19F: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-29 01:55:23.032351 :B858E1A8: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-29 01:55:23.033273 :B858E1A9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:56:23.940503 :B858F530:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:56:23.925
2025-07-29 01:56:23.940505 :B858F531:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12960 pso_num=27 pso_serial#=149
2025-07-29 01:56:23.940648 :B858F532:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12960 (legacy spawn)
2025-07-29 01:56:23.947163 :B858F533: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-29 01:56:23.947339 :B858F538: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-29 01:56:23.948161 :B858F53D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:56:23.956949 :B858F540:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:56:23.956
2025-07-29 01:56:23.956951 :B858F541:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19176 pso_num=27 pso_serial#=150
2025-07-29 01:56:23.957085 :B858F542:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19176 (legacy spawn)
2025-07-29 01:56:23.963606 :B858F543: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-29 01:56:23.963773 :B858F54C: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-29 01:56:23.964621 :B858F54D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:56:25.184095 :B858F5A2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:56:25.175
2025-07-29 01:56:25.184097 :B858F5A3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17936 pso_num=27 pso_serial#=151
2025-07-29 01:56:25.184241 :B858F5A4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17936 (legacy spawn)
2025-07-29 01:56:25.190681 :B858F5A5: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-29 01:56:25.190849 :B858F5AE: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-29 01:56:25.191683 :B858F5AF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:57:00.500550 :B8590115:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:57:00.486
2025-07-29 01:57:00.500552 :B8590116:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13592 pso_num=27 pso_serial#=152
2025-07-29 01:57:00.500690 :B8590117:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13592 (legacy spawn)
2025-07-29 01:57:00.507377 :B8590118: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-29 01:57:00.507566 :B859011D: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-29 01:57:00.508385 :B8590122:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:57:00.517146 :B8590125:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:57:00.517
2025-07-29 01:57:00.517148 :B8590126:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18356 pso_num=27 pso_serial#=153
2025-07-29 01:57:00.517290 :B8590127:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18356 (legacy spawn)
2025-07-29 01:57:00.523693 :B8590128: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-29 01:57:00.523866 :B8590131: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-29 01:57:00.524648 :B8590140:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:57:00.978860 :B859014F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:57:00.971
2025-07-29 01:57:00.978862 :B8590150:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15732 pso_num=27 pso_serial#=154
2025-07-29 01:57:00.979000 :B8590151:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15732 (legacy spawn)
2025-07-29 01:57:00.985435 :B8590152: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-29 01:57:00.985601 :B859015B: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-29 01:57:00.986465 :B859015C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:57:03.610852 :B859023B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:57:03.597
2025-07-29 01:57:03.610854 :B859023C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6844 pso_num=27 pso_serial#=155
2025-07-29 01:57:03.610997 :B859023D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6844 (legacy spawn)
2025-07-29 01:57:03.617456 :B859023E: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-29 01:57:03.617646 :B8590247: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-29 01:57:03.618444 :B8590248:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:57:03.627149 :B859024B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:57:03.613
2025-07-29 01:57:03.627151 :B859024C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18016 pso_num=27 pso_serial#=156
2025-07-29 01:57:03.627284 :B859024D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18016 (legacy spawn)
2025-07-29 01:57:03.633775 :B859024E: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-29 01:57:03.633937 :B8590257: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-29 01:57:03.634795 :B8590258:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:57:58.508644 :B85913EE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:57:58.496
2025-07-29 01:57:58.508647 :B85913EF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19432 pso_num=27 pso_serial#=157
2025-07-29 01:57:58.508792 :B85913F0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19432 (legacy spawn)
2025-07-29 01:57:58.515162 :B85913F1: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-29 01:57:58.515328 :B85913F6: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-29 01:57:58.516149 :B85913FB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:58:10.535092 :B8591822:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:58:10.525
2025-07-29 01:58:10.535094 :B8591823:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18132 pso_num=27 pso_serial#=158
2025-07-29 01:58:10.535231 :B8591824:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18132 (legacy spawn)
2025-07-29 01:58:10.541726 :B8591825: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-29 01:58:10.541894 :B859182B: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-29 01:58:10.542694 :B859182F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:58:13.138244 :B85918F0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:58:13.130
2025-07-29 01:58:13.138246 :B85918F1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9028 pso_num=27 pso_serial#=159
2025-07-29 01:58:13.138378 :B85918F2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9028 (legacy spawn)
2025-07-29 01:58:13.144797 :B85918F3: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-29 01:58:13.144825 :B85918F6: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-29 01:58:13.144990 :B85918FD: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-29 01:58:13.145877 :B859190A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:58:13.154627 :B859190D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:58:13.146
2025-07-29 01:58:13.154629 :B859190E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13816 pso_num=27 pso_serial#=160
2025-07-29 01:58:13.154774 :B859190F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13816 (legacy spawn)
2025-07-29 01:58:13.161322 :B8591910: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-29 01:58:13.161484 :B8591919: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-29 01:58:13.162303 :B859191A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:58:16.284795 :B8591A0E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:58:16.271
2025-07-29 01:58:16.284797 :B8591A0F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16256 pso_num=27 pso_serial#=161
2025-07-29 01:58:16.284944 :B8591A10:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16256 (legacy spawn)
2025-07-29 01:58:16.291672 :B8591A11: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-29 01:58:16.291853 :B8591A19: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-29 01:58:16.292728 :B8591A1A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:58:16.301538 :B8591A1D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:58:16.286
2025-07-29 01:58:16.301540 :B8591A1E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10672 pso_num=27 pso_serial#=162
2025-07-29 01:58:16.301684 :B8591A1F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10672 (legacy spawn)
2025-07-29 01:58:16.308214 :B8591A20: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-29 01:58:16.308383 :B8591A29: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-29 01:58:16.309210 :B8591A2A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:59:00.629753 :B8592893:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:59:00.622
2025-07-29 01:59:00.629756 :B8592894:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1812 pso_num=27 pso_serial#=163
2025-07-29 01:59:00.629897 :B8592895:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1812 (legacy spawn)
2025-07-29 01:59:00.636441 :B8592896: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-29 01:59:00.636471 :B8592899: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-29 01:59:00.636630 :B859289C: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-29 01:59:00.637475 :B85928AD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:59:10.111497 :B8592B7A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:59:10.100
2025-07-29 01:59:10.111499 :B8592B7B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16524 pso_num=27 pso_serial#=164
2025-07-29 01:59:10.111638 :B8592B7C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16524 (legacy spawn)
2025-07-29 01:59:10.118199 :B8592B7D: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-29 01:59:10.118380 :B8592B86: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-29 01:59:10.119181 :B8592B87:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:59:10.127882 :B8592B8A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:59:10.115
2025-07-29 01:59:10.127884 :B8592B8B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1852 pso_num=27 pso_serial#=165
2025-07-29 01:59:10.128023 :B8592B8C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1852 (legacy spawn)
2025-07-29 01:59:10.134603 :B8592B8D: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-29 01:59:10.134768 :B8592B96: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-29 01:59:10.135597 :B8592B97:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:59:18.460703 :B8592E41:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:59:18.451
2025-07-29 01:59:18.460705 :B8592E42:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=636 pso_num=27 pso_serial#=166
2025-07-29 01:59:18.460842 :B8592E43:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=636 (legacy spawn)
2025-07-29 01:59:18.467377 :B8592E44: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-29 01:59:18.467540 :B8592E49: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-29 01:59:18.468357 :B8592E4E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:59:25.703826 :B859308B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:59:25.688
2025-07-29 01:59:25.703829 :B859308C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17416 pso_num=27 pso_serial#=167
2025-07-29 01:59:25.704001 :B859308D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17416 (legacy spawn)
2025-07-29 01:59:25.710562 :B859308E: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-29 01:59:25.710734 :B8593097: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-29 01:59:25.711609 :B8593098:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 01:59:25.720696 :B859309B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 01:59:25.720
2025-07-29 01:59:25.720698 :B859309C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12004 pso_num=27 pso_serial#=168
2025-07-29 01:59:25.720838 :B859309D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12004 (legacy spawn)
2025-07-29 01:59:25.727453 :B859309E: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-29 01:59:25.727620 :B85930A7: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-29 01:59:25.728461 :B85930A8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 02:00:00.028180 :B8593D34:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 02:00:00.017
2025-07-29 02:00:00.028182 :B8593D35:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4148 pso_num=27 pso_serial#=169
2025-07-29 02:00:00.028315 :B8593D36:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=4148 osp_idx=25 osp_ver=208002 osp_pg=0 (spawn #433050)
2025-07-29 02:00:00.028315 :B8593D37: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-29 02:00:00.028317 :B8593D38:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=115 time=2435175923
2025-07-29 02:00:00.028318 :B8593D39:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-07-29 02:00:00.028318 :B8593D3A:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-07-29 02:00:00.028318 :B8593D3B:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-07-29 02:00:00.028319 :B8593D3C:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-29 02:00:00.028319 :B8593D3D:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-29 02:00:00.028808 :B8593D3E: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-29 02:00:00.028851 :B8593D45: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-29 02:00:00.028926 :B8593D59: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-29 02:00:00.032883 :B8593D68: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-29 02:00:00.085408 :B8593D73: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-29 02:00:00.087847 :B8593D7B: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-29 02:00:00.089257 :B8593D83: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-29 02:00:00.090394 :B8593D8B: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-29 02:00:00.090420 :B8593D8E: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-29 02:00:00.090466 :B8593D99: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-29 02:00:00.091130 :B8593DA4: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-29 02:00:00.091144 :B8593DA7: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-29 02:00:00.091175 :B8593DB2: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-29 02:00:00.092017 :B8593DBD: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-29 02:00:00.092030 :B8593DC0: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-29 02:00:00.092055 :B8593DCC: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-29 02:00:00.092108 :B8593DD7: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-29 02:00:00.093282 :B8593DD8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 08:23:43.471857 :B877290B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 08:23:43.470
2025-07-29 08:23:43.471859 :B877290C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17632 pso_num=27 pso_serial#=170
2025-07-29 08:23:43.472018 :B877290D:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17632 osp_idx=25 osp_ver=208003 osp_pg=0 (spawn #433938)
2025-07-29 08:23:43.472019 :B877290E: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-29 08:23:43.472019 :B877290F:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=116 time=2458199361
2025-07-29 08:23:43.472020 :B8772910:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-07-29 08:23:43.472020 :B8772911:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-07-29 08:23:43.472021 :B8772912:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-07-29 08:23:43.472021 :B8772913:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-29 08:23:43.472021 :B8772914:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-29 08:23:43.472870 :B8772915: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-29 08:23:43.472911 :B877291B: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-29 08:23:43.565563 :B877294D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-29 08:23:45.773196 :B8772A18:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-29 08:23:45.767
2025-07-29 08:23:45.773199 :B8772A19:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18288 pso_num=27 pso_serial#=171
2025-07-29 08:23:45.773329 :B8772A1A:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=18288 osp_idx=25 osp_ver=208004 osp_pg=0 (spawn #433940)
2025-07-29 08:23:45.773330 :B8772A1B: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-29 08:23:45.773331 :B8772A1C:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=117 time=2458201673
2025-07-29 08:23:45.773331 :B8772A1D:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-07-29 08:23:45.773331 :B8772A1E:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-07-29 08:23:45.773331 :B8772A1F:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-07-29 08:23:45.773332 :B8772A20:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-29 08:23:45.773332 :B8772A21:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-29 08:23:45.773819 :B8772A22: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-29 08:23:45.773869 :B8772A28: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: 18288, M002)