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


*** 2025-05-12 22:00:05.990
*** SESSION ID:(241.1617) 2025-05-12 22:00:05.990
 

*** 2025-05-12 22:00:05.990
Process diagnostic dump for ORACLE.EXE (J004), OS id=13276,
pid: 30, proc_ser: 21, sid: 241, sess_ser: 1617 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13276
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=0.480707 sec, exc=0.480707 sec, total=0.480707 sec
    wait times: max=0.500000 sec, heur=1.984995 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.000008 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 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.000008 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=1 seq_num=2 snap_id=1
    wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000006 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.504273 sec, exc=0.504273 sec, total=0.504273 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 1617
---------------------------------------------------
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
---------------------------------------------------
  [2 samples,                                              22:00:04 - 22:00:05]
    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-12 22:00:05.990
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 30 (osid: 13276, J004)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-11 23:59:09.689030 :95DC5ECA:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=6556 pso_num=30 pso_serial#=13
2025-05-11 23:59:09.689141 :95DC5ECB:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=6556 osp_idx=28 osp_ver=777 osp_pg=0 (spawn #172752)
2025-05-11 23:59:09.689141 :95DC5ECC:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-11 23:59:09.689141 :95DC5ECD:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=101 time=4278726843
2025-05-11 23:59:09.689142 :95DC5ECE:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-05-11 23:59:09.689142 :95DC5ECF:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-05-11 23:59:09.689143 :95DC5ED0:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-05-11 23:59:09.689143 :95DC5ED1:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-11 23:59:09.689143 :95DC5ED2:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-11 23:59:09.689643 :95DC5ED3: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-05-11 23:59:39.738727 :95DC699C:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-05-11 23:59:51.418433 :95DC6DA8:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-11 23:59:51.413
2025-05-11 23:59:51.418435 :95DC6DA9:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12796 pso_num=30 pso_serial#=14
2025-05-11 23:59:51.418575 :95DC6DAA:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=12796 (legacy spawn)
2025-05-11 23:59:51.432731 :95DC6DAB: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-05-12 00:00:24.446474 :95DC7C74:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-05-12 01:00:25.745264 :95E12132:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 01:00:25.734
2025-05-12 01:00:25.745265 :95E12133:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=10684 pso_num=30 pso_serial#=15
2025-05-12 01:00:25.745406 :95E12134:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=10684 osp_idx=28 osp_ver=779 osp_pg=0 (spawn #172897)
2025-05-12 01:00:25.745407 :95E12135:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-12 01:00:25.745407 :95E12136:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=102 time=4282402906
2025-05-12 01:00:25.745408 :95E12137:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-05-12 01:00:25.745408 :95E12138:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-05-12 01:00:25.745408 :95E12139:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-05-12 01:00:25.745409 :95E1213A:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-12 01:00:25.745409 :95E1213B:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-12 01:00:25.745919 :95E1213C: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-05-12 01:00:25.745957 :95E12142: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-05-12 01:00:25.753935 :95E12154:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-05-12 01:00:27.113031 :95E12234:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 01:00:27.109
2025-05-12 01:00:27.113034 :95E12235:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12400 pso_num=30 pso_serial#=16
2025-05-12 01:00:27.113162 :95E12236:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=12400 osp_idx=28 osp_ver=780 osp_pg=0 (spawn #172898)
2025-05-12 01:00:27.113163 :95E12237:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-12 01:00:27.113163 :95E12238:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=103 time=4282404265
2025-05-12 01:00:27.113164 :95E12239:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-05-12 01:00:27.113164 :95E1223A:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-05-12 01:00:27.113164 :95E1223B:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-05-12 01:00:27.113165 :95E1223C:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-12 01:00:27.113165 :95E1223D:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-12 01:00:27.113657 :95E1223E: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-05-12 01:00:27.113706 :95E12244: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-05-12 01:00:27.121528 :95E12252: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-05-12 01:00:27.122633 :95E1225A: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-05-12 01:00:27.123272 :95E12262: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-05-12 01:00:27.123756 :95E1226A: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-05-12 01:00:27.124197 :95E12272: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-05-12 01:00:27.126909 :95E12286: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-05-12 01:00:27.128132 :95E1228E: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-05-12 01:00:27.178192 :95E12296: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-05-12 01:00:27.179070 :95E1229E: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-05-12 01:00:27.179319 :95E122A6: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-05-12 01:00:27.179535 :95E122AE: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-05-12 01:00:27.181329 :95E122B6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-05-12 06:00:06.126124 :95F84C48:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 06:00:06.114
2025-05-12 06:00:06.126125 :95F84C49:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11324 pso_num=30 pso_serial#=17
2025-05-12 06:00:06.126268 :95F84C4A:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=11324 osp_idx=28 osp_ver=781 osp_pg=0 (spawn #173603)
2025-05-12 06:00:06.126268 :95F84C4B:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-12 06:00:06.126269 :95F84C4C:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=104 time=5415985
2025-05-12 06:00:06.126269 :95F84C4D:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-05-12 06:00:06.126270 :95F84C4E:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-05-12 06:00:06.126270 :95F84C4F:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-05-12 06:00:06.126271 :95F84C50:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-12 06:00:06.126271 :95F84C51:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-12 06:00:06.127636 :95F84C52: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-05-12 06:00:06.127709 :95F84C58: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-05-12 06:00:06.136126 :95F84C6A:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-05-12 07:00:13.686875 :95FCE4D1:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 07:00:13.676
2025-05-12 07:00:13.686876 :95FCE4D2:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11236 pso_num=30 pso_serial#=18
2025-05-12 07:00:13.687012 :95FCE4D3:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=11236 osp_idx=28 osp_ver=782 osp_pg=0 (spawn #173742)
2025-05-12 07:00:13.687013 :95FCE4D4:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-12 07:00:13.687014 :95FCE4D5:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=105 time=9023547
2025-05-12 07:00:13.687014 :95FCE4D6:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-05-12 07:00:13.687015 :95FCE4D7:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-05-12 07:00:13.687016 :95FCE4D8:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-05-12 07:00:13.687016 :95FCE4D9:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-12 07:00:13.687016 :95FCE4DA:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-12 07:00:13.687485 :95FCE4DE: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-05-12 07:00:13.687525 :95FCE4E4: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-05-12 07:00:13.696630 :95FCE4F6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-05-12 08:00:21.684016 :96019378:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 08:00:21.674
2025-05-12 08:00:21.684017 :96019379:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12616 pso_num=30 pso_serial#=19
2025-05-12 08:00:21.684138 :9601937A:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=12616 osp_idx=28 osp_ver=783 osp_pg=0 (spawn #173886)
2025-05-12 08:00:21.684139 :9601937B:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-12 08:00:21.684139 :9601937C:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=106 time=12631547
2025-05-12 08:00:21.684140 :9601937D:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-05-12 08:00:21.684140 :9601937E:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-05-12 08:00:21.684140 :9601937F:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-05-12 08:00:21.684141 :96019380:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-12 08:00:21.684141 :96019381:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-12 08:00:21.684629 :96019382: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-05-12 08:00:21.684670 :96019388: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-05-12 08:00:21.693233 :9601939A:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-05-12 09:00:30.351786 :9606490F:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 09:00:30.340
2025-05-12 09:00:30.351787 :96064910:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=10124 pso_num=30 pso_serial#=20
2025-05-12 09:00:30.351910 :96064911:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=10124 osp_idx=28 osp_ver=784 osp_pg=0 (spawn #174024)
2025-05-12 09:00:30.351911 :96064912:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-12 09:00:30.351911 :96064913:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=107 time=16240219
2025-05-12 09:00:30.351912 :96064914:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-05-12 09:00:30.351912 :96064915:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-05-12 09:00:30.351912 :96064916:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-05-12 09:00:30.351913 :96064917:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-12 09:00:30.351913 :96064918:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-12 09:00:30.352406 :96064919: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-05-12 09:00:30.352462 :9606491F: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-05-12 09:00:30.361746 :96064941:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted
2025-05-12 22:00:02.059817 :96424FD9:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 22:00:02.048
2025-05-12 22:00:02.059819 :96424FDA:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13276 pso_num=30 pso_serial#=21
2025-05-12 22:00:02.059943 :96424FDC:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=13276 osp_idx=28 osp_ver=785 osp_pg=0 (spawn #175824)
2025-05-12 22:00:02.059945 :96424FDD:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-12 22:00:02.059945 :96424FDE:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=108 time=63011922
2025-05-12 22:00:02.059946 :96424FDF:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs
2025-05-12 22:00:02.059947 :96424FE0:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs
2025-05-12 22:00:02.059947 :96424FE1:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs
2025-05-12 22:00:02.059948 :96424FE2:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-12 22:00:02.059948 :96424FE3:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-12 22:00:02.060532 :96424FF1: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-05-12 22:00:02.065519 :9642500E: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-05-12 22:00:02.065558 :96425011:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203
2025-05-12 22:00:02.070934 :9642505C: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-05-12 22:00:02.290699 :964252D4: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-05-12 22:00:02.502387 :96425373: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-05-12 22:00:02.634534 :96425383: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-05-12 22:00:03.756757 :964255A2: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-05-12 22:00:03.757776 :964255AA: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-05-12 22:00:03.760421 :964255B2: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-05-12 22:00:03.762917 :964255BA: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-05-12 22:00:03.766931 :964255C3: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-05-12 22:00:03.768496 :964255CB: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-05-12 22:00:03.770895 :964255D3: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-05-12 22:00:03.774760 :964255DB: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-05-12 22:00:03.793282 :964255E3: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-05-12 22:00:03.794013 :964255EB: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-05-12 22:00:03.794622 :964255F3: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-05-12 22:00:03.795147 :964255FB: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-05-12 22:00:03.797722 :96425603: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-05-12 22:00:03.798741 :9642560B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.799546 :9642560C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.800320 :9642560D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.800738 :9642560E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.800745 :9642560F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.801337 :96425610:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.801843 :96425611:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.802157 :96425612:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.803395 :96425613:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.843271 :96425655: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-05-12 22:00:03.843320 :96425658:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.844320 :9642565E: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-05-12 22:00:03.845253 :96425666:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.845699 :96425667: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-05-12 22:00:03.865959 :9642566F: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-05-12 22:00:03.866012 :96425672:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.866984 :96425678: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-05-12 22:00:03.867868 :96425680:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.868295 :96425681: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-05-12 22:00:03.886620 :96425689: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-05-12 22:00:03.886672 :9642568C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.887590 :96425692: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-05-12 22:00:03.899379 :9642569A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.899901 :9642569B: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-05-12 22:00:03.920537 :964256A3: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-05-12 22:00:03.920590 :964256A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.921510 :964256AC: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-05-12 22:00:03.922322 :964256B4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.922748 :964256B5: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-05-12 22:00:03.944272 :964256BD: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-05-12 22:00:03.944322 :964256C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.945224 :964256C6: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-05-12 22:00:03.946092 :964256CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.946532 :964256CF: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-05-12 22:00:03.967001 :964256D7: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-05-12 22:00:03.967055 :964256DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:03.967959 :964256E0: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-05-12 22:00:03.979813 :964256F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235
2025-05-12 22:00:03.980343 :964256F1: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-05-12 22:00:04.004446 :96425706: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-05-12 22:00:04.004495 :96425709:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235
2025-05-12 22:00:04.005421 :9642570F: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-05-12 22:00:04.005861 :96425717: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-05-12 22:00:04.006235 :9642571F: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-05-12 22:00:04.006444 :96425727: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-05-12 22:00:04.006583 :9642572F: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-05-12 22:00:04.006735 :96425737:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203
2025-05-12 22:00:04.007322 :96425738: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-05-12 22:00:04.011033 :96425740: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-05-12 22:00:04.011125 :96425743: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
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 30 (osid: 13276, J004)