Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250520220009\orcl_j003_8392_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:37332M/63366M, Ph+PgF:44435M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 29
Windows thread id: 8392, image: ORACLE.EXE (J003)


*** 2025-05-20 22:00:09.344
*** SESSION ID:(233.22753) 2025-05-20 22:00:09.344
 

*** 2025-05-20 22:00:09.344
Process diagnostic dump for ORACLE.EXE (J003), OS id=8392,
pid: 29, proc_ser: 129, sid: 233, sess_ser: 22753 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 8392
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=10 seq_num=11 snap_id=1
    wait times: snap=0.260672 sec, exc=0.260672 sec, total=0.260672 sec
    wait times: max=0.500000 sec, heur=5.269423 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=9 seq_num=10 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.000013 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 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.000008 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.510141 sec, exc=0.510141 sec, total=0.510141 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=4 seq_num=5 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.000008 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 snap_id=1
    wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 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=1 seq_num=2 snap_id=1
    wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000020 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.498548 sec, exc=0.498548 sec, total=0.498548 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 233 serial 22753
---------------------------------------------------
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
---------------------------------------------------
  [6 samples,                                              22:00:04 - 22:00:09]
    idle wait at each sample
  [session created at: 22:00:04]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-05-20 22:00:09.344
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 29 (osid: 8392, J003)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-20 00:05:50.331055 :99663268:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13468 (legacy spawn)
2025-05-20 00:05:50.337445 :99663269:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 00:05:50.337611 :99663272:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 00:05:50.338461 :99663273:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 00:05:52.971541 :9966336A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 00:05:52.963
2025-05-20 00:05:52.971544 :9966336B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10456 pso_num=29 pso_serial#=118
2025-05-20 00:05:52.971678 :9966336C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10456 (legacy spawn)
2025-05-20 00:05:52.978111 :9966336D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 00:05:52.978288 :99663372:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 00:05:52.979143 :99663377:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 00:05:53.632960 :9966337A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 00:05:53.620
2025-05-20 00:05:53.632962 :9966337B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12300 pso_num=29 pso_serial#=119
2025-05-20 00:05:53.633098 :9966337C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12300 (legacy spawn)
2025-05-20 00:05:53.639638 :9966337D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 00:05:53.639810 :99663386:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 00:05:53.640651 :99663387:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 00:05:53.649331 :9966338A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 00:05:53.635
2025-05-20 00:05:53.649333 :9966338B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13644 pso_num=29 pso_serial#=120
2025-05-20 00:05:53.649463 :9966338C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13644 (legacy spawn)
2025-05-20 00:05:53.656180 :9966338D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 00:05:53.656351 :99663396:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 00:05:53.657226 :99663397:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 00:05:55.379505 :99663461:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 00:05:55.372
2025-05-20 00:05:55.379507 :99663462:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14172 pso_num=29 pso_serial#=121
2025-05-20 00:05:55.379640 :99663463:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14172 (legacy spawn)
2025-05-20 00:05:55.386081 :99663464:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 00:05:55.386264 :99663469:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 00:05:55.387072 :9966346E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 01:00:19.082109 :996A67EF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 01:00:19.074
2025-05-20 01:00:19.082111 :996A67F0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13296 pso_num=29 pso_serial#=122
2025-05-20 01:00:19.082249 :996A67F1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13296 (legacy spawn)
2025-05-20 01:00:19.088707 :996A67F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:19.088875 :996A67F7:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 01:00:19.089731 :996A67FC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 01:00:22.129273 :996A68E6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 01:00:22.115
2025-05-20 01:00:22.129275 :996A68E7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8908 pso_num=29 pso_serial#=123
2025-05-20 01:00:22.129416 :996A68E8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=8908 (legacy spawn)
2025-05-20 01:00:22.135964 :996A68E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:22.136144 :996A68EE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 01:00:22.136962 :996A68F3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 01:00:22.145713 :996A68F6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 01:00:22.131
2025-05-20 01:00:22.145715 :996A68F7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12532 pso_num=29 pso_serial#=124
2025-05-20 01:00:22.145854 :996A68F8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12532 (legacy spawn)
2025-05-20 01:00:22.152713 :996A68F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:22.152875 :996A6902:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 01:00:22.153866 :996A6903:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 01:00:24.173272 :996A69EF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 01:00:24.164
2025-05-20 01:00:24.173274 :996A69F0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13464 pso_num=29 pso_serial#=125
2025-05-20 01:00:24.173408 :996A69F1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13464 (legacy spawn)
2025-05-20 01:00:24.197036 :996A69F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:24.197208 :996A69F7:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 01:00:24.198078 :996A69FC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 01:00:25.302256 :996A6A3F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 01:00:25.289
2025-05-20 01:00:25.302258 :996A6A40:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9532 pso_num=29 pso_serial#=126
2025-05-20 01:00:25.302393 :996A6A41:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9532 (legacy spawn)
2025-05-20 01:00:25.666765 :996A6A42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:25.666942 :996A6A47:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 01:00:25.667768 :996A6A4C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 01:00:25.676740 :996A6A4F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 01:00:25.664
2025-05-20 01:00:25.676742 :996A6A50:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12668 pso_num=29 pso_serial#=127
2025-05-20 01:00:25.676878 :996A6A51:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12668 (legacy spawn)
2025-05-20 01:00:25.683396 :996A6A52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:25.683559 :996A6A5B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 01:00:25.684388 :996A6A5C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 01:00:29.917559 :996A6C06:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 01:00:29.912
2025-05-20 01:00:29.917561 :996A6C07:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14112 pso_num=29 pso_serial#=128
2025-05-20 01:00:29.917736 :996A6C08:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14112 osp_idx=27 osp_ver=11347 osp_pg=0 (spawn #199599)
2025-05-20 01:00:29.917737 :996A6C09:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-20 01:00:29.917737 :996A6C0A:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=201 time=678639782
2025-05-20 01:00:29.917738 :996A6C0B:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-05-20 01:00:29.917738 :996A6C0C:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-05-20 01:00:29.917738 :996A6C0D:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-05-20 01:00:29.917739 :996A6C0E:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-20 01:00:29.917739 :996A6C0F:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-20 01:00:29.918264 :996A6C10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-20 01:00:29.918312 :996A6C16:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 01:00:29.922796 :996A6C2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:29.946650 :996A6C58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:29.961181 :996A6C60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:29.987730 :996A6C68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:29.991392 :996A6C70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:29.991689 :996A6C78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:29.995407 :996A6C80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:29.998083 :996A6C88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:29.999688 :996A6C90:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:30.000820 :996A6C98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:30.004291 :996A6CA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:31.240195*:996A6CE7:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299EF958 estart=1747674031 eid=16778597 ctx=0x0000000787AC3BF0 cwh=0x0000000029950AA0 cpu=0 buffg=0 interb=0 r=0 rb=0 w=0 wb=0
2025-05-20 01:00:31.240195*:996A6CE8:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299EF958 curCtx=0x00000000299DFCD0 pubTabIdxCnt=0
2025-05-20 01:00:31.240195*:996A6CE9:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299EF958
2025-05-20 01:00:31.240195*:996A6CEA:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299EF958
2025-05-20 01:00:31.240195*:996A6CEB:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299EF958 curCtx=0x00000000299DFCD0 xsc->flg4=65792 flags=2
2025-05-20 01:00:31.240195*:996A6CEC:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299EF958
2025-05-20 01:00:31.257891 :996A6CED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:31.284629 :996A6CF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:31.284951 :996A6CFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:31.285231 :996A6D05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:31.285249 :996A6D08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 01:00:31.285308 :996A6D14:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-20 01:00:31.288115 :996A6D22:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-05-20 22:00:02.206916 :99CC8466:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-20 22:00:02.194
2025-05-20 22:00:02.206917 :99CC8467:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8392 pso_num=29 pso_serial#=129
2025-05-20 22:00:02.207054 :99CC8468:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=8392 osp_idx=27 osp_ver=11348 osp_pg=0 (spawn #202508)
2025-05-20 22:00:02.207054 :99CC8469:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-20 22:00:02.207055 :99CC846A:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=202 time=754212063
2025-05-20 22:00:02.207055 :99CC846B:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-05-20 22:00:02.207055 :99CC846C:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-05-20 22:00:02.207056 :99CC846D:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-05-20 22:00:02.207056 :99CC846E:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-20 22:00:02.207056 :99CC846F:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-20 22:00:02.207647 :99CC8471:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-20 22:00:02.212561 :99CC85E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:02.212594 :99CC85E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203
2025-05-20 22:00:02.218279 :99CC8616:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:02.429438 :99CC8786:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:02.621521 :99CC8796:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:02.755218 :99CC87B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.871801 :99CC89A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.872732 :99CC89A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.876666 :99CC89B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.879045 :99CC89B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.881247 :99CC89C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.882615 :99CC89C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.884075 :99CC89D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.903687 :99CC89D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.904307 :99CC89E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.904837 :99CC89E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.905346 :99CC89F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.908367 :99CC89F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.909279 :99CC8A01:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:03.910052 :99CC8A02:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:03.910787 :99CC8A03:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:03.911165 :99CC8A04:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:03.911171 :99CC8A05:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:03.911669 :99CC8A06:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:03.912217 :99CC8A07:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:03.912506 :99CC8A08:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:03.913605 :99CC8A09:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:03.949299*:99CC8A1C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031976D20 estart=1747749602 eid=16777834 ctx=0x000000077B458BE8 cwh=0x00000000318DBFD0 cpu=46875 buffg=1438 interb=98304 r=12 rb=98304 w=0 wb=0
2025-05-20 22:00:03.949299*:99CC8A1D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031976D20 curCtx=0x0000000031F97D70 pubTabIdxCnt=0
2025-05-20 22:00:03.949299*:99CC8A1E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031976D20
2025-05-20 22:00:03.949299*:99CC8A1F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031976D20
2025-05-20 22:00:03.949299*:99CC8A20:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031976D20 curCtx=0x0000000031F97D70 xsc->flg4=65792 flags=2
2025-05-20 22:00:03.949299*:99CC8A21:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031976D20
2025-05-20 22:00:03.952088 :99CC8A22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.952131 :99CC8A25:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:03.953020 :99CC8A2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.953862 :99CC8A33:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:03.954315 :99CC8A34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.974728 :99CC8A3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.974776 :99CC8A3F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:03.975666 :99CC8A47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:03.987128 :99CC8A59:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:03.987693 :99CC8A5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.008717 :99CC8A6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.008760 :99CC8A6F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:04.009497 :99CC8A7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.010318 :99CC8A94:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:04.010941 :99CC8A9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.029197 :99CC8AFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.029244 :99CC8B01:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:04.030076 :99CC8B07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.030924 :99CC8B0F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:04.031393 :99CC8B10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.053073 :99CC8B1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.053121 :99CC8B1D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:04.054011 :99CC8B23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.054846 :99CC8B2B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-05-20 22:00:04.055285 :99CC8B2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.076153 :99CC8B3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.076209 :99CC8B42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-05-20 22:00:04.077085 :99CC8B48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.077559 :99CC8B50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.077914 :99CC8B58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.078230 :99CC8B60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.078372 :99CC8B68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.078514 :99CC8B70:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203
2025-05-20 22:00:04.082708 :99CC8B71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-20 22:00:04.082795 :99CC8B74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name=   type=0
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 29 (osid: 8392, J003)