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


*** 2025-08-23 10:00:33.305
*** SESSION ID:(193.28071) 2025-08-23 10:00:33.305
 

*** 2025-08-23 10:00:33.305
Process diagnostic dump for ORACLE.EXE (W000), OS id=21488,
pid: 24, proc_ser: 130, sid: 193, sess_ser: 28071 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=78 seq_num=79 snap_id=1
    wait times: snap=0.457216 sec, exc=0.457216 sec, total=0.457216 sec
    wait times: max=5.000000 sec, heur=6 min 25 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.000042 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=77 seq_num=78 snap_id=1
    wait times: snap=5.014270 sec, exc=5.014270 sec, total=5.014270 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000039 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=76 seq_num=77 snap_id=1
    wait times: snap=5.014727 sec, exc=5.014727 sec, total=5.014727 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=75 seq_num=76 snap_id=1
    wait times: snap=5.004625 sec, exc=5.004625 sec, total=5.004625 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000031 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=74 seq_num=75 snap_id=1
    wait times: snap=5.004635 sec, exc=5.004635 sec, total=5.004635 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000033 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=73 seq_num=74 snap_id=1
    wait times: snap=5.007238 sec, exc=5.007238 sec, total=5.007238 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000033 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=72 seq_num=73 snap_id=1
    wait times: snap=5.004033 sec, exc=5.004033 sec, total=5.004033 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=71 seq_num=72 snap_id=1
    wait times: snap=5.011073 sec, exc=5.011073 sec, total=5.011073 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000030 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=70 seq_num=71 snap_id=1
    wait times: snap=5.010664 sec, exc=5.010664 sec, total=5.010664 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=69 seq_num=70 snap_id=1
    wait times: snap=5.004605 sec, exc=5.004605 sec, total=5.004605 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000036 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=68 seq_num=69 snap_id=1
    wait times: snap=5.004608 sec, exc=5.004608 sec, total=5.004608 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000037 sec of elapsed time
Sampled Session History of session 193 serial 28071
---------------------------------------------------
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,                                            09:58:33 - 10:00:33]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-23 10:00:33.305
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 21488, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-23 09:22:33.172871 :C38312BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879
2025-08-23 09:22:33.172901 :C38312C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879
2025-08-23 09:22:33.180903 :C38312C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:22:33.180960 :C38312C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879
2025-08-23 09:22:33.180989 :C38312CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879
2025-08-23 09:22:33.182984 :C38312D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.183031 :C38312D1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.183328 :C38312D2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.183365 :C38312D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.183575 :C38312D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.183605 :C38312D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.183698 :C38312D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.183928 :C38312D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:22:33.183965 :C38312DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.191907 :C38312E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:22:33.191968 :C38312E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879
2025-08-23 09:22:33.192000 :C38312E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879
2025-08-23 09:22:33.193757 :C38312EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.193801 :C38312EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.193825 :C38312EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.193860 :C38312ED:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194180 :C38312EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194209 :C38312EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194215 :C38312F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194240 :C38312F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194343 :C38312F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194369 :C38312F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194375 :C38312F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194400 :C38312F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194498 :C38312F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194528 :C38312F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194534 :C38312F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194559 :C38312F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194656 :C38312FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194682 :C38312FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194687 :C38312FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194712 :C38312FD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194808 :C38312FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194833 :C38312FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194839 :C3831300:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194863 :C3831301:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.194965 :C3831302:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.194991 :C3831303:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.195025 :C3831304:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.195267 :C3831305:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:22:33.195302 :C3831308:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.207291 :C3831319:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:22:33.207360 :C383131C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879
2025-08-23 09:22:33.207393 :C383131D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879
2025-08-23 09:22:33.207714 :C3831323:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-08-23 09:22:33.208047 :C3831324:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-08-23 09:22:33.208169 :C3831325:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.208394 :C3831326:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:22:33.208419 :C3831329:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.210019 :C383132F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:22:33.210267 :C3831330:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:22:33.210312 :C3831333:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:22:33.211294 :C3831339:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:22:33.211416 :C3831342:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:23:03.334962 :C3831CE7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:23:11.675825 :C3831F3D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:23:11.663
2025-08-23 09:23:11.675827 :C3831F3E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22252 pso_num=24 pso_serial#=119
2025-08-23 09:23:11.675963 :C3831F3F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22252 (legacy spawn)
2025-08-23 09:23:11.682514 :C3831F40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:11.682688 :C3831F45:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:23:11.683483 :C3831F4A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:23:11.692255 :C3831F4D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:23:11.679
2025-08-23 09:23:11.692257 :C3831F4E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11336 pso_num=24 pso_serial#=120
2025-08-23 09:23:11.692396 :C3831F4F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11336 (legacy spawn)
2025-08-23 09:23:11.698913 :C3831F50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:11.699070 :C3831F59:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:23:11.699873 :C3831F5A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:23:29.225381 :C38324ED:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:23:29.212
2025-08-23 09:23:29.225383 :C38324EE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15660 pso_num=24 pso_serial#=121
2025-08-23 09:23:29.225530 :C38324EF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15660 (legacy spawn)
2025-08-23 09:23:29.232085 :C38324F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:29.232249 :C38324F5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:23:29.233066 :C38324FA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:23:29.696406 :C38324FD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:23:29.696
2025-08-23 09:23:29.696407 :C38324FE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20908 pso_num=24 pso_serial#=122
2025-08-23 09:23:29.696549 :C38324FF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20908 (legacy spawn)
2025-08-23 09:23:29.703365 :C3832500:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:29.703557 :C3832508:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:23:29.704502 :C383250A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:23:29.713992 :C383250D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:23:29.712
2025-08-23 09:23:29.713995 :C383250E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8628 pso_num=24 pso_serial#=123
2025-08-23 09:23:29.714188 :C383250F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8628 (legacy spawn)
2025-08-23 09:23:29.730375 :C3832510:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:29.730545 :C3832519:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:23:29.731438 :C383251A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:23:32.914592 :C3832620:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:23:32.901
2025-08-23 09:23:32.914594 :C3832621:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20520 pso_num=24 pso_serial#=124
2025-08-23 09:23:32.914740 :C3832622:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20520 (legacy spawn)
2025-08-23 09:23:32.921262 :C3832623:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:32.921439 :C3832628:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:23:32.922265 :C383262D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:23:32.930994 :C3832630:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:23:32.917
2025-08-23 09:23:32.930996 :C3832631:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17496 pso_num=24 pso_serial#=125
2025-08-23 09:23:32.931133 :C3832632:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17496 (legacy spawn)
2025-08-23 09:23:32.937565 :C3832633:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:32.937724 :C383263C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:23:32.938539 :C383263D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:23:33.173792 :C3832684:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:23:33.167
2025-08-23 09:23:33.173795 :C3832685:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22128 pso_num=24 pso_serial#=126
2025-08-23 09:23:33.173958 :C3832686:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22128 osp_idx=22 osp_ver=776866 osp_pg=0 (spawn #517403)
2025-08-23 09:23:33.173959 :C3832687:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-23 09:23:33.173960 :C3832688:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=243 time=326715190
2025-08-23 09:23:33.173960 :C3832689:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-08-23 09:23:33.173961 :C383268A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-08-23 09:23:33.173961 :C383268B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-08-23 09:23:33.173961 :C383268C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-23 09:23:33.173962 :C383268D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-23 09:23:33.174557 :C383268E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-23 09:23:33.175457 :C38326A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:33.183709 :C38326BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-08-23 09:23:33.184123 :C38326C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:33.184163 :C38326C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-08-23 09:23:33.185399 :C38326C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 09:23:33.185548 :C38326D0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:24:03.263564 :C3833011:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:24:03.393110 :C383302B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:24:03.388
2025-08-23 09:24:03.393113 :C383302C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21344 pso_num=24 pso_serial#=127
2025-08-23 09:24:03.393257 :C383302D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21344 osp_idx=22 osp_ver=776867 osp_pg=0 (spawn #517405)
2025-08-23 09:24:03.393258 :C383302E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-23 09:24:03.393259 :C383302F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=244 time=326745408
2025-08-23 09:24:03.393260 :C3833030:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-08-23 09:24:03.393260 :C3833031:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-08-23 09:24:03.393261 :C3833032:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-08-23 09:24:03.393261 :C3833033:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-23 09:24:03.393261 :C3833034:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-23 09:24:03.393773 :C3833035:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-23 09:24:03.393820 :C383303D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:34:04.344962 :C383F686:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:34:04.709780 :C383F6C4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:34:04.704
2025-08-23 09:34:04.709783 :C383F6C5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22256 pso_num=24 pso_serial#=128
2025-08-23 09:34:04.709939 :C383F6C6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22256 osp_idx=22 osp_ver=776868 osp_pg=0 (spawn #517427)
2025-08-23 09:34:04.709940 :C383F6C7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-23 09:34:04.709940 :C383F6C8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=245 time=327346721
2025-08-23 09:34:04.709941 :C383F6C9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-08-23 09:34:04.709941 :C383F6CA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-08-23 09:34:04.709942 :C383F6CB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-08-23 09:34:04.709942 :C383F6CC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-23 09:34:04.709943 :C383F6CD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-23 09:34:04.710588 :C383F6CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-23 09:34:04.710644 :C383F6D5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:44:05.726952 :C384BB9D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:44:06.012844 :C384BBE4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:44:06.007
2025-08-23 09:44:06.012846 :C384BBE5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21580 pso_num=24 pso_serial#=129
2025-08-23 09:44:06.012986 :C384BBE6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21580 osp_idx=22 osp_ver=776869 osp_pg=0 (spawn #517450)
2025-08-23 09:44:06.012987 :C384BBE7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-23 09:44:06.012987 :C384BBE8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=246 time=327948018
2025-08-23 09:44:06.012987 :C384BBE9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-08-23 09:44:06.012988 :C384BBEA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-08-23 09:44:06.012988 :C384BBEB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-08-23 09:44:06.012989 :C384BBEC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-23 09:44:06.012989 :C384BBED:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-23 09:44:06.013507 :C384BBEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-23 09:44:06.013552 :C384BBF6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-23 09:54:06.878445 :C385821E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-08-23 09:54:07.320926 :C3858259:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 09:54:07.315
2025-08-23 09:54:07.320928 :C385825A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21488 pso_num=24 pso_serial#=130
2025-08-23 09:54:07.321071 :C385825B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21488 osp_idx=22 osp_ver=776870 osp_pg=0 (spawn #517473)
2025-08-23 09:54:07.321071 :C385825C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-23 09:54:07.321072 :C385825D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=247 time=328549330
2025-08-23 09:54:07.321072 :C385825E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-08-23 09:54:07.321073 :C385825F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-08-23 09:54:07.321073 :C3858260:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-08-23 09:54:07.321073 :C3858261:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-23 09:54:07.321074 :C3858262:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-23 09:54:07.321677 :C3858263:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-23 09:54:07.321724 :C3858269:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 21488, W000)