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


*** 2025-05-24 10:00:35.662
*** SESSION ID:(225.15051) 2025-05-24 10:00:35.662
 

*** 2025-05-24 10:00:35.662
Process diagnostic dump for ORACLE.EXE (J002), OS id=4804,
pid: 28, proc_ser: 235, sid: 225, sess_ser: 15051 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 4804
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=0.078692 sec, exc=0.078692 sec, total=0.078692 sec
    wait times: max=0.500000 sec, heur=4.582427 sec
    wait counts: calls=1 os=1
    in_wait=1 iflags=0x5a8
Wait State:
  fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1
Session Wait History:
    elapsed time of 0.000007 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=0.510099 sec, exc=0.510099 sec, total=0.510099 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 snap_id=1
    wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=1 seq_num=2 snap_id=1
    wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.493571 sec, exc=0.493571 sec, total=0.493571 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000000 sec of elapsed time
Sampled Session History of session 225 serial 15051
---------------------------------------------------
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
---------------------------------------------------
  [5 samples,                                              10:00:31 - 10:00:35]
    idle wait at each sample
  [session created at: 10:00:31]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-05-24 10:00:35.662
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 28 (osid: 4804, J002)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-24 09:57:20.329628 :9B511D74:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:57:20.338286 :9B511D77:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:57:20.339
2025-05-24 09:57:20.338288 :9B511D78:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13928 pso_num=28 pso_serial#=212
2025-05-24 09:57:20.338426 :9B511D79:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13928 (legacy spawn)
2025-05-24 09:57:20.344952 :9B511D7A: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-24 09:57:20.345135 :9B511D83: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-24 09:57:20.345962 :9B511D84:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:57:23.538979 :9B511E70:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:57:23.526
2025-05-24 09:57:23.538981 :9B511E71:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13256 pso_num=28 pso_serial#=213
2025-05-24 09:57:23.539109 :9B511E72:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13256 (legacy spawn)
2025-05-24 09:57:23.545581 :9B511E73: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-24 09:57:23.545767 :9B511E78: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-24 09:57:23.546593 :9B511E7D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:57:23.565970 :9B511E80:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:57:23.557
2025-05-24 09:57:23.565972 :9B511E81:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13360 pso_num=28 pso_serial#=214
2025-05-24 09:57:23.566108 :9B511E82:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13360 (legacy spawn)
2025-05-24 09:57:23.572673 :9B511E83: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-24 09:57:23.572837 :9B511E8C: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-24 09:57:23.573637 :9B511E8D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:57:31.980485 :9B512132:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:57:31.973
2025-05-24 09:57:31.980487 :9B512133:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14092 pso_num=28 pso_serial#=215
2025-05-24 09:57:31.980621 :9B512134:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14092 (legacy spawn)
2025-05-24 09:57:31.987107 :9B512135: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-24 09:57:31.987272 :9B51213A: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-24 09:57:31.988131 :9B51213F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:58:20.690406 :9B51311A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:58:20.677
2025-05-24 09:58:20.690408 :9B51311B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10416 pso_num=28 pso_serial#=216
2025-05-24 09:58:20.690546 :9B51311C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10416 (legacy spawn)
2025-05-24 09:58:20.696971 :9B51311D: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-24 09:58:20.697149 :9B513122: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-24 09:58:20.697923 :9B513127:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:58:33.061214 :9B5134D9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:58:33.054
2025-05-24 09:58:33.061216 :9B5134DA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12352 pso_num=28 pso_serial#=217
2025-05-24 09:58:33.061359 :9B5134DB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12352 (legacy spawn)
2025-05-24 09:58:33.067831 :9B5134DC: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-24 09:58:33.068006 :9B5134E1: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-24 09:58:33.068810 :9B5134F4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:58:33.077498 :9B5134F7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:58:33.069
2025-05-24 09:58:33.077500 :9B5134F8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12468 pso_num=28 pso_serial#=218
2025-05-24 09:58:33.077630 :9B5134F9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12468 (legacy spawn)
2025-05-24 09:58:33.084116 :9B5134FA: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-24 09:58:33.084284 :9B513503: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-24 09:58:33.085155 :9B513504:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:58:34.186858 :9B5135B9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:58:34.179
2025-05-24 09:58:34.186860 :9B5135BA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10868 pso_num=28 pso_serial#=219
2025-05-24 09:58:34.187001 :9B5135BB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10868 (legacy spawn)
2025-05-24 09:58:34.193491 :9B5135BC: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-24 09:58:34.193658 :9B5135C1: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-24 09:58:34.194480 :9B5135C6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:58:36.326853 :9B513600:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:58:36.314
2025-05-24 09:58:36.326855 :9B513601:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11320 pso_num=28 pso_serial#=220
2025-05-24 09:58:36.326998 :9B513602:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11320 (legacy spawn)
2025-05-24 09:58:36.349123 :9B513603: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-24 09:58:36.349307 :9B51360C: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-24 09:58:36.350128 :9B51360D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:58:36.358787 :9B513610:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:58:36.345
2025-05-24 09:58:36.358789 :9B513611:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13176 pso_num=28 pso_serial#=221
2025-05-24 09:58:36.358925 :9B513612:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13176 (legacy spawn)
2025-05-24 09:58:36.365352 :9B513613: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-24 09:58:36.365514 :9B51361C: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-24 09:58:36.366311 :9B51361D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:59:12.705232 :9B51417A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:59:12.692
2025-05-24 09:59:12.705234 :9B51417B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13888 pso_num=28 pso_serial#=222
2025-05-24 09:59:12.705377 :9B51417C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13888 (legacy spawn)
2025-05-24 09:59:12.711857 :9B51417D: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-24 09:59:12.712016 :9B514186: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-24 09:59:12.712807 :9B514187:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:59:12.721418 :9B51418A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:59:12.707
2025-05-24 09:59:12.721420 :9B51418B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10960 pso_num=28 pso_serial#=223
2025-05-24 09:59:12.721555 :9B51418C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10960 (legacy spawn)
2025-05-24 09:59:12.728035 :9B51418D: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-24 09:59:12.728201 :9B514196: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-24 09:59:12.728975 :9B514197:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:59:23.568364 :9B514529:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:59:23.555
2025-05-24 09:59:23.568366 :9B51452A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=1112 pso_num=28 pso_serial#=224
2025-05-24 09:59:23.568505 :9B51452B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=1112 (legacy spawn)
2025-05-24 09:59:23.574938 :9B51452C: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-24 09:59:23.575110 :9B514531: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-24 09:59:23.575900 :9B514536:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:59:23.584521 :9B514539:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:59:23.570
2025-05-24 09:59:23.584523 :9B51453A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13100 pso_num=28 pso_serial#=225
2025-05-24 09:59:23.584669 :9B51453B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13100 (legacy spawn)
2025-05-24 09:59:23.591236 :9B51453C: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-24 09:59:23.591407 :9B514545: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-24 09:59:23.592246 :9B514546:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:59:28.785684 :9B5146F5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:59:28.779
2025-05-24 09:59:28.785686 :9B5146F6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14216 pso_num=28 pso_serial#=226
2025-05-24 09:59:28.785820 :9B5146F7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14216 (legacy spawn)
2025-05-24 09:59:28.792274 :9B5146F8: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-24 09:59:28.792450 :9B514701: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-24 09:59:28.793261 :9B514702:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 09:59:36.394427 :9B51490D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 09:59:36.386
2025-05-24 09:59:36.394429 :9B51490E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9804 pso_num=28 pso_serial#=227
2025-05-24 09:59:36.394569 :9B51490F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9804 (legacy spawn)
2025-05-24 09:59:36.400984 :9B514910: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-24 09:59:36.401163 :9B514919: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-24 09:59:36.402006 :9B51491A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 10:00:19.599129 :9B5158E5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 10:00:19.586
2025-05-24 10:00:19.599132 :9B5158E6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13156 pso_num=28 pso_serial#=228
2025-05-24 10:00:19.599270 :9B5158E7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13156 (legacy spawn)
2025-05-24 10:00:19.605670 :9B5158E8: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-24 10:00:19.605828 :9B5158F1: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-24 10:00:19.606614 :9B5158F2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 10:00:22.159657 :9B515984:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 10:00:22.148
2025-05-24 10:00:22.159659 :9B515985:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13532 pso_num=28 pso_serial#=229
2025-05-24 10:00:22.159794 :9B515986:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13532 (legacy spawn)
2025-05-24 10:00:22.166419 :9B515987: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-24 10:00:22.166594 :9B51598C: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-24 10:00:22.167397 :9B515991:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 10:00:22.175956 :9B515994:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 10:00:22.164
2025-05-24 10:00:22.175959 :9B515995:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14012 pso_num=28 pso_serial#=230
2025-05-24 10:00:22.176088 :9B515996:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14012 (legacy spawn)
2025-05-24 10:00:22.182616 :9B515997: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-24 10:00:22.182791 :9B5159A0: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-24 10:00:22.183583 :9B5159A1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 10:00:25.359550 :9B515B18:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 10:00:25.346
2025-05-24 10:00:25.359552 :9B515B19:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10972 pso_num=28 pso_serial#=231
2025-05-24 10:00:25.359684 :9B515B1A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10972 (legacy spawn)
2025-05-24 10:00:25.366079 :9B515B1B: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-24 10:00:25.366260 :9B515B24: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-24 10:00:25.367058 :9B515B25:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 10:00:25.385057 :9B515B28:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 10:00:25.377
2025-05-24 10:00:25.385060 :9B515B29:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11632 pso_num=28 pso_serial#=232
2025-05-24 10:00:25.385196 :9B515B2A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11632 (legacy spawn)
2025-05-24 10:00:25.391602 :9B515B2B: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-24 10:00:25.391772 :9B515B34: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-24 10:00:25.392565 :9B515B35:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 10:00:30.959665 :9B515CA7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 10:00:30.954
2025-05-24 10:00:30.959667 :9B515CA8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9984 pso_num=28 pso_serial#=233
2025-05-24 10:00:30.959808 :9B515CA9:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=9984 osp_idx=26 osp_ver=138441 osp_pg=0 (spawn #214172)
2025-05-24 10:00:30.959809 :9B515CAA: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-24 10:00:30.959809 :9B515CAB:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=36 time=1056603219
2025-05-24 10:00:30.959810 :9B515CAC:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs
2025-05-24 10:00:30.959810 :9B515CAD:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs
2025-05-24 10:00:30.959810 :9B515CAE:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs
2025-05-24 10:00:30.959810 :9B515CAF:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-24 10:00:30.959811 :9B515CB0:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-24 10:00:30.960314 :9B515CB1: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-24 10:00:30.960358 :9B515CB7: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-24 10:00:30.964469 :9B515CCA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517
2025-05-24 10:00:30.964514 :9B515CCB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517
2025-05-24 10:00:30.964777 :9B515CCC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517
2025-05-24 10:00:30.964793 :9B515CCD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517
2025-05-24 10:00:30.965011 :9B515CCE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517
2025-05-24 10:00:30.965025 :9B515CCF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517
2025-05-24 10:00:30.965228 :9B515CDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517
2025-05-24 10:00:30.965241 :9B515CDB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517
2025-05-24 10:00:30.965446 :9B515CDC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517
2025-05-24 10:00:30.965459 :9B515CDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517
2025-05-24 10:00:30.965664 :9B515CDE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517
2025-05-24 10:00:30.965677 :9B515CE0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517
2025-05-24 10:00:30.968335 :9B515CFA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 10:00:30.978101 :9B515D31:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 10:00:30.970
2025-05-24 10:00:30.978102 :9B515D32:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11800 pso_num=28 pso_serial#=234
2025-05-24 10:00:30.978236 :9B515D33:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=11800 osp_idx=26 osp_ver=138442 osp_pg=0 (spawn #214175)
2025-05-24 10:00:30.978237 :9B515D34: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-24 10:00:30.978238 :9B515D35:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=37 time=1056603235
2025-05-24 10:00:30.978238 :9B515D36:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs
2025-05-24 10:00:30.978239 :9B515D37:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs
2025-05-24 10:00:30.978239 :9B515D38:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs
2025-05-24 10:00:30.978240 :9B515D39:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-24 10:00:30.978240 :9B515D3A:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-24 10:00:30.978950 :9B515D3B: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-24 10:00:30.979009 :9B515D41: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-24 10:00:30.987643 :9B515D63:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted
2025-05-24 10:00:31.084583 :9B515EFC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 10:00:31.079
2025-05-24 10:00:31.084584 :9B515EFD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=4804 pso_num=28 pso_serial#=235
2025-05-24 10:00:31.084712 :9B515EFE:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=4804 osp_idx=26 osp_ver=138443 osp_pg=0 (spawn #214176)
2025-05-24 10:00:31.084713 :9B515EFF:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-24 10:00:31.084713 :9B515F00:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=38 time=1056603344
2025-05-24 10:00:31.084714 :9B515F01:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs
2025-05-24 10:00:31.084714 :9B515F02:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs
2025-05-24 10:00:31.084715 :9B515F03:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs
2025-05-24 10:00:31.084715 :9B515F04:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-24 10:00:31.084715 :9B515F05:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-24 10:00:31.085337 :9B515F0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 28 (osid: 4804, J002)