Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706140114\orcl_w000_4196_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:37097M/63366M, Ph+PgF:43112M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 25
Windows thread id: 4196, image: ORACLE.EXE (W000)


*** 2025-07-06 14:01:14.923
*** SESSION ID:(201.16347) 2025-07-06 14:01:14.923
 

*** 2025-07-06 14:01:14.923
Process diagnostic dump for ORACLE.EXE (W000), OS id=4196,
pid: 25, proc_ser: 79, sid: 201, sess_ser: 16347 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=33 seq_num=34 snap_id=1
    wait times: snap=1.524721 sec, exc=1.524721 sec, total=1.524721 sec
    wait times: max=5.000000 sec, heur=2 min 41 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.000034 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=32 seq_num=33 snap_id=1
    wait times: snap=5.001248 sec, exc=5.001248 sec, total=5.001248 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000029 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=31 seq_num=32 snap_id=1
    wait times: snap=5.000321 sec, exc=5.000321 sec, total=5.000321 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=30 seq_num=31 snap_id=1
    wait times: snap=5.004902 sec, exc=5.004902 sec, total=5.004902 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=29 seq_num=30 snap_id=1
    wait times: snap=5.014750 sec, exc=5.014750 sec, total=5.014750 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=28 seq_num=29 snap_id=1
    wait times: snap=5.004628 sec, exc=5.004628 sec, total=5.004628 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000020 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=27 seq_num=28 snap_id=1
    wait times: snap=5.014739 sec, exc=5.014739 sec, total=5.014739 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=26 seq_num=27 snap_id=1
    wait times: snap=5.004621 sec, exc=5.004621 sec, total=5.004621 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=25 seq_num=26 snap_id=1
    wait times: snap=5.004626 sec, exc=5.004626 sec, total=5.004626 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000017 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=24 seq_num=25 snap_id=1
    wait times: snap=5.001731 sec, exc=5.001731 sec, total=5.001731 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=23 seq_num=24 snap_id=1
    wait times: snap=5.010285 sec, exc=5.010285 sec, total=5.010285 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000028 sec of elapsed time
Sampled Session History of session 201 serial 16347
---------------------------------------------------
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
---------------------------------------------------
  [121 samples,                                            13:59:14 - 14:01:14]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-06 14:01:14.923
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 25 (osid: 4196, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-06 12:45:39.822819 :AE4EF882:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17984 pso_num=25 pso_serial#=58
2025-07-06 12:45:39.822956 :AE4EF883:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17984 (legacy spawn)
2025-07-06 12:45:39.829567 :AE4EF88F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:45:39.829726 :AE4EF898:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:45:39.830585 :AE4EF899:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:45:43.196612 :AE4EF983:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:45:43.183
2025-07-06 12:45:43.196613 :AE4EF984:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17596 pso_num=25 pso_serial#=59
2025-07-06 12:45:43.196754 :AE4EF985:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17596 (legacy spawn)
2025-07-06 12:45:43.203360 :AE4EF986:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:45:43.203530 :AE4EF98B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:45:43.204348 :AE4EF990:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:45:43.213146 :AE4EF993:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:45:43.199
2025-07-06 12:45:43.213149 :AE4EF994:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6988 pso_num=25 pso_serial#=60
2025-07-06 12:45:43.213299 :AE4EF995:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=6988 (legacy spawn)
2025-07-06 12:45:43.219781 :AE4EF996:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:45:43.219951 :AE4EF99F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:45:43.220797 :AE4EF9A0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:46:19.596025 :AE4F076D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:46:19.583
2025-07-06 12:46:19.596027 :AE4F076E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18056 pso_num=25 pso_serial#=61
2025-07-06 12:46:19.596163 :AE4F076F:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=18056 (legacy spawn)
2025-07-06 12:46:19.602682 :AE4F0770:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:46:19.602845 :AE4F0779:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:46:19.603610 :AE4F077A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:46:19.612542 :AE4F077D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:46:19.598
2025-07-06 12:46:19.612544 :AE4F077E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3068 pso_num=25 pso_serial#=62
2025-07-06 12:46:19.612683 :AE4F077F:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=3068 (legacy spawn)
2025-07-06 12:46:19.619250 :AE4F0780:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:46:19.619408 :AE4F0788:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:46:19.620200 :AE4F078A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:46:27.616418 :AE4F0A3C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:46:27.608
2025-07-06 12:46:27.616420 :AE4F0A3D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14992 pso_num=25 pso_serial#=63
2025-07-06 12:46:27.616559 :AE4F0A3E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14992 (legacy spawn)
2025-07-06 12:46:27.623208 :AE4F0A3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:46:27.623373 :AE4F0A44:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:46:27.624155 :AE4F0A49:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:46:30.059410 :AE4F0B00:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:46:30.050
2025-07-06 12:46:30.059412 :AE4F0B01:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16372 pso_num=25 pso_serial#=64
2025-07-06 12:46:30.059551 :AE4F0B02:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16372 (legacy spawn)
2025-07-06 12:46:30.066222 :AE4F0B03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:46:30.066383 :AE4F0B08:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:46:30.067179 :AE4F0B0D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:47:29.010035 :AE4F1E70:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:47:28.995
2025-07-06 12:47:29.010037 :AE4F1E71:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8712 pso_num=25 pso_serial#=65
2025-07-06 12:47:29.010191 :AE4F1E72:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8712 (legacy spawn)
2025-07-06 12:47:29.017015 :AE4F1E73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:47:29.017188 :AE4F1E7C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:47:29.017982 :AE4F1E7D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:47:29.027068 :AE4F1E80:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:47:29.026
2025-07-06 12:47:29.027070 :AE4F1E81:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18256 pso_num=25 pso_serial#=66
2025-07-06 12:47:29.027212 :AE4F1E82:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=18256 (legacy spawn)
2025-07-06 12:47:29.033934 :AE4F1E83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:47:29.034098 :AE4F1E8C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:47:29.034911 :AE4F1E8D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:47:29.841624 :AE4F1EDC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:47:29.833
2025-07-06 12:47:29.841626 :AE4F1EDD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14888 pso_num=25 pso_serial#=67
2025-07-06 12:47:29.841772 :AE4F1EDE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14888 (legacy spawn)
2025-07-06 12:47:29.869060 :AE4F1EDF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:47:29.869232 :AE4F1EE8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:47:29.870020 :AE4F1EE9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:47:32.299110 :AE4F1FCC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:47:32.286
2025-07-06 12:47:32.299112 :AE4F1FCD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18232 pso_num=25 pso_serial#=68
2025-07-06 12:47:32.299246 :AE4F1FCE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=18232 (legacy spawn)
2025-07-06 12:47:32.306112 :AE4F1FCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:47:32.306284 :AE4F1FD8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:47:32.307101 :AE4F1FD9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:47:32.316417 :AE4F1FDC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:47:32.302
2025-07-06 12:47:32.316419 :AE4F1FDD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18356 pso_num=25 pso_serial#=69
2025-07-06 12:47:32.316555 :AE4F1FDE:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=18356 (legacy spawn)
2025-07-06 12:47:32.323152 :AE4F1FDF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:47:32.323315 :AE4F1FE8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:47:32.324141 :AE4F1FE9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:47:38.040418 :AE4F21A9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:47:38.031
2025-07-06 12:47:38.040421 :AE4F21AA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13328 pso_num=25 pso_serial#=70
2025-07-06 12:47:38.040555 :AE4F21AB:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13328 (legacy spawn)
2025-07-06 12:47:38.066932 :AE4F21AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 12:47:38.067098 :AE4F21B2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 12:47:38.067908 :AE4F21B6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:48:24.388422 :AE4F316C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:48:24.383
2025-07-06 12:48:24.388425 :AE4F316D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12980 pso_num=25 pso_serial#=71
2025-07-06 12:48:24.388578 :AE4F316E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12980 osp_idx=23 osp_ver=185058 osp_pg=0 (spawn #357893)
2025-07-06 12:48:24.388579 :AE4F316F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-06 12:48:24.388580 :AE4F3170:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=211 time=486880283
2025-07-06 12:48:24.388580 :AE4F3171:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-07-06 12:48:24.388581 :AE4F3172:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-07-06 12:48:24.388581 :AE4F3173:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-07-06 12:48:24.388581 :AE4F3174:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-06 12:48:24.388581 :AE4F3175:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-06 12:48:24.389148 :AE4F3176:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-06 12:48:24.389196 :AE4F317D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-06 12:58:25.215655 :AE4FFD9B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 12:58:25.580258 :AE4FFDEA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 12:58:25.575
2025-07-06 12:58:25.580261 :AE4FFDEB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6856 pso_num=25 pso_serial#=72
2025-07-06 12:58:25.580403 :AE4FFDEC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6856 osp_idx=23 osp_ver=185059 osp_pg=0 (spawn #357916)
2025-07-06 12:58:25.580403 :AE4FFDED:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-06 12:58:25.580404 :AE4FFDEE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=212 time=487481470
2025-07-06 12:58:25.580404 :AE4FFDEF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-07-06 12:58:25.580405 :AE4FFDF0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-07-06 12:58:25.580405 :AE4FFDF1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-07-06 12:58:25.580406 :AE4FFDF2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-06 12:58:25.580406 :AE4FFDF3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-06 12:58:25.581049 :AE4FFDF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-06 12:58:25.581107 :AE4FFDFA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-06 13:08:26.452015 :AE50D8A5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 13:08:26.763663 :AE50D8F6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 13:08:26.758
2025-07-06 13:08:26.763665 :AE50D8F7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10992 pso_num=25 pso_serial#=73
2025-07-06 13:08:26.763797 :AE50D8F8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10992 osp_idx=23 osp_ver=185060 osp_pg=0 (spawn #357943)
2025-07-06 13:08:26.763798 :AE50D8F9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-06 13:08:26.763798 :AE50D8FA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=213 time=488082658
2025-07-06 13:08:26.763799 :AE50D8FB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-07-06 13:08:26.763799 :AE50D8FC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-07-06 13:08:26.763800 :AE50D8FD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-07-06 13:08:26.763800 :AE50D8FE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-06 13:08:26.763800 :AE50D8FF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-06 13:08:26.764289 :AE50D900:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-06 13:08:26.764333 :AE50D908:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-06 13:18:27.701422 :AE51A56F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 13:18:27.995781 :AE51A5AD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 13:18:27.982
2025-07-06 13:18:27.995783 :AE51A5AE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17796 pso_num=25 pso_serial#=74
2025-07-06 13:18:27.995922 :AE51A5AF:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17796 (legacy spawn)
2025-07-06 13:18:28.002376 :AE51A5B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 13:18:28.002541 :AE51A5B6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 13:18:28.003349 :AE51A5BA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 13:18:28.012005 :AE51A5BD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 13:18:27.998
2025-07-06 13:18:28.012007 :AE51A5BE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16196 pso_num=25 pso_serial#=75
2025-07-06 13:18:28.012139 :AE51A5BF:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16196 (legacy spawn)
2025-07-06 13:18:28.018567 :AE51A5C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 13:18:28.018727 :AE51A5C9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06 13:18:28.019508 :AE51A5CA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 13:18:28.049657 :AE51A5E4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 13:18:28.045
2025-07-06 13:18:28.049659 :AE51A5E5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18160 pso_num=25 pso_serial#=76
2025-07-06 13:18:28.049793 :AE51A5E6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18160 osp_idx=23 osp_ver=185063 osp_pg=0 (spawn #357965)
2025-07-06 13:18:28.049794 :AE51A5E7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-06 13:18:28.049794 :AE51A5E8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=488683939
2025-07-06 13:18:28.049794 :AE51A5E9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-07-06 13:18:28.049795 :AE51A5EA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-07-06 13:18:28.049795 :AE51A5EB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-07-06 13:18:28.049795 :AE51A5EC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-06 13:18:28.049796 :AE51A5ED:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-06 13:18:28.050321 :AE51A5EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-06 13:18:28.050376 :AE51A5F5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-06 13:28:28.973516 :AE5272D9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 13:28:29.353136 :AE52735F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 13:28:29.348
2025-07-06 13:28:29.353138 :AE527360:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17720 pso_num=25 pso_serial#=77
2025-07-06 13:28:29.353272 :AE527361:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17720 osp_idx=23 osp_ver=185064 osp_pg=0 (spawn #357988)
2025-07-06 13:28:29.353273 :AE527362:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-06 13:28:29.353274 :AE527363:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=489285251
2025-07-06 13:28:29.353274 :AE527364:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-07-06 13:28:29.353275 :AE527365:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-07-06 13:28:29.353275 :AE527366:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-07-06 13:28:29.353276 :AE527367:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-06 13:28:29.353276 :AE527368:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-06 13:28:29.353774 :AE527369:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-06 13:28:29.353829 :AE527373:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-06 13:31:09.967537 :AE52A9AB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-06 13:38:30.678487 :AE53402D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-06 13:48:31.440815 :AE540C1A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 13:48:31.945315 :AE540C75:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 13:48:31.940
2025-07-06 13:48:31.945316 :AE540C76:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17560 pso_num=25 pso_serial#=78
2025-07-06 13:48:31.945455 :AE540C77:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17560 osp_idx=23 osp_ver=185065 osp_pg=0 (spawn #358032)
2025-07-06 13:48:31.945456 :AE540C78:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-06 13:48:31.945457 :AE540C79:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=490487845
2025-07-06 13:48:31.945457 :AE540C7A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-07-06 13:48:31.945457 :AE540C7B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-07-06 13:48:31.945458 :AE540C7C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-07-06 13:48:31.945458 :AE540C7D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-06 13:48:31.945459 :AE540C7E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-06 13:48:31.945982 :AE540C7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-06 13:48:31.946035 :AE540C85:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-06 13:58:32.780832 :AE54D8D7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-07-06 13:58:33.207485 :AE54D928:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-06 13:58:33.203
2025-07-06 13:58:33.207487 :AE54D929:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4196 pso_num=25 pso_serial#=79
2025-07-06 13:58:33.207627 :AE54D92A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4196 osp_idx=23 osp_ver=185066 osp_pg=0 (spawn #358055)
2025-07-06 13:58:33.207628 :AE54D92B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-06 13:58:33.207628 :AE54D92C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=491089095
2025-07-06 13:58:33.207629 :AE54D92D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-07-06 13:58:33.207629 :AE54D92E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-07-06 13:58:33.207630 :AE54D92F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-07-06 13:58:33.207630 :AE54D930:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-06 13:58:33.207630 :AE54D931:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-06 13:58:33.208137 :AE54D932:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-06 13:58:33.208184 :AE54D93B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 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 25 (osid: 4196, W000)