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


*** 2025-07-28 22:00:10.283
*** SESSION ID:(233.30485) 2025-07-28 22:00:10.283
 

*** 2025-07-28 22:00:10.283
Process diagnostic dump for ORACLE.EXE (J003), OS id=18072,
pid: 29, proc_ser: 143, sid: 233, sess_ser: 30485 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18072
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=12 seq_num=13 snap_id=1
    wait times: snap=0.382946 sec, exc=0.382946 sec, total=0.382946 sec
    wait times: max=0.500000 sec, heur=6.381948 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.000011 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=11 seq_num=12 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
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=10 seq_num=11 snap_id=1
    wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=0.499976 sec, exc=0.499976 sec, total=0.499976 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000016 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=0.500011 sec, exc=0.500011 sec, total=0.500011 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=0.500114 sec, exc=0.500114 sec, total=0.500114 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000014 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000015 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 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
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=0.499984 sec, exc=0.499984 sec, total=0.499984 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000017 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 snap_id=1
    wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
Sampled Session History of session 233 serial 30485
---------------------------------------------------
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
---------------------------------------------------
  [7 samples,                                              22:00:03 - 22:00:10]
    idle wait at each sample
  [session created at: 22:00:03]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-28 22:00:10.283
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 29 (osid: 18072, J003)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-28 00:00:47.005700 :B7DF4DDF:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-28 00:00:47.005700 :B7DF4DE0:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-28 00:00:47.005700 :B7DF4DE1:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-28 00:00:47.005701 :B7DF4DE2:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-28 00:00:47.005701 :B7DF4DE3:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-28 00:00:47.006161 :B7DF4DE4: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-07-28 00:01:17.079781 :B7DF5A6B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 00:01:47.089210 :B7DF63E7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 00:01:47.077
2025-07-28 00:01:47.089213 :B7DF63E8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=1020 pso_num=29 pso_serial#=134
2025-07-28 00:01:47.089328 :B7DF63E9:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=1020 osp_idx=27 osp_ver=15178 osp_pg=0 (spawn #429444)
2025-07-28 00:01:47.089329 :B7DF63EA:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-28 00:01:47.089329 :B7DF63EB:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=67 time=2341682970
2025-07-28 00:01:47.089330 :B7DF63EC:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-28 00:01:47.089330 :B7DF63ED:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-28 00:01:47.089330 :B7DF63EE:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-28 00:01:47.089331 :B7DF63EF:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-28 00:01:47.089331 :B7DF63F0:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-28 00:01:47.089809 :B7DF63F1: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-07-28 00:02:12.104337 :B7DF6C27:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-28 00:02:42.199277 :B7DF75EE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 00:02:47.152502 :B7DF778A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 00:02:47.141
2025-07-28 00:02:47.152504 :B7DF778B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15440 pso_num=29 pso_serial#=135
2025-07-28 00:02:47.152626 :B7DF778C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15440 osp_idx=27 osp_ver=15179 osp_pg=0 (spawn #429446)
2025-07-28 00:02:47.152627 :B7DF778D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-28 00:02:47.152628 :B7DF778E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=68 time=2341743033
2025-07-28 00:02:47.152628 :B7DF778F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-28 00:02:47.152628 :B7DF7790:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-28 00:02:47.152629 :B7DF7791:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-28 00:02:47.152629 :B7DF7792:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-28 00:02:47.152630 :B7DF7793:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-28 00:02:47.153102 :B7DF7794: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-07-28 00:03:17.247148 :B7DF812B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 00:03:47.216246 :B7DF8B31:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 00:03:47.204
2025-07-28 00:03:47.216248 :B7DF8B32:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19412 pso_num=29 pso_serial#=136
2025-07-28 00:03:47.216373 :B7DF8B33:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19412 osp_idx=27 osp_ver=15180 osp_pg=0 (spawn #429449)
2025-07-28 00:03:47.216374 :B7DF8B34:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-28 00:03:47.216374 :B7DF8B35:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=69 time=2341803111
2025-07-28 00:03:47.216375 :B7DF8B36:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-28 00:03:47.216376 :B7DF8B37:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-28 00:03:47.216376 :B7DF8B38:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-28 00:03:47.216376 :B7DF8B39:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-28 00:03:47.216377 :B7DF8B3A:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-28 00:03:47.216832 :B7DF8B3B: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-07-28 00:04:17.283584 :B7DF9579:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 00:04:47.319081 :B7DF9F48:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 00:04:47.308
2025-07-28 00:04:47.319084 :B7DF9F49:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2768 pso_num=29 pso_serial#=137
2025-07-28 00:04:47.319214 :B7DF9F4A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=2768 osp_idx=27 osp_ver=15181 osp_pg=0 (spawn #429451)
2025-07-28 00:04:47.319215 :B7DF9F4B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-28 00:04:47.319216 :B7DF9F4C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=70 time=2341863204
2025-07-28 00:04:47.319216 :B7DF9F4D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-28 00:04:47.319217 :B7DF9F4E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-28 00:04:47.319217 :B7DF9F4F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-28 00:04:47.319217 :B7DF9F50:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-28 00:04:47.319218 :B7DF9F51:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-28 00:04:47.319701 :B7DF9F52: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-07-28 00:05:17.386530 :B7DFA960:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 00:05:47.397810 :B7DFBBE3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 00:05:47.386
2025-07-28 00:05:47.397812 :B7DFBBE4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4428 pso_num=29 pso_serial#=138
2025-07-28 00:05:47.397935 :B7DFBBE5:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=4428 osp_idx=27 osp_ver=15182 osp_pg=0 (spawn #429454)
2025-07-28 00:05:47.397936 :B7DFBBE6:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-28 00:05:47.397937 :B7DFBBE7:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=71 time=2341923283
2025-07-28 00:05:47.397937 :B7DFBBE8:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-28 00:05:47.397937 :B7DFBBE9:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-28 00:05:47.397938 :B7DFBBEA:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-28 00:05:47.397938 :B7DFBBEB:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-28 00:05:47.397938 :B7DFBBEC:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-28 00:05:47.398438 :B7DFBBED: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-07-28 00:06:17.456307 :B7DFC6CB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 01:00:14.482704 :B7E3F188:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 01:00:14.470
2025-07-28 01:00:14.482706 :B7E3F189:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18776 pso_num=29 pso_serial#=139
2025-07-28 01:00:14.482850 :B7E3F18A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18776 (legacy spawn)
2025-07-28 01:00:14.489370 :B7E3F18B: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-07-28 01:00:14.489556 :B7E3F192: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-07-28 01:00:14.490341 :B7E3F195:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 01:00:14.499126 :B7E3F198:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 01:00:14.485
2025-07-28 01:00:14.499129 :B7E3F199:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10744 pso_num=29 pso_serial#=140
2025-07-28 01:00:14.499307 :B7E3F19A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10744 (legacy spawn)
2025-07-28 01:00:14.505786 :B7E3F19B: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-07-28 01:00:14.505949 :B7E3F1A4: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-07-28 01:00:14.506776 :B7E3F1A5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 01:00:23.368812 :B7E3F465:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 01:00:23.360
2025-07-28 01:00:23.368814 :B7E3F466:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17856 pso_num=29 pso_serial#=141
2025-07-28 01:00:23.368952 :B7E3F467:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17856 (legacy spawn)
2025-07-28 01:00:23.375444 :B7E3F468: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-07-28 01:00:23.375630 :B7E3F46D: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-07-28 01:00:23.376432 :B7E3F472:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 16:00:29.818793 :B82A4B0F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 16:00:29.809
2025-07-28 16:00:29.818795 :B82A4B10:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17340 pso_num=29 pso_serial#=142
2025-07-28 16:00:29.818929 :B82A4B11:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17340 osp_idx=27 osp_ver=15186 osp_pg=0 (spawn #431663)
2025-07-28 16:00:29.818930 :B82A4B12:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-28 16:00:29.818930 :B82A4B13:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=72 time=2399205704
2025-07-28 16:00:29.818931 :B82A4B14:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-28 16:00:29.818931 :B82A4B15:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-28 16:00:29.818932 :B82A4B16:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-28 16:00:29.818933 :B82A4B17:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-28 16:00:29.818933 :B82A4B18:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-28 16:00:29.819434 :B82A4B19: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-07-28 16:00:29.819476 :B82A4B1F: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-07-28 16:00:29.826729 :B82A4B2D: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-07-28 16:00:29.827775 :B82A4B35: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-07-28 16:00:29.828318 :B82A4B3D: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-07-28 16:00:29.828795 :B82A4B45: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-07-28 16:00:29.829289 :B82A4B4D: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-07-28 16:00:29.831739 :B82A4B55: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-07-28 16:00:29.832920 :B82A4B5D: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-07-28 16:00:29.882777 :B82A4B65: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-07-28 16:00:29.883753 :B82A4B6D: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-07-28 16:00:29.884006 :B82A4B75: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-07-28 16:00:29.884221 :B82A4B7D: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-07-28 16:00:29.886060 :B82A4B85:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-07-28 22:00:02.044163 :B84656F3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-28 22:00:02.032
2025-07-28 22:00:02.044164 :B84656F4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18072 pso_num=29 pso_serial#=143
2025-07-28 22:00:02.044326 :B84656F5:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=18072 osp_idx=27 osp_ver=15187 osp_pg=0 (spawn #432492)
2025-07-28 22:00:02.044327 :B84656F6:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-28 22:00:02.044328 :B84656F7:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=73 time=2420777939
2025-07-28 22:00:02.044328 :B84656F8:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-07-28 22:00:02.044329 :B84656F9:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-07-28 22:00:02.044330 :B84656FA:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-07-28 22:00:02.044332 :B84656FB:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-28 22:00:02.044332 :B84656FC:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-28 22:00:02.045025 :B8465706: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-07-28 22:00:02.050388 :B846571B: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-07-28 22:00:02.050440 :B846571E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203
2025-07-28 22:00:02.056713 :B8465753: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-07-28 22:00:02.255292 :B84659D1: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-07-28 22:00:02.443793 :B84659D9: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-07-28 22:00:02.576700 :B8465A01: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-07-28 22:00:03.694768 :B8465C2C: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-07-28 22:00:03.695742 :B8465C34: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-07-28 22:00:03.699556 :B8465C3C: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-07-28 22:00:03.702547 :B8465C44: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-07-28 22:00:03.704034 :B8465C4C: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-07-28 22:00:03.705434 :B8465C60: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-07-28 22:00:03.706788 :B8465C68: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-07-28 22:00:03.726187 :B8465C70: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-07-28 22:00:03.726777 :B8465C78: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-07-28 22:00:03.727290 :B8465C80: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-07-28 22:00:03.727788 :B8465C88: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-07-28 22:00:03.730478 :B8465C90: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-07-28 22:00:03.731367 :B8465C98:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.732133 :B8465C99:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.732909 :B8465C9A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.733362 :B8465C9B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.733368 :B8465C9C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.733922 :B8465C9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.734404 :B8465C9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.734665 :B8465C9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.735742 :B8465CA0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.771157 :B8465CAD: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-07-28 22:00:03.771209 :B8465CB0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.772131 :B8465CB6: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-07-28 22:00:03.773003 :B8465CBE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.773483 :B8465CBF:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 2, nex 3
2025-07-28 22:00:03.773489 :B8465CC0:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:3 rdba:0x00400e01  where: kddwh01: kdddel 
2025-07-28 22:00:03.773926 :B8465CC1: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-07-28 22:00:03.795130 :B8465D00: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-07-28 22:00:03.795184 :B8465D03:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.796038 :B8465D09: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-07-28 22:00:03.796880 :B8465D11:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.797294 :B8465D12: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-07-28 22:00:03.815753 :B8465D1A: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-07-28 22:00:03.815801 :B8465D1D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.816652 :B8465D23: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-07-28 22:00:03.828208 :B8465D2B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.828826 :B8465D2C: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-07-28 22:00:03.847720 :B8465D34: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-07-28 22:00:03.847769 :B8465D37:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.848616 :B8465D3D: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-07-28 22:00:03.849583 :B8465D45:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.850070 :B8465D46: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-07-28 22:00:03.873798 :B8465D4E: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-07-28 22:00:03.873867 :B8465D51:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.874913 :B8465D57: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-07-28 22:00:03.875993 :B8465D5F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-07-28 22:00:03.876491 :B8465D60: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-07-28 22:00:03.899269 :B8465D68: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-07-28 22:00:03.899349 :B8465D6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-07-28 22:00:03.900413 :B8465D71: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-07-28 22:00:03.900972 :B8465D79: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-07-28 22:00:03.901381 :B8465D81: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-07-28 22:00:03.901611 :B8465D89: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-07-28 22:00:03.901752 :B8465D91: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-07-28 22:00:03.901926 :B8465D99:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203
2025-07-28 22:00:03.906128 :B8465D9A: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-07-28 22:00:03.906232 :B8465D9D: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
2025-07-28 22:00:03.906313 :B8465DAA: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
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 29 (osid: 18072, J003)