Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250915220005\orcl_j004_18800_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:34716M/63366M, Ph+PgF:39306M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 30
Windows thread id: 18800, image: ORACLE.EXE (J004)


*** 2025-09-15 22:00:05.239
*** SESSION ID:(241.4739) 2025-09-15 22:00:05.239
 

*** 2025-09-15 22:00:05.239
Process diagnostic dump for ORACLE.EXE (J004), OS id=18800,
pid: 30, proc_ser: 163, sid: 241, sess_ser: 4739 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18800
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.294569 sec, exc=0.294569 sec, total=0.294569 sec
    wait times: max=0.500000 sec, heur=2.804221 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.000010 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=0.510123 sec, exc=0.510123 sec, total=0.510123 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 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.000009 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=1 seq_num=2 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=0 seq_num=1 snap_id=1
    wait times: snap=0.499486 sec, exc=0.499486 sec, total=0.499486 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 241 serial 4739
---------------------------------------------------
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
---------------------------------------------------
  [3 samples,                                              22:00:02 - 22:00:05]
    idle wait at each sample
  [session created at: 22:00:02]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-15 22:00:05.239
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 30 (osid: 18800, J004)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-14 06:00:03.781273 :CD2CC95A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 06:00:03.781410 :CD2CC962:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 06:00:03.781549 :CD2CC96C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203
2025-09-14 06:00:03.785361 :CD2CC96D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 06:00:03.785443 :CD2CC970:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name=   type=0
2025-09-14 06:00:03.785573 :CD2CC97D:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-14 06:00:33.892643 :CD2CD893:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-09-14 16:00:10.433389 :CD5CA21A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-14 16:00:10.420
2025-09-14 16:00:10.433391 :CD5CA21B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=23796 pso_num=30 pso_serial#=157
2025-09-14 16:00:10.433533 :CD5CA21C:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=23796 osp_idx=28 osp_ver=2196 osp_pg=0 (spawn #591684)
2025-09-14 16:00:10.433534 :CD5CA21D:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 16:00:10.433534 :CD5CA21E:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=100 time=2251261737
2025-09-14 16:00:10.433535 :CD5CA21F:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 16:00:10.433535 :CD5CA220:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 16:00:10.433535 :CD5CA221:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 16:00:10.433535 :CD5CA222:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 16:00:10.433536 :CD5CA223:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 16:00:10.434045 :CD5CA224:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.434092 :CD5CA22A:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-14 16:00:10.441412 :CD5CA238:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.442506 :CD5CA240:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.443083 :CD5CA248:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.443554 :CD5CA250:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.443993 :CD5CA258:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.446513 :CD5CA260:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.447608 :CD5CA268:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.499326 :CD5CA270:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.500259 :CD5CA278:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.500504 :CD5CA280:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.500712 :CD5CA288:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 16:00:10.502587 :CD5CA290:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-09-14 23:59:05.023861 :CD8287FF:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-14 23:59:05.011
2025-09-14 23:59:05.023863 :CD828800:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=24024 pso_num=30 pso_serial#=158
2025-09-14 23:59:05.023988 :CD828801:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=24024 (legacy spawn)
2025-09-14 23:59:05.030391 :CD82880A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 23:59:05.030543 :CD828813:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-14 23:59:05.031296 :CD828814:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-09-14 23:59:05.599622 :CD828817:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-14 23:59:05.589
2025-09-14 23:59:05.599624 :CD828818:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=24764 pso_num=30 pso_serial#=159
2025-09-14 23:59:05.599782 :CD828819:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=24764 (legacy spawn)
2025-09-14 23:59:05.614705 :CD82881A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 23:59:38.882055 :CD8293F8:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-09-14 23:59:46.668340 :CD829732:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-14 23:59:46.656
2025-09-14 23:59:46.668342 :CD829733:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=23384 pso_num=30 pso_serial#=160
2025-09-14 23:59:46.668482 :CD829734:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=23384 (legacy spawn)
2025-09-14 23:59:46.683394 :CD829735:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 00:00:19.791424 :CD82A5FE:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-09-15 01:00:19.161264 :CD875DF2:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-15 01:00:19.149
2025-09-15 01:00:19.161267 :CD875DF3:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=23108 pso_num=30 pso_serial#=161
2025-09-15 01:00:19.161411 :CD875DF4:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=23108 osp_idx=28 osp_ver=2200 osp_pg=0 (spawn #592933)
2025-09-15 01:00:19.161412 :CD875DF5:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-15 01:00:19.161412 :CD875DF6:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=101 time=2283670455
2025-09-15 01:00:19.161413 :CD875DF7:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 01:00:19.161413 :CD875DF8:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 01:00:19.161414 :CD875DF9:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 01:00:19.161414 :CD875DFA:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 01:00:19.161414 :CD875DFB:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 01:00:19.161927 :CD875DFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.161970 :CD875E02:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-15 01:00:19.169887 :CD875E10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.170979 :CD875E18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.171454 :CD875E20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.171986 :CD875E28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.172429 :CD875E30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.174970 :CD875E38:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.176178 :CD875E40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.229638 :CD875E48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.230543 :CD875E50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.230774 :CD875E58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.231024 :CD875E60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 01:00:19.232880 :CD875E68:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-09-15 16:00:16.985702 :CDCE9DC9:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-15 16:00:16.983
2025-09-15 16:00:16.985703 :CDCE9DCA:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=20616 pso_num=30 pso_serial#=162
2025-09-15 16:00:16.985828 :CDCE9DCD:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=20616 osp_idx=28 osp_ver=2201 osp_pg=0 (spawn #595014)
2025-09-15 16:00:16.985828 :CDCE9DCE:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-15 16:00:16.985829 :CDCE9DCF:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=102 time=2337668283
2025-09-15 16:00:16.985829 :CDCE9DD0:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 16:00:16.985830 :CDCE9DD1:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 16:00:16.985831 :CDCE9DD2:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 16:00:16.985832 :CDCE9DD3:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 16:00:16.985832 :CDCE9DD4:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 16:00:16.986340 :CDCE9DD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-15 16:00:16.986382 :CDCE9DDD:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-15 16:00:16.991792 :CDCE9DF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:17.016003 :CDCE9E26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:17.030944 :CDCE9E2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:17.064599 :CDCE9E36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:17.068770 :CDCE9E3E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:17.072373 :CDCE9E46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:17.076598 :CDCE9E4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:17.077593 :CDCE9E56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:18.045164*:CDCE9E75:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB73480 estart=1757923217 eid=16781469 ctx=0x000000077BA4B1C8 cwh=0x000000002DB60B90 cpu=359375 buffg=0 interb=0 r=0 rb=0 w=0 wb=0
2025-09-15 16:00:18.045164*:CDCE9E76:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB73480 curCtx=0x000000002DBAF9B8 pubTabIdxCnt=0
2025-09-15 16:00:18.045164*:CDCE9E77:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB73480
2025-09-15 16:00:18.045164*:CDCE9E78:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB73480
2025-09-15 16:00:18.045164*:CDCE9E79:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DBBFCB8 estart=1757923217 eid=16781444 ctx=0x0000000787AD5BF0 cwh=0x000000002DB607D0 cpu=359375 buffg=0 interb=0 r=0 rb=0 w=0 wb=0
2025-09-15 16:00:18.045164*:CDCE9E7A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DBBFCB8 curCtx=0x000000002DBB25F8 pubTabIdxCnt=0
2025-09-15 16:00:18.045164*:CDCE9E7B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DBBFCB8
2025-09-15 16:00:18.045164*:CDCE9E7C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DBBFCB8
2025-09-15 16:00:18.232678*:CDCE9E90:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB73480 curCtx=0x000000002DBAF9B8 xsc->flg4=65792 flags=1
2025-09-15 16:00:18.232678*:CDCE9E91:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB73480
2025-09-15 16:00:18.232678*:CDCE9E92:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DBBFCB8 curCtx=0x000000002DBB25F8 xsc->flg4=65792 flags=2
2025-09-15 16:00:18.232678*:CDCE9E93:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DBBFCB8
2025-09-15 16:00:18.296929 :CDCE9E94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:18.351299 :CDCE9E9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:18.351667 :CDCE9EA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:18.351969 :CDCE9EAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:18.351988 :CDCE9EAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 16:00:18.352058 :CDCE9EBB:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-15 16:00:18.355331 :CDCE9EC9:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-09-15 22:00:00.513510 :CDEB2548:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-15 22:00:00.500
2025-09-15 22:00:00.513512 :CDEB2549:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18800 pso_num=30 pso_serial#=163
2025-09-15 22:00:00.513709 :CDEB254E:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=18800 osp_idx=28 osp_ver=2202 osp_pg=0 (spawn #595848)
2025-09-15 22:00:00.513710 :CDEB254F:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-15 22:00:00.513710 :CDEB2550:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=103 time=2359251815
2025-09-15 22:00:00.513711 :CDEB2551:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 22:00:00.513711 :CDEB2552:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 22:00:00.513711 :CDEB2553:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 22:00:00.513712 :CDEB2554:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 22:00:00.513712 :CDEB2555:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 22:00:00.514329 :CDEB2563:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-15 22:00:00.519338 :CDEB2612:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:00.519393 :CDEB261A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203
2025-09-15 22:00:00.524967 :CDEB270E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:00.756794 :CDEB283E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:00.966950 :CDEB286E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:01.108880 :CDEB2881:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.225106 :CDEB2A87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.226160 :CDEB2A8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.230494 :CDEB2A97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.233018 :CDEB2A9F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.234605 :CDEB2AA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.237078 :CDEB2AAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.238607 :CDEB2AB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.261463 :CDEB2ACA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.262178 :CDEB2AD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.262722 :CDEB2ADA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.263234 :CDEB2AE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.266120 :CDEB2AEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.267037 :CDEB2AF2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.267845 :CDEB2AF3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.268718 :CDEB2AF4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.269159 :CDEB2AF5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.269166 :CDEB2AF6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.269804 :CDEB2AF7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.270328 :CDEB2AF8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.270664 :CDEB2AF9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.271776 :CDEB2AFA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.309824 :CDEB2AFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.309876 :CDEB2AFE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.310864 :CDEB2B04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.311833 :CDEB2B0C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.312293 :CDEB2B0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.333154 :CDEB2B15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.333215 :CDEB2B18:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.334183 :CDEB2B1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.335221 :CDEB2B26:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.335703 :CDEB2B27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.354690 :CDEB2B39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.354741 :CDEB2B3C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.355688 :CDEB2B42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.368047 :CDEB2B5E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.368711 :CDEB2B6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.389508 :CDEB2B8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.389564 :CDEB2B8D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.390561 :CDEB2B93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.391517 :CDEB2B9B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.392022 :CDEB2B9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.414235 :CDEB2BA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.414284 :CDEB2BA7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.415227 :CDEB2BAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.416179 :CDEB2BB5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-09-15 22:00:02.416656 :CDEB2BB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.437794 :CDEB2BBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.437859 :CDEB2BC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-09-15 22:00:02.438819 :CDEB2BC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.439448 :CDEB2BCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.439869 :CDEB2BD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.440061 :CDEB2BDF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.440207 :CDEB2BE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.440367 :CDEB2BEF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203
2025-09-15 22:00:02.444887 :CDEB2BF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.444984 :CDEB2BF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name=   type=0
2025-09-15 22:00:02.445090 :CDEB2C00:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 30 (osid: 18800, J004)